builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-044 starttime: 1494973222.0 results: success (0) revision: c100ae56e585d7ad9176b4bead105b5c02843407 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 15:20:21.996680) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 15:20:21.997087) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 15:20:21.997352) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 15:20:22.081186) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 15:20:22.081482) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-16 15:20:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.6M=0s 2017-05-16 15:20:22 (45.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.372694 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 15:20:22.475001) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 15:20:22.475316) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014847 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 15:20:22.524299) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 15:20:22.524661) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c100ae56e585d7ad9176b4bead105b5c02843407 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c100ae56e585d7ad9176b4bead105b5c02843407 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-16 15:20:22,871 truncating revision to first 12 chars 2017-05-16 15:20:22,871 Setting DEBUG logging. 2017-05-16 15:20:22,871 attempt 1/10 2017-05-16 15:20:22,871 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c100ae56e585?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-16 15:20:23,394 unpacking tar archive at: mozilla-beta-c100ae56e585/testing/mozharness/ program finished with exit code 0 elapsedTime=0.829656 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 15:20:23.366988) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 15:20:23.367290) ========= script_repo_revision: c100ae56e585d7ad9176b4bead105b5c02843407 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 15:20:23.367697) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 15:20:23.367962) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 15:20:23.376430) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-05-16 15:20:23.376817) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:20:23 INFO - MultiFileLogger online at 20170516 15:20:23 in /builds/slave/test 15:20:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 15:20:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:20:23 INFO - {'append_to_log': False, 15:20:23 INFO - 'base_work_dir': '/builds/slave/test', 15:20:23 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 15:20:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:20:23 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 15:20:23 INFO - 'buildbot_json_path': 'buildprops.json', 15:20:23 INFO - 'config_files': ('talos/linux_config.py',), 15:20:23 INFO - 'default_actions': ('clobber', 15:20:23 INFO - 'read-buildbot-config', 15:20:23 INFO - 'download-and-extract', 15:20:23 INFO - 'populate-webroot', 15:20:23 INFO - 'create-virtualenv', 15:20:23 INFO - 'install', 15:20:23 INFO - 'run-tests'), 15:20:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:20:23 INFO - 'download_minidump_stackwalk': True, 15:20:23 INFO - 'download_symbols': 'ondemand', 15:20:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:20:23 INFO - 'tooltool.py': '/tools/tooltool.py', 15:20:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:20:23 INFO - '/tools/misc-python/virtualenv.py')}, 15:20:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:20:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:20:23 INFO - 'gecko_profile': False, 15:20:23 INFO - 'gecko_profile_interval': 0, 15:20:23 INFO - 'installer_path': 'installer.exe', 15:20:23 INFO - 'log_level': 'info', 15:20:23 INFO - 'log_name': 'talos', 15:20:23 INFO - 'log_to_console': True, 15:20:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:20:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:20:23 INFO - 'opt_config_files': (), 15:20:23 INFO - 'pip_index': False, 15:20:23 INFO - 'suite': 'dromaeojs', 15:20:23 INFO - 'system_bits': '32', 15:20:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:20:23 INFO - 'title': 'talos-linux64-ix-044', 15:20:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:20:23 INFO - 'use_talos_json': True, 15:20:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:20:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:20:23 INFO - 'work_dir': 'build'} 15:20:23 INFO - [mozharness: 2017-05-16 22:20:23.824612Z] Running clobber step. 15:20:23 INFO - Running pre-action listener: _resource_record_pre_action 15:20:23 INFO - Running main action method: clobber 15:20:23 INFO - rmtree: /builds/slave/test/build 15:20:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:20:24 INFO - Running post-action listener: _resource_record_post_action 15:20:24 INFO - [mozharness: 2017-05-16 22:20:24.829770Z] Finished clobber step (success) 15:20:24 INFO - [mozharness: 2017-05-16 22:20:24.829870Z] Running read-buildbot-config step. 15:20:24 INFO - Running pre-action listener: _resource_record_pre_action 15:20:24 INFO - Running main action method: read_buildbot_config 15:20:24 INFO - Using buildbot properties: 15:20:24 INFO - { 15:20:24 INFO - "project": "", 15:20:24 INFO - "product": "firefox", 15:20:24 INFO - "who": "ryanvm@gmail.com", 15:20:24 INFO - "installer_path": "public/build/target.tar.bz2", 15:20:24 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 15:20:24 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 15:20:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 15:20:24 INFO - "stage_platform": "linux64", 15:20:24 INFO - "basedir": "/builds/slave/test", 15:20:24 INFO - "buildnumber": 48, 15:20:24 INFO - "platform": "ubuntu64_hw", 15:20:24 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:20:24 INFO - "slavebuilddir": "test", 15:20:24 INFO - "taskId": "DBfFSQ2uQdW-vBK31_87pw", 15:20:24 INFO - "branch": "mozilla-beta", 15:20:24 INFO - "script_repo_revision": "production", 15:20:24 INFO - "revision": "c100ae56e585d7ad9176b4bead105b5c02843407", 15:20:24 INFO - "slavename": "talos-linux64-ix-044", 15:20:24 INFO - "repo_path": "releases/mozilla-beta" 15:20:24 INFO - } 15:20:24 INFO - Finding installer, test and symbols from parent task. 15:20:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DBfFSQ2uQdW-vBK31_87pw'}, attempt #1 15:20:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DBfFSQ2uQdW-vBK31_87pw'}, attempt #1 15:20:25 INFO - Task dependencies: Ov489HNHSpqRPCA2Uek3ig 15:20:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig'}, attempt #1 15:20:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.tar.bz2 15:20:26 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/test_packages.json 15:20:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.crashreporter-symbols.zip 15:20:26 INFO - Running post-action listener: _resource_record_post_action 15:20:26 INFO - [mozharness: 2017-05-16 22:20:26.162850Z] Finished read-buildbot-config step (success) 15:20:26 INFO - [mozharness: 2017-05-16 22:20:26.163068Z] Running download-and-extract step. 15:20:26 INFO - Running pre-action listener: _resource_record_pre_action 15:20:26 INFO - Running main action method: download_and_extract 15:20:26 INFO - mkdir: /builds/slave/test/build/tests 15:20:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:26 INFO - trying https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.test_packages.json 15:20:26 INFO - Downloading https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:20:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:20:26 INFO - Downloaded 986 bytes. 15:20:26 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:20:26 INFO - Using the following test package requirements: 15:20:26 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:20:26 INFO - u'common': [u'target.common.tests.zip'], 15:20:26 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:20:26 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:20:26 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:20:26 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:20:26 INFO - u'mozbase': [u'target.common.tests.zip'], 15:20:26 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:20:26 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:20:26 INFO - u'web-platform': [u'target.common.tests.zip', 15:20:26 INFO - u'target.web-platform.tests.zip'], 15:20:26 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:20:26 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:20:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.common.tests.zip 15:20:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:20:26 INFO - Fetch https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.common.tests.zip into memory 15:20:29 INFO - Expected file size: 59560097 15:20:29 INFO - Obtained file size: 59560097 15:20:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:20:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.common.tests.zip 15:20:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:20:39 INFO - Fetch https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.common.tests.zip into memory 15:20:41 INFO - Expected file size: 59560097 15:20:41 INFO - Obtained file size: 59560097 15:20:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.talos.tests.zip 15:20:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:20:52 INFO - Fetch https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.talos.tests.zip into memory 15:20:53 INFO - Expected file size: 14404449 15:20:53 INFO - Obtained file size: 14404449 15:20:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:53 INFO - trying https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.tar.bz2 15:20:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:20:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:20:57 INFO - Downloaded 59703772 bytes. 15:20:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.tar.bz2 15:20:57 INFO - mkdir: /builds/slave/test/properties 15:20:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:20:57 INFO - Writing to file /builds/slave/test/properties/build_url 15:20:57 INFO - Contents: 15:20:57 INFO - build_url:https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.tar.bz2 15:20:57 INFO - Running post-action listener: _resource_record_post_action 15:20:57 INFO - Running post-action listener: set_extra_try_arguments 15:20:57 INFO - [mozharness: 2017-05-16 22:20:57.145269Z] Finished download-and-extract step (success) 15:20:57 INFO - [mozharness: 2017-05-16 22:20:57.145537Z] Running populate-webroot step. 15:20:57 INFO - Running pre-action listener: _resource_record_pre_action 15:20:57 INFO - Running main action method: populate_webroot 15:20:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:20:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:20:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:20:57 INFO - '--tppagecycles', 15:20:57 INFO - '7'], 15:20:57 INFO - 'tests': ['tsvgm']}}, 15:20:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:20:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:20:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:20:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:20:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:20:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:20:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:20:57 INFO - 'talos_options': ['--disable-e10s'], 15:20:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:20:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:20:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:20:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:20:57 INFO - 'talos_options': ['--disable-e10s'], 15:20:57 INFO - 'tests': ['damp', 'tps']}, 15:20:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:20:57 INFO - 'tests': ['damp', 'tps']}, 15:20:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:20:57 INFO - 'tests': ['dromaeo_dom']}, 15:20:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:20:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:20:57 INFO - 'tests': ['basic_compositor_video']}, 15:20:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:20:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:20:57 INFO - 'tests': ['a11yr', 15:20:57 INFO - 'ts_paint', 15:20:57 INFO - 'tpaint', 15:20:57 INFO - 'sessionrestore', 15:20:57 INFO - 'sessionrestore_no_auto_restore', 15:20:57 INFO - 'tabpaint']}, 15:20:57 INFO - 'other-e10s': {'tests': ['a11yr', 15:20:57 INFO - 'ts_paint', 15:20:57 INFO - 'tpaint', 15:20:57 INFO - 'sessionrestore', 15:20:57 INFO - 'sessionrestore_no_auto_restore', 15:20:57 INFO - 'tabpaint']}, 15:20:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:20:57 INFO - 'ts_paint', 15:20:57 INFO - 'tpaint', 15:20:57 INFO - 'sessionrestore', 15:20:57 INFO - 'sessionrestore_no_auto_restore', 15:20:57 INFO - 'tabpaint']}, 15:20:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:20:57 INFO - 'ts_paint', 15:20:57 INFO - 'tpaint', 15:20:57 INFO - 'sessionrestore', 15:20:57 INFO - 'sessionrestore_no_auto_restore', 15:20:57 INFO - 'tabpaint']}, 15:20:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:20:57 INFO - 'tests': ['a11yr', 15:20:57 INFO - 'ts_paint', 15:20:57 INFO - 'tpaint', 15:20:57 INFO - 'sessionrestore', 15:20:57 INFO - 'sessionrestore_no_auto_restore', 15:20:57 INFO - 'tabpaint']}, 15:20:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:20:57 INFO - 'tests': ['a11yr', 15:20:57 INFO - 'ts_paint', 15:20:57 INFO - 'tpaint', 15:20:57 INFO - 'sessionrestore', 15:20:57 INFO - 'sessionrestore_no_auto_restore', 15:20:57 INFO - 'tabpaint']}, 15:20:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:20:57 INFO - 'tests': ['tsvgx', 15:20:57 INFO - 'tsvgr_opacity', 15:20:57 INFO - 'tart', 15:20:57 INFO - 'tscrollx', 15:20:57 INFO - 'cart', 15:20:57 INFO - 'tsvg_static']}, 15:20:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:20:57 INFO - 'tsvgr_opacity', 15:20:57 INFO - 'tart', 15:20:57 INFO - 'tscrollx', 15:20:57 INFO - 'cart', 15:20:57 INFO - 'tsvg_static']}, 15:20:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:20:57 INFO - 'talos_options': ['--disable-e10s'], 15:20:57 INFO - 'tests': ['tp5o']}, 15:20:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:20:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:20:57 INFO - 'talos_options': ['--disable-e10s', 15:20:57 INFO - '--xperf_path', 15:20:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:20:57 INFO - 'tests': ['tp5n']}, 15:20:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:20:57 INFO - 'talos_options': ['--xperf_path', 15:20:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:20:57 INFO - 'tests': ['tp5n']}}, 15:20:57 INFO - 'talos.zip': {'path': '', 15:20:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:20:57 INFO - Running post-action listener: _resource_record_post_action 15:20:57 INFO - [mozharness: 2017-05-16 22:20:57.158685Z] Finished populate-webroot step (success) 15:20:57 INFO - [mozharness: 2017-05-16 22:20:57.158775Z] Running create-virtualenv step. 15:20:57 INFO - Running pre-action listener: _resource_record_pre_action 15:20:57 INFO - Running main action method: create_virtualenv 15:20:57 INFO - Creating virtualenv /builds/slave/test/build/venv 15:20:57 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:20:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:20:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:20:57 INFO - Using real prefix '/usr' 15:20:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:20:57 INFO - Installing distribute.............................................................................................................................................................................................done. 15:20:59 INFO - Installing pip.................done. 15:20:59 INFO - Return code: 0 15:20:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:20:59 INFO - Reading from file tmpfile_stdout 15:20:59 INFO - Output received: 15:20:59 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:20:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1842a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9f400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:20:59 INFO - Using env: {'DISPLAY': ':0', 15:20:59 INFO - 'HOME': '/home/cltbld', 15:20:59 INFO - 'LANG': 'en_US.UTF-8', 15:20:59 INFO - 'LANGUAGE': 'en_US:en', 15:20:59 INFO - 'LOGNAME': 'cltbld', 15:20:59 INFO - 'MAIL': '/var/mail/cltbld', 15:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:59 INFO - 'MOZ_NO_REMOTE': '1', 15:20:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:59 INFO - 'NO_EM_RESTART': '1', 15:20:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:59 INFO - 'PWD': '/builds/slave/test', 15:20:59 INFO - 'SHELL': '/bin/bash', 15:20:59 INFO - 'SHLVL': '1', 15:20:59 INFO - 'TERM': 'linux', 15:20:59 INFO - 'TMOUT': '86400', 15:20:59 INFO - 'USER': 'cltbld', 15:20:59 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 15:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:59 INFO - '_': '/tools/buildbot/bin/python'} 15:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:21:00 INFO - Cleaning up... 15:21:00 INFO - Return code: 0 15:21:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:21:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1842a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9f400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:21:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:21:00 INFO - Using env: {'DISPLAY': ':0', 15:21:00 INFO - 'HOME': '/home/cltbld', 15:21:00 INFO - 'LANG': 'en_US.UTF-8', 15:21:00 INFO - 'LANGUAGE': 'en_US:en', 15:21:00 INFO - 'LOGNAME': 'cltbld', 15:21:00 INFO - 'MAIL': '/var/mail/cltbld', 15:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:21:00 INFO - 'MOZ_NO_REMOTE': '1', 15:21:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:21:00 INFO - 'NO_EM_RESTART': '1', 15:21:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:21:00 INFO - 'PWD': '/builds/slave/test', 15:21:00 INFO - 'SHELL': '/bin/bash', 15:21:00 INFO - 'SHLVL': '1', 15:21:00 INFO - 'TERM': 'linux', 15:21:00 INFO - 'TMOUT': '86400', 15:21:00 INFO - 'USER': 'cltbld', 15:21:00 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 15:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:21:00 INFO - '_': '/tools/buildbot/bin/python'} 15:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:21:00 INFO - Downloading/unpacking psutil>=3.1.1 15:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:21:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:21:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:21:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:21:01 INFO - Installing collected packages: psutil 15:21:01 INFO - Running setup.py install for psutil 15:21:01 INFO - building 'psutil._psutil_linux' extension 15:21:01 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:21:01 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:21:01 INFO - building 'psutil._psutil_posix' extension 15:21:01 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:21:01 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:21:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:21:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:21:02 INFO - Successfully installed psutil 15:21:02 INFO - Cleaning up... 15:21:02 INFO - Return code: 0 15:21:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:21:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:21:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:21:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1842a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9f400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:21:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:21:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:21:02 INFO - Using env: {'DISPLAY': ':0', 15:21:02 INFO - 'HOME': '/home/cltbld', 15:21:02 INFO - 'LANG': 'en_US.UTF-8', 15:21:02 INFO - 'LANGUAGE': 'en_US:en', 15:21:02 INFO - 'LOGNAME': 'cltbld', 15:21:02 INFO - 'MAIL': '/var/mail/cltbld', 15:21:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:21:02 INFO - 'MOZ_NO_REMOTE': '1', 15:21:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:21:02 INFO - 'NO_EM_RESTART': '1', 15:21:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:21:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:21:02 INFO - 'PWD': '/builds/slave/test', 15:21:02 INFO - 'SHELL': '/bin/bash', 15:21:02 INFO - 'SHLVL': '1', 15:21:02 INFO - 'TERM': 'linux', 15:21:02 INFO - 'TMOUT': '86400', 15:21:02 INFO - 'USER': 'cltbld', 15:21:02 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 15:21:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:21:02 INFO - '_': '/tools/buildbot/bin/python'} 15:21:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:21:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:21:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:21:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:21:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:21:03 INFO - Installing collected packages: mozsystemmonitor 15:21:03 INFO - Running setup.py install for mozsystemmonitor 15:21:03 INFO - Successfully installed mozsystemmonitor 15:21:03 INFO - Cleaning up... 15:21:03 INFO - Return code: 0 15:21:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:21:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1842a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9f400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:21:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:21:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:21:03 INFO - Using env: {'DISPLAY': ':0', 15:21:03 INFO - 'HOME': '/home/cltbld', 15:21:03 INFO - 'LANG': 'en_US.UTF-8', 15:21:03 INFO - 'LANGUAGE': 'en_US:en', 15:21:03 INFO - 'LOGNAME': 'cltbld', 15:21:03 INFO - 'MAIL': '/var/mail/cltbld', 15:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:21:03 INFO - 'MOZ_NO_REMOTE': '1', 15:21:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:21:03 INFO - 'NO_EM_RESTART': '1', 15:21:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:21:03 INFO - 'PWD': '/builds/slave/test', 15:21:03 INFO - 'SHELL': '/bin/bash', 15:21:03 INFO - 'SHLVL': '1', 15:21:03 INFO - 'TERM': 'linux', 15:21:03 INFO - 'TMOUT': '86400', 15:21:03 INFO - 'USER': 'cltbld', 15:21:03 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 15:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:21:03 INFO - '_': '/tools/buildbot/bin/python'} 15:21:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:21:03 INFO - Downloading/unpacking jsonschema==2.5.1 15:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:21:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:21:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:21:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:21:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:21:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:21:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:21:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:21:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:21:05 INFO - Downloading functools32-3.2.3-2.tar.gz 15:21:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:21:05 INFO - warning: no files found matching '*.txt' 15:21:05 INFO - no previously-included directories found matching 'build' 15:21:05 INFO - no previously-included directories found matching 'dist' 15:21:05 INFO - no previously-included directories found matching '.git*' 15:21:05 INFO - Installing collected packages: jsonschema, functools32 15:21:05 INFO - Running setup.py install for functools32 15:21:05 INFO - warning: no files found matching '*.txt' 15:21:05 INFO - no previously-included directories found matching 'build' 15:21:05 INFO - no previously-included directories found matching 'dist' 15:21:05 INFO - no previously-included directories found matching '.git*' 15:21:05 INFO - Successfully installed jsonschema functools32 15:21:05 INFO - Cleaning up... 15:21:05 INFO - Return code: 0 15:21:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:21:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1842a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9f400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:21:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:21:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:21:05 INFO - Using env: {'DISPLAY': ':0', 15:21:05 INFO - 'HOME': '/home/cltbld', 15:21:05 INFO - 'LANG': 'en_US.UTF-8', 15:21:05 INFO - 'LANGUAGE': 'en_US:en', 15:21:05 INFO - 'LOGNAME': 'cltbld', 15:21:05 INFO - 'MAIL': '/var/mail/cltbld', 15:21:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:21:05 INFO - 'MOZ_NO_REMOTE': '1', 15:21:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:21:05 INFO - 'NO_EM_RESTART': '1', 15:21:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:21:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:21:05 INFO - 'PWD': '/builds/slave/test', 15:21:05 INFO - 'SHELL': '/bin/bash', 15:21:05 INFO - 'SHLVL': '1', 15:21:05 INFO - 'TERM': 'linux', 15:21:05 INFO - 'TMOUT': '86400', 15:21:05 INFO - 'USER': 'cltbld', 15:21:05 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 15:21:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:21:05 INFO - '_': '/tools/buildbot/bin/python'} 15:21:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:21:05 INFO - Cleaning up... 15:21:05 INFO - Return code: 0 15:21:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:21:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1842a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9f400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:21:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:21:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:21:05 INFO - Using env: {'DISPLAY': ':0', 15:21:05 INFO - 'HOME': '/home/cltbld', 15:21:05 INFO - 'LANG': 'en_US.UTF-8', 15:21:05 INFO - 'LANGUAGE': 'en_US:en', 15:21:05 INFO - 'LOGNAME': 'cltbld', 15:21:05 INFO - 'MAIL': '/var/mail/cltbld', 15:21:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:21:05 INFO - 'MOZ_NO_REMOTE': '1', 15:21:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:21:05 INFO - 'NO_EM_RESTART': '1', 15:21:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:21:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:21:05 INFO - 'PWD': '/builds/slave/test', 15:21:05 INFO - 'SHELL': '/bin/bash', 15:21:05 INFO - 'SHLVL': '1', 15:21:05 INFO - 'TERM': 'linux', 15:21:05 INFO - 'TMOUT': '86400', 15:21:05 INFO - 'USER': 'cltbld', 15:21:05 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 15:21:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:21:05 INFO - '_': '/tools/buildbot/bin/python'} 15:21:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:21:05 INFO - Downloading/unpacking blobuploader==1.2.4 15:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:21:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:21:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:21:06 INFO - Downloading blobuploader-1.2.4.tar.gz 15:21:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:21:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:21:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:21:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:21:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:21:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:21:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:21:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:21:07 INFO - Downloading docopt-0.6.1.tar.gz 15:21:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:21:07 INFO - Installing collected packages: blobuploader, requests, docopt 15:21:07 INFO - Running setup.py install for blobuploader 15:21:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:21:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:21:07 INFO - Running setup.py install for requests 15:21:07 INFO - Running setup.py install for docopt 15:21:07 INFO - Successfully installed blobuploader requests docopt 15:21:07 INFO - Cleaning up... 15:21:07 INFO - Return code: 0 15:21:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:21:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1842a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9f400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:21:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:21:08 INFO - Using env: {'DISPLAY': ':0', 15:21:08 INFO - 'HOME': '/home/cltbld', 15:21:08 INFO - 'LANG': 'en_US.UTF-8', 15:21:08 INFO - 'LANGUAGE': 'en_US:en', 15:21:08 INFO - 'LOGNAME': 'cltbld', 15:21:08 INFO - 'MAIL': '/var/mail/cltbld', 15:21:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:21:08 INFO - 'MOZ_NO_REMOTE': '1', 15:21:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:21:08 INFO - 'NO_EM_RESTART': '1', 15:21:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:21:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:21:08 INFO - 'PWD': '/builds/slave/test', 15:21:08 INFO - 'SHELL': '/bin/bash', 15:21:08 INFO - 'SHLVL': '1', 15:21:08 INFO - 'TERM': 'linux', 15:21:08 INFO - 'TMOUT': '86400', 15:21:08 INFO - 'USER': 'cltbld', 15:21:08 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 15:21:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:21:08 INFO - '_': '/tools/buildbot/bin/python'} 15:21:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:21:08 INFO - Running setup.py (path:/tmp/pip-0w3GCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:21:08 INFO - Running setup.py (path:/tmp/pip-4Coa_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:21:08 INFO - Running setup.py (path:/tmp/pip-gXPSEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:21:08 INFO - Running setup.py (path:/tmp/pip-4AU71M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:21:08 INFO - Running setup.py (path:/tmp/pip-eGJaYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:21:08 INFO - Running setup.py (path:/tmp/pip-z0bK6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:21:08 INFO - Running setup.py (path:/tmp/pip-lX8rzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:21:08 INFO - Running setup.py (path:/tmp/pip-q0o_xS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:21:08 INFO - Running setup.py (path:/tmp/pip-mzcdBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:21:08 INFO - Running setup.py (path:/tmp/pip-opoTCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:21:08 INFO - Running setup.py (path:/tmp/pip-zFgjUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:21:08 INFO - Running setup.py (path:/tmp/pip-dOxnco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:21:09 INFO - Running setup.py (path:/tmp/pip-PF7Phu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:21:09 INFO - Running setup.py (path:/tmp/pip-9pWS68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:21:09 INFO - Running setup.py (path:/tmp/pip-N5MPTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:21:09 INFO - Running setup.py (path:/tmp/pip-_OPZCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:21:09 INFO - Running setup.py (path:/tmp/pip-8VnsEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:21:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:21:09 INFO - Running setup.py install for manifestparser 15:21:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:21:09 INFO - Running setup.py install for mozcrash 15:21:09 INFO - Running setup.py install for mozdebug 15:21:09 INFO - Running setup.py install for mozdevice 15:21:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:21:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:21:09 INFO - Running setup.py install for mozfile 15:21:09 INFO - Running setup.py install for mozhttpd 15:21:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:21:10 INFO - Running setup.py install for mozinfo 15:21:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:21:10 INFO - Running setup.py install for mozInstall 15:21:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:21:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:21:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:21:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:21:10 INFO - Running setup.py install for mozleak 15:21:10 INFO - Running setup.py install for mozlog 15:21:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:21:10 INFO - Running setup.py install for moznetwork 15:21:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:21:10 INFO - Running setup.py install for mozprocess 15:21:10 INFO - Running setup.py install for mozprofile 15:21:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:21:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:21:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:21:10 INFO - Running setup.py install for mozrunner 15:21:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:21:10 INFO - Running setup.py install for mozscreenshot 15:21:10 INFO - Running setup.py install for moztest 15:21:11 INFO - Running setup.py install for mozversion 15:21:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:21:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:21:11 INFO - Cleaning up... 15:21:11 INFO - Return code: 0 15:21:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:21:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:21:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:21:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1842a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9f400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:21:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:21:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:21:11 INFO - Using env: {'DISPLAY': ':0', 15:21:11 INFO - 'HOME': '/home/cltbld', 15:21:11 INFO - 'LANG': 'en_US.UTF-8', 15:21:11 INFO - 'LANGUAGE': 'en_US:en', 15:21:11 INFO - 'LOGNAME': 'cltbld', 15:21:11 INFO - 'MAIL': '/var/mail/cltbld', 15:21:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:21:11 INFO - 'MOZ_NO_REMOTE': '1', 15:21:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:21:11 INFO - 'NO_EM_RESTART': '1', 15:21:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:21:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:21:11 INFO - 'PWD': '/builds/slave/test', 15:21:11 INFO - 'SHELL': '/bin/bash', 15:21:11 INFO - 'SHLVL': '1', 15:21:11 INFO - 'TERM': 'linux', 15:21:11 INFO - 'TMOUT': '86400', 15:21:11 INFO - 'USER': 'cltbld', 15:21:11 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 15:21:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:21:11 INFO - '_': '/tools/buildbot/bin/python'} 15:21:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:21:11 INFO - Running setup.py (path:/tmp/pip-S09f0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:21:11 INFO - Running setup.py (path:/tmp/pip-3hr_FT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:21:11 INFO - Running setup.py (path:/tmp/pip-FIKZoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:21:11 INFO - Running setup.py (path:/tmp/pip-1KIo77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:21:11 INFO - Running setup.py (path:/tmp/pip-a_VZEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:21:11 INFO - Running setup.py (path:/tmp/pip-tDm_AO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:21:11 INFO - Running setup.py (path:/tmp/pip-rZ99By-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:21:11 INFO - Running setup.py (path:/tmp/pip-x1nP6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:21:12 INFO - Running setup.py (path:/tmp/pip-I7yJns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:21:12 INFO - Running setup.py (path:/tmp/pip-Tm7Z_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:21:12 INFO - Running setup.py (path:/tmp/pip-Oqg38J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:21:12 INFO - Running setup.py (path:/tmp/pip-6LrMDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:21:12 INFO - Running setup.py (path:/tmp/pip-7n6vAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:21:12 INFO - Running setup.py (path:/tmp/pip-TTM_2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:21:12 INFO - Running setup.py (path:/tmp/pip-7Ez97R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:21:12 INFO - Running setup.py (path:/tmp/pip-0EfyNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:21:12 INFO - Running setup.py (path:/tmp/pip-su8Puu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:21:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:21:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:21:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:21:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:21:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:21:13 INFO - Downloading blessings-1.6.tar.gz 15:21:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:21:13 INFO - Installing collected packages: blessings 15:21:13 INFO - Running setup.py install for blessings 15:21:13 INFO - Successfully installed blessings 15:21:13 INFO - Cleaning up... 15:21:14 INFO - Return code: 0 15:21:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:21:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:21:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:21:14 INFO - Reading from file tmpfile_stdout 15:21:14 INFO - Current package versions: 15:21:14 INFO - argparse == 1.2.1 15:21:14 INFO - blessings == 1.6 15:21:14 INFO - blobuploader == 1.2.4 15:21:14 INFO - docopt == 0.6.1 15:21:14 INFO - functools32 == 3.2.3-2 15:21:14 INFO - jsonschema == 2.5.1 15:21:14 INFO - manifestparser == 1.1 15:21:14 INFO - mozInstall == 1.12 15:21:14 INFO - mozcrash == 1.0 15:21:14 INFO - mozdebug == 0.1 15:21:14 INFO - mozdevice == 0.49 15:21:14 INFO - mozfile == 1.2 15:21:14 INFO - mozhttpd == 0.7 15:21:14 INFO - mozinfo == 0.9 15:21:14 INFO - mozleak == 0.1 15:21:14 INFO - mozlog == 3.4 15:21:14 INFO - moznetwork == 0.27 15:21:14 INFO - mozprocess == 0.25 15:21:14 INFO - mozprofile == 0.28 15:21:14 INFO - mozrunner == 6.13 15:21:14 INFO - mozscreenshot == 0.1 15:21:14 INFO - mozsystemmonitor == 0.3 15:21:14 INFO - moztest == 0.8 15:21:14 INFO - mozversion == 1.4 15:21:14 INFO - psutil == 3.1.1 15:21:14 INFO - requests == 1.2.3 15:21:14 INFO - wsgiref == 0.1.2 15:21:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:21:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:21:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:21:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1842a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9f400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:21:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:21:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:21:14 INFO - Using env: {'DISPLAY': ':0', 15:21:14 INFO - 'HOME': '/home/cltbld', 15:21:14 INFO - 'LANG': 'en_US.UTF-8', 15:21:14 INFO - 'LANGUAGE': 'en_US:en', 15:21:14 INFO - 'LOGNAME': 'cltbld', 15:21:14 INFO - 'MAIL': '/var/mail/cltbld', 15:21:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:21:14 INFO - 'MOZ_NO_REMOTE': '1', 15:21:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:21:14 INFO - 'NO_EM_RESTART': '1', 15:21:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:21:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:21:14 INFO - 'PWD': '/builds/slave/test', 15:21:14 INFO - 'SHELL': '/bin/bash', 15:21:14 INFO - 'SHLVL': '1', 15:21:14 INFO - 'TERM': 'linux', 15:21:14 INFO - 'TMOUT': '86400', 15:21:14 INFO - 'USER': 'cltbld', 15:21:14 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 15:21:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:21:14 INFO - '_': '/tools/buildbot/bin/python'} 15:21:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:21:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:21:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:21:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:21:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:21:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:21:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:21:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:21:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:21:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:21:14 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:21:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:21:14 INFO - Cleaning up... 15:21:14 INFO - Return code: 0 15:21:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:21:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:21:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:21:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1842a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9f400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:21:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:21:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:21:14 INFO - Using env: {'DISPLAY': ':0', 15:21:14 INFO - 'HOME': '/home/cltbld', 15:21:14 INFO - 'LANG': 'en_US.UTF-8', 15:21:14 INFO - 'LANGUAGE': 'en_US:en', 15:21:14 INFO - 'LOGNAME': 'cltbld', 15:21:14 INFO - 'MAIL': '/var/mail/cltbld', 15:21:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:21:14 INFO - 'MOZ_NO_REMOTE': '1', 15:21:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:21:14 INFO - 'NO_EM_RESTART': '1', 15:21:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:21:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:21:14 INFO - 'PWD': '/builds/slave/test', 15:21:14 INFO - 'SHELL': '/bin/bash', 15:21:14 INFO - 'SHLVL': '1', 15:21:14 INFO - 'TERM': 'linux', 15:21:14 INFO - 'TMOUT': '86400', 15:21:14 INFO - 'USER': 'cltbld', 15:21:14 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 15:21:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:21:14 INFO - '_': '/tools/buildbot/bin/python'} 15:21:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:21:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:21:14 INFO - Cleaning up... 15:21:14 INFO - Return code: 0 15:21:14 INFO - Running post-action listener: _resource_record_post_action 15:21:14 INFO - Running post-action listener: _start_resource_monitoring 15:21:14 INFO - Starting resource monitoring. 15:21:14 INFO - [mozharness: 2017-05-16 22:21:14.717496Z] Finished create-virtualenv step (success) 15:21:14 INFO - [mozharness: 2017-05-16 22:21:14.717981Z] Running install step. 15:21:14 INFO - Running pre-action listener: _resource_record_pre_action 15:21:14 INFO - Running main action method: install 15:21:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:21:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:21:14 INFO - Reading from file tmpfile_stdout 15:21:14 INFO - Detecting whether we're running mozinstall >=1.0... 15:21:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:21:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:21:15 INFO - Reading from file tmpfile_stdout 15:21:15 INFO - Output received: 15:21:15 INFO - Usage: mozinstall [options] installer 15:21:15 INFO - Options: 15:21:15 INFO - -h, --help show this help message and exit 15:21:15 INFO - -d DEST, --destination=DEST 15:21:15 INFO - Directory to install application into. [default: 15:21:15 INFO - "/builds/slave/test"] 15:21:15 INFO - --app=APP Application being installed. [default: firefox] 15:21:15 INFO - mkdir: /builds/slave/test/build/application 15:21:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:21:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:21:29 INFO - Reading from file tmpfile_stdout 15:21:29 INFO - Output received: 15:21:29 INFO - /builds/slave/test/build/application/firefox/firefox 15:21:29 INFO - Running post-action listener: _resource_record_post_action 15:21:29 INFO - [mozharness: 2017-05-16 22:21:29.049630Z] Finished install step (success) 15:21:29 INFO - [mozharness: 2017-05-16 22:21:29.049880Z] Running run-tests step. 15:21:29 INFO - Running pre-action listener: _resource_record_pre_action 15:21:29 INFO - Running main action method: run_tests 15:21:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:21:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:21:29 INFO - Python 2.7.3 15:21:29 INFO - Return code: 0 15:21:29 INFO - grabbing minidump binary from tooltool 15:21:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:29 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9f400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aa03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:21:29 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:21:29 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:21:29 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:21:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:21:29 INFO - Return code: 0 15:21:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:21:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:21:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:21:29 INFO - ENV: RUST_BACKTRACE is now 1 15:21:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:21:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:21:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:21:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-044 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:21:29 INFO - Using env: {'DISPLAY': ':0', 15:21:29 INFO - 'HOME': '/home/cltbld', 15:21:29 INFO - 'LANG': 'en_US.UTF-8', 15:21:29 INFO - 'LANGUAGE': 'en_US:en', 15:21:29 INFO - 'LOGNAME': 'cltbld', 15:21:29 INFO - 'MAIL': '/var/mail/cltbld', 15:21:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:21:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:21:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:21:29 INFO - 'MOZ_NO_REMOTE': '1', 15:21:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:21:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:21:29 INFO - 'NO_EM_RESTART': '1', 15:21:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:21:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:21:29 INFO - 'PWD': '/builds/slave/test', 15:21:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:21:29 INFO - 'RUST_BACKTRACE': '1', 15:21:29 INFO - 'SHELL': '/bin/bash', 15:21:29 INFO - 'SHLVL': '1', 15:21:29 INFO - 'TERM': 'linux', 15:21:29 INFO - 'TMOUT': '86400', 15:21:29 INFO - 'USER': 'cltbld', 15:21:29 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283', 15:21:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:21:29 INFO - '_': '/tools/buildbot/bin/python'} 15:21:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:21:29 INFO - mozversion application_buildid: 20170516211020 15:21:29 INFO - mozversion application_changeset: c100ae56e585d7ad9176b4bead105b5c02843407 15:21:29 INFO - mozversion application_display_name: Firefox 15:21:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:21:29 INFO - mozversion application_name: Firefox 15:21:29 INFO - mozversion application_remotingname: firefox 15:21:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:21:29 INFO - mozversion application_vendor: Mozilla 15:21:29 INFO - mozversion application_version: 54.0 15:21:29 INFO - mozversion platform_buildid: 20170516211020 15:21:29 INFO - mozversion platform_changeset: c100ae56e585d7ad9176b4bead105b5c02843407 15:21:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:21:29 INFO - mozversion platform_version: 54.0 15:21:29 INFO - using testdate: 1494973289 15:21:29 INFO - actual date: 1494973289 15:21:29 INFO - starting webserver on 'localhost:60065' 15:21:29 INFO - SUITE-START | Running 2 tests 15:21:29 INFO - TEST-START | dromaeo_css 15:21:29 INFO - operating with platform_type : linux_ 15:21:29 INFO - Initialising browser for dromaeo_css test... 15:21:29 INFO - TEST-INFO | started process 28748 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzvIpsU/profile http://localhost:60065/getInfo.html) 15:21:33 INFO - PROCESS | 28748 | __metrics Screen width/height:1600/1200 15:21:33 INFO - PROCESS | 28748 | colorDepth:24 15:21:33 INFO - PROCESS | 28748 | Browser inner width/height: 1024/697 15:21:33 INFO - PROCESS | 28748 | __metrics 15:21:33 INFO - PROCESS | 28748 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:21:33 INFO - PROCESS | 28748 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:21:34 INFO - PROCESS | 28748 | console.error: PushService: 15:21:34 INFO - PROCESS | 28748 | stateChangeProcessEnqueue: Error transitioning state 15:21:34 INFO - PROCESS | 28748 | UnknownError 15:21:34 INFO - TEST-INFO | 28748: exit 0 15:21:34 INFO - Browser initialized. 15:21:34 INFO - Running cycle 1/1 for dromaeo_css test... 15:21:34 INFO - TEST-INFO | started process 28842 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzvIpsU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:21:46 INFO - PROCESS | 28842 | RSS: Main: 141000704 15:21:46 INFO - PROCESS | 28842 | 15:24:47 INFO - PROCESS | 28842 | Cycle 1(1): loaded http://localhost:60065/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60065/tests/dromaeo/cssquery-ext.html) 15:24:49 INFO - PROCESS | 28842 | RSS: Main: 285724672 15:24:49 INFO - PROCESS | 28842 | 15:27:51 INFO - PROCESS | 28842 | Cycle 1(1): loaded http://localhost:60065/tests/dromaeo/cssquery-ext.html (next: http://localhost:60065/tests/dromaeo/cssquery-jquery.html) 15:27:52 INFO - PROCESS | 28842 | RSS: Main: 221835264 15:27:52 INFO - PROCESS | 28842 | 15:30:54 INFO - PROCESS | 28842 | Cycle 1(1): loaded http://localhost:60065/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60065/tests/dromaeo/cssquery-mootools.html) 15:30:54 INFO - PROCESS | 28842 | RSS: Main: 196059136 15:30:54 INFO - PROCESS | 28842 | 15:33:56 INFO - PROCESS | 28842 | Cycle 1(1): loaded http://localhost:60065/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60065/tests/dromaeo/cssquery-prototype.html) 15:33:57 INFO - PROCESS | 28842 | RSS: Main: 200495104 15:33:57 INFO - PROCESS | 28842 | 15:37:00 INFO - PROCESS | 28842 | Cycle 1(1): loaded http://localhost:60065/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60065/tests/dromaeo/cssquery-yui.html) 15:37:00 INFO - PROCESS | 28842 | RSS: Main: 174301184 15:37:00 INFO - PROCESS | 28842 | 15:40:03 INFO - PROCESS | 28842 | Cycle 1(1): loaded http://localhost:60065/tests/dromaeo/cssquery-yui.html (next: http://localhost:60065/tests/dromaeo/cssquery-dojo.html) 15:40:03 INFO - PROCESS | 28842 | RSS: Main: 266555392 15:40:03 INFO - PROCESS | 28842 | 15:40:03 INFO - PROCESS | 28842 | __start_tp_report 15:40:03 INFO - PROCESS | 28842 | _x_x_mozilla_page_load 15:40:03 INFO - PROCESS | 28842 | _x_x_mozilla_page_load_details 15:40:03 INFO - PROCESS | 28842 | |i|pagename|runs| 15:40:03 INFO - PROCESS | 28842 | |0;dojo.html;3334.4498157803964;3345.2138747394356;3378.206121561434;3378.2230087079956;3422.503736958141;5730.942690573093;5817.418258174181;5825.067989121736;5835.854103647405;5844.736986835592;5764.25064741583;5818.0400233961345;5822.330432000321;5823.941760582393;5825.417458254183;5245.973770131144;5268.236105764662;5320.361556613212;5326.174442961347;5363.570914326844;5171;5194.2208668699805;5375.274337964363;5383.219433182187;5385.623006389555;5262.315898933152;5271;5285.735713214343;5295.682259064469;5304.257403963448;5365.248865158881;5369.060414427459;5370.43610420904;5373.301470808769;5380.865478363033;5118.539331460149;5226.7909283628605;5274.973625131888;5338.332708411449;5341.359036915595;5656.264685590888;5668.291463567054;5713.771449142029;5779.306483221998;5790.218320526717;6170.228721409824;6203.348648391899;6212.974859148235;6233.283172435183;6239.65681887496;6223.782167624111;6243.063540468921;6266.3420340864;6273.87452250957;6283.465905398047;5640.633358831663;5671.347795003545;5731.570132240098;5776.768929242868;5785.855353616109;6340.968295158495;6347.904781428282;6350.841228969312;6354.364563543609;6354.904676429857;6017.368176341509;6119.357467465941;6133.202683651097;6139.815805525842;6143.815685529441;6007.279126504847;6031.60794548353;6101.908471372933;6188.9690551546955;6201.534884883652;6170.938290617126;6180.351063138396;6183.938160618426;6184.443400094013;6185.907211391833;5970.820875373745;5998.040313549811;6002.279726432856;6090.908636370458;6191.783287584913;4673.579377856009;4713.6936099153445;4730.88172795676;4731.787069581877;4741.857744267678;4150.190712810973;4150.418941348214;4159.729617574909;4215.78921053946;4235.322348424237;5947.405259474104;5993;5995.46040856325;6018.3680713524445;6030.6080104793045;6195.907061394083;6222.142451165185;6245.375462453809;6245.875082498415;6264.2796078451565;5803.593748437569;5843.795467158714;5885.735141918624;5978.432048955342;6014.879702405928;5273.604479664041;5302.09864323066;5320.680759154463;5322.733863306819;5325.653832500875;6450.193725784277;6491.188601424822;6493.480521558264;6497.15536980199;6499.3175716549085;6068.848278793065;6183.783567575113;6253.5622506424115;6267.561270711007;6269.12232287471;6337.6197428154455;6361.300256971637;6374.298827129105;6376.3623637635865;6404.199475065617;6125.356837531995;6127.938720612737;6128.32588415265;6135.754569817157;6138.109974053827;6185.319614842454;6253.624782513064;6254.21822272216;6256.7810126645345;6259.593126446766;5882.735276912635;5930.28836539618;6020.458158765732;6062.151298818081;6136.20229370179;6013.188219590299;6082.087686847009;6086.239220097488;6093.268807743099;6112.602680825732;5867.73595188209;6072.362401947732;6076.210092687922;6081.5134789217645;6088.8782224356155;5947.553933454921;6009.128676341993;6063.393660634078;6084.33072362031;6121.724522396414;5979.073243647242;5996.580239383201;5998.4601385875485;6001.7299221536005;6001.849953751192 15:40:03 INFO - PROCESS | 28842 | |1;ext.html;758.6244808819636;839.5634270179505;850.0054935725203;920.4339331311241;927.0358826820108;31681;45300.54699453004;45955.54044459554;46041.30938035932;46060.309095363555;46961;46995.76502117485;47230.76384618081;47258;47276.763616181954;7731.845363092735;9311.394759340656;9439.480828554426;9448.196903263231;9452.952735236315;7811.726589569366;10703.1437485001;10984.890151098507;10989.395583242918;10992.17558683098;11154.498047587838;11155.553777848876;11157.38634375109;11180.776384472305;11191.440427978569;11054.557817687282;11057.170712196577;11085.445727713583;11091.667249982513;11097.16771242156;10883.78232435347;10898;10918.617848375268;10946.671599852018;10949.178811589163;43281.567184328065;43789.34315985263;43858;43914.78042609766;43941.340879886826;4322.092360604245;4356.520782713899;4385.758783266919;4488.977555112237;4498.842540511099;30515.694843051508;30640.2339941501;30758.53862192069;31225;32745.508817367758;4161.854335098257;4199.9790001050105;4203.495580530325;4241.257779888538;4274.978625106885;251119;251836;251935;253060;253466.73266633734;57381.139282910786;57424;57796;57828.71085644545;57851.132233016535;57418.13872791911;58475.41524584785;58686.41313586895;58734.70632646809;58765.41234587685;16854;17298.913505432392;17306.048167350666;17309.30762769475;17311.220995055253;12686.682832929251;13153.53962611304;15417.768733469007;15437.459688910834;15444.613884652748;13757.587272381845;14133.434662613587;14174.14955102697;14764.114153150631;14891.851081489263;5468.644538105011;5504.697241651747;5521.4478552144465;5592.356878958891;5612.326520817528;1008.626808081;1010.2120346130031;1018.6638409324905;1019.1490105761771;1022.6574097677361;28944.855275723487;29335.706642933725;29430.852845735633;29716.2570935724;30013;1093.0435868614961;1093.786711591248;1106.1427393769893;1114.4093630375914;1115.6931843743037;16100.838991610168;16668.249928753234;17073.48779536616;17122.14389280531;17630.118494075246;312945;315241;315698;316136;317099;52550.73724631429;52613;52663.473365265854;52696.20955685668;52756.47243527592;1501.5045035138378;1505.52575938579;1509.222750283605;1528.9464868729763;1539.692061587687;28306;29267.853660731562;29305.120846374644;29660;29896.103116906528;25200;25283.620745688462;25338.493230135664;25374.365640859127;25385;277841;281014;281684;282254;312850;27194.864025679744;27424.862875685492;29999.550006750353;30963.225919352197;31146.68853311438;40144;41395;41450;41465.58534414617;41659.7917010413;31525.684743153193;31619.36761264716;31657;31825.6817431832;31920.5211921826;31014.53478197874;31725.239616613235;32117;32308.676913230567;32519.349613008082;35245.29509409746;36025.0993725159;36781.08047298839;39672.2065558693;40294.19411611693;30797;31020.379592408477;31035.379292414476;31277.530837037917;31727.365452690356;25021.374465638506;25796;25799.226023219333;25925.6111158329;26113.456621275607 15:40:03 INFO - PROCESS | 28842 | |2;jquery.html;458.08840407588906;467.3877220840699;479.75532478435986;484.93695819543456;497.788439913037;20299.797002029976;20897.686534701992;22093.33719988403;22272.331830045103;22460.88769556154;23119.768802311974;23251.069957201693;23348.299551013497;23639.645405318934;23824.736650018265;2117.12139462123;2118.3433135727896;2119.0993827623274;2119.5760847830497;2383.974890796961;6535.34646535345;6883.724651013953;6914.101166848328;6917.792466225996;6917.89623155653;6690.230623478314;6917.13535808024;6929.341712537301;6932.549384290005;6932.72269109235;6919.481038922101;6959.373656370951;6959.547629404073;6960.582365058112;6964;6447.2263328400895;6653.733850645968;6688.665566721645;6696.631685257309;6701.463882889358;25560.233193004053;25620.103296384725;25701.485970280675;25718.357041073934;25834.87082564594;3063.5251536011733;3140.2149462634343;3149.8425078745972;3232.515122731609;3245.1238165695368;16193.352265909349;16984.575385615335;17002.574935626584;17124.828751712448;17574.560635984075;2282.429392651837;2361.2207971369407;2404.2666986569266;2405.374602603318;2558.270892795544;120428;125039;127951.36024319819;128087;128208.35895820461;21741.347759567238;22162.33512994613;22444.55110897773;22651.43371415728;23108.65337019946;44217.33673994893;44440.77779611146;44761.55238447639;45078;45368.3194752079;12308.50765969369;12313.876861231272;12397.93801030989;12419.503219871103;12422.571404288441;10274.178065754722;10314.793704125876;10350.844737328945;10411.010953959361;10545.10366618846;11282.717932051764;11658.834116588443;11687.474063667156;11760.176787624956;11834.408279585987;3865.342891708416;3910.7066969977363;4359.885455554054;4387;4497.190505708938;966.3139171188535;967.308374512213;972.9075737804898;974.468914441631;981.7790997025755;19831.40505784829;20954.37136885896;21177.470563235733;21572.12083102512;22023.66964495534;973.7030205787303;974.1962880623514;974.5517062151348;992.1368409483796;998.3810037776625;11272.492737826817;11835;11899.345535995604;11946.820797688042;11961.820572691417;614058;644707;646627;654913;656984.0055598031;64737.676311619085;79861;88683.11316886877;114670.42664786623;115901.420492897;1248.918820276679;1266.341502418737;1293.618382577142;1304.0545604436707;1311.5934060441102;18650.533736656693;19055.80944190596;19060.714089288394;19126;19173.137208825916;37017.81491092527;44673;48417;50076.74961625168;51908.74045629748;67281.66359168173;67946;68401.65799170973;69372.65313673399;69849.65075174591;11751.941240293743;12156.331401772633;12234.510619575118;12384.504619815467;12568.486302739671;13724.862751372359;13763.174209547058;14349.426022958965;14374.928125359726;14383.280835958369;11395.202335836411;11407.258528195973;11653.6503904883;12158.088143389105;12598.685032873886;10983.450827458435;11014.44927753625;11040.668779936615;11807.822882656596;11821.468033938665;11691.532338706696;11823.82264266054;12122.151449398458;12165.513379464723;12835;11949.342786146497;12051;12074.879251207376;12250;12445;11893.524259029888;11900.880991189977;12019.75960480803;12222.75554488923;12521 15:40:03 INFO - PROCESS | 28842 | |3;mootools.html;1548.3496931288857;1580.4231455518739;1596.8323326050765;1599.976000359994;1614.2171047042182;10673.252872298932;11293.20947533672;11299.548018079267;11317.207795454322;11485.540578376855;11324.32054076756;11517.596884109058;11528.077753779698;11529.481173347198;11535.250208736446;2487.4900645367748;2523.671922650053;2528.88620012099;2531.4177739120014;2534.0623969131357;2385.809135269183;2471.851688898672;2530.8354956927833;2535.429528356114;2539.517491676585;2419.2258477287273;2447.963280550793;2454.7913427358694;2463.3964678653756;2463.741307162749;2382.7498112698263;2401.6997875265592;2406.193925035115;2425.3451568076607;2434;2394.2817154853474;2396.424858034077;2396.508715713275;2407.855528668268;2415.3478560788662;11789.823152652718;11829.526818927232;11845.23006004607;11890;11900.166988310822;5634.4365563443735;5784;5792.68140252286;5792.82621521355;5797.565182611279;3445.896623101286;3510.1751088494184;3516.7186625069935;3554.591222009476;3581;1284.9614511564669;1310.803379493074;1339.2567125245484;1394.2262044565261;1409.0770545292746;14612.561623151429;14621.195834229113;14985.100893946397;17578.82421175785;17766.55583610407;3965.5836137205756;4487.708298960557;4517.774111294422;4518.254488009507;4526.705764125321;12724.872751272369;12737.55418560346;12752.872471275354;12764.297963611905;12766.553170639168;5685.573581981368;5687.772489100473;5693.857653558694;5695.231143795618;5698.059820129715;5362.463753624606;5558.249636299129;5583.525400340935;5590.944090559043;5595.608307418523;3968.5949767587454;4043;4082.2447847148374;4092.877213683594;4119.299719047769;1033.1734612310272;1035.8549803027581;1041.1878734587035;1045.1012129568564;1047.7904419116055;926.8841394825647;930.223263574909;936.6300311376968;947.1570302430704;948.5162567090833;6986.126734158231;7082.468814838805;7153.928460715326;7218.60297683622;7346.375558077623;1053.8419237114495;1073.388168743824;1084.7396624810146;1090.6346373964652;1108.024938054507;4287.2711639021445;4291.699581029298;4322.978385108055;4353.695241333076;4375.693701440869;14502.85497145015;15068;15108.39566417353;15111.697766044617;15115;14213.147211167363;14710.779338309934;14741.41034358635;14746;15265.23673816327;9183.494907780003;9376.390534615228;10394.376337419779;10583.364998100138;10671.999999999845;6955;7017.122859642544;7082.822929426599;7093.829518129451;7134.714611415485;13252.86747132555;13309.267990260629;13356.065075444625;13589.728205435638;13710.314484275947;13439.529616463376;13475.460981560627;13534.59396218115;13542.59372218835;13690.178589284675;9633.855492167764;9872.605095796087;9884.901150988686;9885.060919212663;9890.604375825174;10692.305000174963;10795.838062429228;10827.296225745302;10963.725906852074;10986.890131098906;9003.504807235666;9033;9139.086091390807;9519.095685909828;9631;9472.95263523678;9592.376495527767;9635.084666956627;9637.084476974676;9694.46680432583;8750.299976002032;8776.605052772513;8870.645174193218;8872.86690699627;8875.955620221857;6235.469985051322;6327.778527751507;6355.713992870425;6397.104405383343;6478.611283323015;6208.037754148114;6218.160548325918;6282;6285.748570057147;6315.684215789284 15:40:03 INFO - PROCESS | 28842 | |4;prototype.html;400.9662308196338;402.20965802198674;404.50852214559313;412.93186624207;420.4072258116057;5105.5149760772665;5247.86880327992;5254.684718916858;5343.438938911417;5391.326084239475;5124.9743751281285;5220.321358223426;5240.240165176047;5247.763850626721;5296.417394086648;4624.2370008948565;4624.398828152335;4630.166570017395;4633;4642.721436713791;4268.996785755345;4594.862154135381;4599.8390056348035;4646.744429056401;4650.883727906796;4541.750203738793;4577.496475387705;4608.5621865922685;4610.608098311631;4614.653900957424;4553.180427523044;4565.3836732041245;4567.794449249792;4569.611583015448;4581.702189357715;4279.721818081845;4418.3593378960195;4422.800973956179;4425.402570652953;4434.800433980478;5099.13314736492;5123.333966584358;5154.20109882965;5160.716160611165;5198.532132108128;5323.619501616463;5340.198970154469;5348.705821179834;5350.197470379435;5357.249985002103;5388.946110538923;5545.77816887324;5549.833504994816;5601.971990140023;5607.7476513556985;4833.824778849496;4841.782119804618;4858.020528957812;4866.221404575286;4914.262860570907;5810.941890581083;5823.941760582382;5839.270091238595;5880.177514792923;5886.9705651471895;4903.583195428358;5036.169032109734;5038.370203724535;5038.496150384932;5057.6459647824295;5125.666831655931;5135.768890399941;5210.843674689765;5213.478652134756;5241.895162096736;5222.190560463135;5316.298784388959;5337.599680024014;5359.732013399314;5425.430329815392;5540.722963851791;5547.833564993057;5553.861153471114;5608.242887210256;5622.353429355595;1680.126334306154;1719.5185348102302;1735.4273089880562;1768.0805980889866;1778.7865456145346;1374.7662897307482;1422.4096999745198;1440.7910852926268;1450.789635502846;1463.7365274250524;5444.863878403072;5444.91832622511;5462.535684466785;5464.23500709901;5496.945030549643;5291.814786482455;5724.570657200727;5730.9024047806015;5743.712814359349;5747.3103227612955;5418.810341638024;5440.863978400572;5477.589180811455;5484.025750729734;5495.093309603951;2992.6408830940427;2993.431248062861;3007.992322571963;3009.653889802657;3020.622422197225;5734;5869.618474799125;5885.911711324334;5889.734961926723;5906.911396329058;5759.568032397587;5780.75114790637;5816.673330667682;5863.736131874076;5875.529957603381;5573.944260557342;5900.822975310747;5943.910841337383;5946.078357854453;6022.638641681426;5621.494065534122;5627.738064909323;5644.604877658525;5652.830415087554;5684.602077854511;5563.972180139073;5596.328440586993;5630.971845140748;5685.374608792947;5704.572157088152;5674.687892165971;5823.7586172445135;5830.358660547252;5865.973454645335;5866.6186697864505;5025.723585202702;5052.9494705052475;5060.974695126501;5061.670991385548;5067.569256613229;5491.203775452617;5592.245046918777;5622.453825198103;5748;5782.5663075268685;5402.459754024566;5449.182622606482;5461.426550212171;5461.863453413696;5498;5471;5507.7246137692155;5527.700990267364;5546.639468434539;5555.500004999408;5325.840224793263;5402.486763757437;5501.559875210054;5505.889882202414;5544.140658197985;5489.615726899238;5556.138798486241;5557.44425557441;5592.517982734497;5596.768710883599;5206.255904272111;5402.918956215739;5438.857839853675;5458.072127738374;5460.208269800777 15:40:03 INFO - PROCESS | 28842 | |5;yui.html;863.4128792421151;879.2306731609841;919.4115765909819;920.6327112931607;931.3759780946765;17701.380451684192;19199.232030718755;19230.615387692254;19238.615227695438;19588.314408995688;19530.31643892464;19816.20735170595;19989.800101998975;20018.399448016546;20034.1986320547;884.0496466298722;900.6172376739892;908.9318301127408;921.8386782313069;924.6347692661428;909.3180114913814;915.2860768600502;916.5096673279796;917.3028498341275;917.3716004536881;980.7842274699555;981.1415011864618;981.8478135888948;982.5431174503855;983.188869182927;976.5361453309692;984.2863923655365;984.7193549838262;984.9507524623776;985;719.8524302517972;719.9244079371696;719.931606497382;720.4020662849822;720.8702433561957;16237.594060148473;16343.591410214722;16344.427945021867;16961.067141307223;16969.23638436273;16530.58673533159;16708.916455417766;16863.241154148094;17043.318267269417;17528.211230494657;13405.597832064956;13649.11280766747;13774.586762397144;13833.79249311261;13857.4457021719;4016.2168377166463;4101.610347017028;4101.938470922939;4111.9177616447505;4211.073563815956;88773;88885.11114888833;89145.99999999935;90137.54931225367;91259;13250.403731831899;13401.463941442427;13434.731305373838;13439.596812095653;13542.45830166802;24012.27963161108;24240.7575924242;24281.514369712506;24347.878260608937;24689.753102468745;9525.237980961507;9597.136257736836;9837.163841073445;9864;9904.405735655884;4965.1807451770055;5129.512696293846;5164.457731938092;5245.842624721265;5264.973675131677;3874.786886721201;3967.7024223183375;3968.0873399118364;3985.4221137934837;3987.6211759882763;673.5891106425076;679.7281087565013;682.2154522299296;689.917209934811;694.0248950224942;851.0935853316198;852.7527017165077;861.5606040919176;885.6634478897978;901.1394118616705;11298.60454884075;11902.226355286879;11966.940165299291;12030.4586293617;12140.028797696163;861.3367706865804;865.4936861935838;866.5277423804042;870.8301881133245;874.9825003499894;5594.580406469449;5663.32040155184;5742.856428589319;5765.625234359753;5798.536117110621;81058.59470702727;81082.59458702669;82590;84177.15822841816;87930;56851.14723279154;59832.70083649554;70883.2911670887;71199.64400177966;71565.6421717888;65677.40258181977;66048;66308;67629;68952.3104768956;10822.080123189302;10881.478706132135;11180.608678696206;11198.16013798952;11271.267367621078;42832.357514636686;43147;43598.34602480902;43682.34476482792;43688.34467482927;51322.23016654678;52191.47808522018;52264;52267.47732522626;52337.47662523478;12803.35983200855;12896.935515322364;12928.418221179882;13082.411291491715;13674.247916364695;14801.777973330623;15142.318595663442;15354.155521446428;16366;17034.48896533106;16168.191590420196;16565.834341656428;17311.13444327804;17338.13309334503;17472.475825725247;15767.68464630678;16680.332786688396;16701.832981670028;16703.498895033168;16822;15499.361481165151;15824.21296434373;16008.919955400614;16366;16539.849819523384;11996.400179990791;12411.441485133371;12599.18105323151;12674.302913339854;12938.353082346033;13373.732525349633;13488.831666566828;13510.729785404434;13551.932240339129;13655.385507651981 15:40:03 INFO - PROCESS | 28842 | __end_tp_report 15:40:03 INFO - PROCESS | 28842 | __start_cc_report 15:40:03 INFO - PROCESS | 28842 | _x_x_mozilla_cycle_collect,1426 15:40:03 INFO - PROCESS | 28842 | __end_cc_report 15:40:03 INFO - PROCESS | 28842 | __startTimestamp1494974403322__endTimestamp 15:40:03 INFO - PROCESS | 28842 | 15:40:03 INFO - PROCESS | 28842 | ------- Summary: start ------- 15:40:03 INFO - PROCESS | 28842 | Number of tests: 6 15:40:03 INFO - PROCESS | 28842 | 15:40:03 INFO - PROCESS | 28842 | [#0] dojo.html Cycles:165 Average:5762.86 Median:6001.85 stddev:654.96 (10.9%) stddev-sans-first:628.66 15:40:03 INFO - PROCESS | 28842 | Values: 3334.4 3345.2 3378.2 3378.2 3422.5 5730.9 5817.4 5825.1 5835.9 5844.7 5764.3 5818.0 5822.3 5823.9 5825.4 5246.0 5268.2 5320.4 5326.2 5363.6 5171.0 5194.2 5375.3 5383.2 5385.6 5262.3 5271.0 5285.7 5295.7 5304.3 5365.2 5369.1 5370.4 5373.3 5380.9 5118.5 5226.8 5275.0 5338.3 5341.4 5656.3 5668.3 5713.8 5779.3 5790.2 6170.2 6203.3 6213.0 6233.3 6239.7 6223.8 6243.1 6266.3 6273.9 6283.5 5640.6 5671.3 5731.6 5776.8 5785.9 6341.0 6347.9 6350.8 6354.4 6354.9 6017.4 6119.4 6133.2 6139.8 6143.8 6007.3 6031.6 6101.9 6189.0 6201.5 6170.9 6180.4 6183.9 6184.4 6185.9 5970.8 5998.0 6002.3 6090.9 6191.8 4673.6 4713.7 4730.9 4731.8 4741.9 4150.2 4150.4 4159.7 4215.8 4235.3 5947.4 5993.0 5995.5 6018.4 6030.6 6195.9 6222.1 6245.4 6245.9 6264.3 5803.6 5843.8 5885.7 5978.4 6014.9 5273.6 5302.1 5320.7 5322.7 5325.7 6450.2 6491.2 6493.5 6497.2 6499.3 6068.8 6183.8 6253.6 6267.6 6269.1 6337.6 6361.3 6374.3 6376.4 6404.2 6125.4 6127.9 6128.3 6135.8 6138.1 6185.3 6253.6 6254.2 6256.8 6259.6 5882.7 5930.3 6020.5 6062.2 6136.2 6013.2 6082.1 6086.2 6093.3 6112.6 5867.7 6072.4 6076.2 6081.5 6088.9 5947.6 6009.1 6063.4 6084.3 6121.7 5979.1 5996.6 5998.5 6001.7 6001.8 15:40:03 INFO - PROCESS | 28842 | 15:40:03 INFO - PROCESS | 28842 | [#1] ext.html Cycles:180 Average:45432.53 Median:30988.88 stddev:74665.87 (240.9%) stddev-sans-first:74799.98 15:40:03 INFO - PROCESS | 28842 | Values: 758.6 839.6 850.0 920.4 927.0 31681.0 45300.5 45955.5 46041.3 46060.3 46961.0 46995.8 47230.8 47258.0 47276.8 7731.8 9311.4 9439.5 9448.2 9453.0 7811.7 10703.1 10984.9 10989.4 10992.2 11154.5 11155.6 11157.4 11180.8 11191.4 11054.6 11057.2 11085.4 11091.7 11097.2 10883.8 10898.0 10918.6 10946.7 10949.2 43281.6 43789.3 43858.0 43914.8 43941.3 4322.1 4356.5 4385.8 4489.0 4498.8 30515.7 30640.2 30758.5 31225.0 32745.5 4161.9 4200.0 4203.5 4241.3 4275.0 251119.0 251836.0 251935.0 253060.0 253466.7 57381.1 57424.0 57796.0 57828.7 57851.1 57418.1 58475.4 58686.4 58734.7 58765.4 16854.0 17298.9 17306.0 17309.3 17311.2 12686.7 13153.5 15417.8 15437.5 15444.6 13757.6 14133.4 14174.1 14764.1 14891.9 5468.6 5504.7 5521.4 5592.4 5612.3 1008.6 1010.2 1018.7 1019.1 1022.7 28944.9 29335.7 29430.9 29716.3 30013.0 1093.0 1093.8 1106.1 1114.4 1115.7 16100.8 16668.2 17073.5 17122.1 17630.1 312945.0 315241.0 315698.0 316136.0 317099.0 52550.7 52613.0 52663.5 52696.2 52756.5 1501.5 1505.5 1509.2 1528.9 1539.7 28306.0 29267.9 29305.1 29660.0 29896.1 25200.0 25283.6 25338.5 25374.4 25385.0 277841.0 281014.0 281684.0 282254.0 312850.0 27194.9 27424.9 29999.6 30963.2 31146.7 40144.0 41395.0 41450.0 41465.6 41659.8 31525.7 31619.4 31657.0 31825.7 31920.5 31014.5 31725.2 32117.0 32308.7 32519.3 35245.3 36025.1 36781.1 39672.2 40294.2 30797.0 31020.4 31035.4 31277.5 31727.4 25021.4 25796.0 25799.2 25925.6 26113.5 15:40:03 INFO - PROCESS | 28842 | 15:40:03 INFO - PROCESS | 28842 | [#2] jquery.html Cycles:180 Average:37423.28 Median:22217.33 stddev:106062.20 (477.4%) stddev-sans-first:106323.42 15:40:03 INFO - PROCESS | 28842 | Values: 458.1 467.4 479.8 484.9 497.8 20299.8 20897.7 22093.3 22272.3 22460.9 23119.8 23251.1 23348.3 23639.6 23824.7 2117.1 2118.3 2119.1 2119.6 2384.0 6535.3 6883.7 6914.1 6917.8 6917.9 6690.2 6917.1 6929.3 6932.5 6932.7 6919.5 6959.4 6959.5 6960.6 6964.0 6447.2 6653.7 6688.7 6696.6 6701.5 25560.2 25620.1 25701.5 25718.4 25834.9 3063.5 3140.2 3149.8 3232.5 3245.1 16193.4 16984.6 17002.6 17124.8 17574.6 2282.4 2361.2 2404.3 2405.4 2558.3 120428.0 125039.0 127951.4 128087.0 128208.4 21741.3 22162.3 22444.6 22651.4 23108.7 44217.3 44440.8 44761.6 45078.0 45368.3 12308.5 12313.9 12397.9 12419.5 12422.6 10274.2 10314.8 10350.8 10411.0 10545.1 11282.7 11658.8 11687.5 11760.2 11834.4 3865.3 3910.7 4359.9 4387.0 4497.2 966.3 967.3 972.9 974.5 981.8 19831.4 20954.4 21177.5 21572.1 22023.7 973.7 974.2 974.6 992.1 998.4 11272.5 11835.0 11899.3 11946.8 11961.8 614058.0 644707.0 646627.0 654913.0 656984.0 64737.7 79861.0 88683.1 114670.4 115901.4 1248.9 1266.3 1293.6 1304.1 1311.6 18650.5 19055.8 19060.7 19126.0 19173.1 37017.8 44673.0 48417.0 50076.7 51908.7 67281.7 67946.0 68401.7 69372.7 69849.7 11751.9 12156.3 12234.5 12384.5 12568.5 13724.9 13763.2 14349.4 14374.9 14383.3 11395.2 11407.3 11653.7 12158.1 12598.7 10983.5 11014.4 11040.7 11807.8 11821.5 11691.5 11823.8 12122.2 12165.5 12835.0 11949.3 12051.0 12074.9 12250.0 12445.0 11893.5 11900.9 12019.8 12222.8 12521.0 15:40:03 INFO - PROCESS | 28842 | 15:40:03 INFO - PROCESS | 28842 | [#3] mootools.html Cycles:180 Average:7131.93 Median:2505.58 stddev:4559.51 (182.0%) stddev-sans-first:4553.00 15:40:03 INFO - PROCESS | 28842 | Values: 1548.3 1580.4 1596.8 1600.0 1614.2 10673.3 11293.2 11299.5 11317.2 11485.5 11324.3 11517.6 11528.1 11529.5 11535.3 2487.5 2523.7 2528.9 2531.4 2534.1 2385.8 2471.9 2530.8 2535.4 2539.5 2419.2 2448.0 2454.8 2463.4 2463.7 2382.7 2401.7 2406.2 2425.3 2434.0 2394.3 2396.4 2396.5 2407.9 2415.3 11789.8 11829.5 11845.2 11890.0 11900.2 5634.4 5784.0 5792.7 5792.8 5797.6 3445.9 3510.2 3516.7 3554.6 3581.0 1285.0 1310.8 1339.3 1394.2 1409.1 14612.6 14621.2 14985.1 17578.8 17766.6 3965.6 4487.7 4517.8 4518.3 4526.7 12724.9 12737.6 12752.9 12764.3 12766.6 5685.6 5687.8 5693.9 5695.2 5698.1 5362.5 5558.2 5583.5 5590.9 5595.6 3968.6 4043.0 4082.2 4092.9 4119.3 1033.2 1035.9 1041.2 1045.1 1047.8 926.9 930.2 936.6 947.2 948.5 6986.1 7082.5 7153.9 7218.6 7346.4 1053.8 1073.4 1084.7 1090.6 1108.0 4287.3 4291.7 4323.0 4353.7 4375.7 14502.9 15068.0 15108.4 15111.7 15115.0 14213.1 14710.8 14741.4 14746.0 15265.2 9183.5 9376.4 10394.4 10583.4 10672.0 6955.0 7017.1 7082.8 7093.8 7134.7 13252.9 13309.3 13356.1 13589.7 13710.3 13439.5 13475.5 13534.6 13542.6 13690.2 9633.9 9872.6 9884.9 9885.1 9890.6 10692.3 10795.8 10827.3 10963.7 10986.9 9003.5 9033.0 9139.1 9519.1 9631.0 9473.0 9592.4 9635.1 9637.1 9694.5 8750.3 8776.6 8870.6 8872.9 8876.0 6235.5 6327.8 6355.7 6397.1 6478.6 6208.0 6218.2 6282.0 6285.7 6315.7 15:40:03 INFO - PROCESS | 28842 | 15:40:03 INFO - PROCESS | 28842 | [#4] prototype.html Cycles:180 Average:4915.77 Median:5374.34 stddev:1264.79 (23.5%) stddev-sans-first:1222.10 15:40:03 INFO - PROCESS | 28842 | Values: 401.0 402.2 404.5 412.9 420.4 5105.5 5247.9 5254.7 5343.4 5391.3 5125.0 5220.3 5240.2 5247.8 5296.4 4624.2 4624.4 4630.2 4633.0 4642.7 4269.0 4594.9 4599.8 4646.7 4650.9 4541.8 4577.5 4608.6 4610.6 4614.7 4553.2 4565.4 4567.8 4569.6 4581.7 4279.7 4418.4 4422.8 4425.4 4434.8 5099.1 5123.3 5154.2 5160.7 5198.5 5323.6 5340.2 5348.7 5350.2 5357.2 5388.9 5545.8 5549.8 5602.0 5607.7 4833.8 4841.8 4858.0 4866.2 4914.3 5810.9 5823.9 5839.3 5880.2 5887.0 4903.6 5036.2 5038.4 5038.5 5057.6 5125.7 5135.8 5210.8 5213.5 5241.9 5222.2 5316.3 5337.6 5359.7 5425.4 5540.7 5547.8 5553.9 5608.2 5622.4 1680.1 1719.5 1735.4 1768.1 1778.8 1374.8 1422.4 1440.8 1450.8 1463.7 5444.9 5444.9 5462.5 5464.2 5496.9 5291.8 5724.6 5730.9 5743.7 5747.3 5418.8 5440.9 5477.6 5484.0 5495.1 2992.6 2993.4 3008.0 3009.7 3020.6 5734.0 5869.6 5885.9 5889.7 5906.9 5759.6 5780.8 5816.7 5863.7 5875.5 5573.9 5900.8 5943.9 5946.1 6022.6 5621.5 5627.7 5644.6 5652.8 5684.6 5564.0 5596.3 5631.0 5685.4 5704.6 5674.7 5823.8 5830.4 5866.0 5866.6 5025.7 5052.9 5061.0 5061.7 5067.6 5491.2 5592.2 5622.5 5748.0 5782.6 5402.5 5449.2 5461.4 5461.9 5498.0 5471.0 5507.7 5527.7 5546.6 5555.5 5325.8 5402.5 5501.6 5505.9 5544.1 5489.6 5556.1 5557.4 5592.5 5596.8 5206.3 5402.9 5438.9 5458.1 5460.2 15:40:03 INFO - PROCESS | 28842 | 15:40:03 INFO - PROCESS | 28842 | [#5] yui.html Cycles:180 Average:19142.54 Median:42989.68 stddev:23395.14 (54.4%) stddev-sans-first:23420.51 15:40:03 INFO - PROCESS | 28842 | Values: 863.4 879.2 919.4 920.6 931.4 17701.4 19199.2 19230.6 19238.6 19588.3 19530.3 19816.2 19989.8 20018.4 20034.2 884.0 900.6 908.9 921.8 924.6 909.3 915.3 916.5 917.3 917.4 980.8 981.1 981.8 982.5 983.2 976.5 984.3 984.7 985.0 985.0 719.9 719.9 719.9 720.4 720.9 16237.6 16343.6 16344.4 16961.1 16969.2 16530.6 16708.9 16863.2 17043.3 17528.2 13405.6 13649.1 13774.6 13833.8 13857.4 4016.2 4101.6 4101.9 4111.9 4211.1 88773.0 88885.1 89146.0 90137.5 91259.0 13250.4 13401.5 13434.7 13439.6 13542.5 24012.3 24240.8 24281.5 24347.9 24689.8 9525.2 9597.1 9837.2 9864.0 9904.4 4965.2 5129.5 5164.5 5245.8 5265.0 3874.8 3967.7 3968.1 3985.4 3987.6 673.6 679.7 682.2 689.9 694.0 851.1 852.8 861.6 885.7 901.1 11298.6 11902.2 11966.9 12030.5 12140.0 861.3 865.5 866.5 870.8 875.0 5594.6 5663.3 5742.9 5765.6 5798.5 81058.6 81082.6 82590.0 84177.2 87930.0 56851.1 59832.7 70883.3 71199.6 71565.6 65677.4 66048.0 66308.0 67629.0 68952.3 10822.1 10881.5 11180.6 11198.2 11271.3 42832.4 43147.0 43598.3 43682.3 43688.3 51322.2 52191.5 52264.0 52267.5 52337.5 12803.4 12896.9 12928.4 13082.4 13674.2 14801.8 15142.3 15354.2 16366.0 17034.5 16168.2 16565.8 17311.1 17338.1 17472.5 15767.7 16680.3 16701.8 16703.5 16822.0 15499.4 15824.2 16008.9 16366.0 16539.8 11996.4 12411.4 12599.2 12674.3 12938.4 13373.7 13488.8 13510.7 13551.9 13655.4 15:40:03 INFO - PROCESS | 28842 | -------- Summary: end -------- 15:40:03 INFO - PROCESS | 28842 | 15:40:04 INFO - TEST-INFO | 28842: exit 0 15:40:04 INFO - TEST-OK | dromaeo_css | took 1114755ms 15:40:04 INFO - TEST-START | kraken 15:40:04 INFO - operating with platform_type : linux_ 15:40:04 INFO - Initialising browser for kraken test... 15:40:04 INFO - TEST-INFO | started process 29145 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHEP37c/profile http://localhost:60065/getInfo.html) 15:40:07 INFO - PROCESS | 29145 | __metrics Screen width/height:1600/1200 15:40:07 INFO - PROCESS | 29145 | colorDepth:24 15:40:07 INFO - PROCESS | 29145 | Browser inner width/height: 1024/697 15:40:07 INFO - PROCESS | 29145 | __metrics 15:40:08 INFO - PROCESS | 29145 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:40:08 INFO - PROCESS | 29145 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:40:09 INFO - TEST-INFO | 29145: exit 0 15:40:09 INFO - Browser initialized. 15:40:09 INFO - Running cycle 1/1 for kraken test... 15:40:09 INFO - TEST-INFO | started process 29236 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHEP37c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:40:21 INFO - PROCESS | 29236 | RSS: Main: 141352960 15:40:21 INFO - PROCESS | 29236 | 15:40:24 INFO - PROCESS | 29236 | Cycle 1(1): loaded http://localhost:60065/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60065/tests/kraken/driver.html?testName=audio-beat-detection) 15:40:24 INFO - PROCESS | 29236 | RSS: Main: 183873536 15:40:24 INFO - PROCESS | 29236 | 15:40:27 INFO - PROCESS | 29236 | Cycle 1(1): loaded http://localhost:60065/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60065/tests/kraken/driver.html?testName=audio-dft) 15:40:27 INFO - PROCESS | 29236 | RSS: Main: 174010368 15:40:27 INFO - PROCESS | 29236 | 15:40:30 INFO - PROCESS | 29236 | Cycle 1(1): loaded http://localhost:60065/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60065/tests/kraken/driver.html?testName=audio-fft) 15:40:31 INFO - PROCESS | 29236 | RSS: Main: 265060352 15:40:31 INFO - PROCESS | 29236 | 15:40:33 INFO - PROCESS | 29236 | Cycle 1(1): loaded http://localhost:60065/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60065/tests/kraken/driver.html?testName=audio-oscillator) 15:40:34 INFO - PROCESS | 29236 | RSS: Main: 171851776 15:40:34 INFO - PROCESS | 29236 | 15:40:36 INFO - PROCESS | 29236 | Cycle 1(1): loaded http://localhost:60065/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60065/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:40:36 INFO - PROCESS | 29236 | RSS: Main: 173993984 15:40:36 INFO - PROCESS | 29236 | 15:40:39 INFO - PROCESS | 29236 | Cycle 1(1): loaded http://localhost:60065/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60065/tests/kraken/driver.html?testName=imaging-darkroom) 15:40:40 INFO - PROCESS | 29236 | RSS: Main: 212983808 15:40:40 INFO - PROCESS | 29236 | 15:40:43 INFO - PROCESS | 29236 | Cycle 1(1): loaded http://localhost:60065/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60065/tests/kraken/driver.html?testName=imaging-desaturate) 15:40:44 INFO - PROCESS | 29236 | RSS: Main: 212606976 15:40:44 INFO - PROCESS | 29236 | 15:40:46 INFO - PROCESS | 29236 | Cycle 1(1): loaded http://localhost:60065/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60065/tests/kraken/driver.html?testName=json-parse-financial) 15:40:47 INFO - PROCESS | 29236 | RSS: Main: 211628032 15:40:47 INFO - PROCESS | 29236 | 15:40:49 INFO - PROCESS | 29236 | Cycle 1(1): loaded http://localhost:60065/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60065/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:40:49 INFO - PROCESS | 29236 | RSS: Main: 174239744 15:40:49 INFO - PROCESS | 29236 | 15:40:51 INFO - PROCESS | 29236 | Cycle 1(1): loaded http://localhost:60065/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60065/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:40:51 INFO - PROCESS | 29236 | RSS: Main: 180756480 15:40:51 INFO - PROCESS | 29236 | 15:40:53 INFO - PROCESS | 29236 | Cycle 1(1): loaded http://localhost:60065/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60065/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:40:54 INFO - PROCESS | 29236 | RSS: Main: 185729024 15:40:54 INFO - PROCESS | 29236 | 15:40:57 INFO - PROCESS | 29236 | Cycle 1(1): loaded http://localhost:60065/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60065/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:40:57 INFO - PROCESS | 29236 | RSS: Main: 182652928 15:40:57 INFO - PROCESS | 29236 | 15:41:00 INFO - PROCESS | 29236 | Cycle 1(1): loaded http://localhost:60065/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60065/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:41:01 INFO - PROCESS | 29236 | RSS: Main: 185909248 15:41:01 INFO - PROCESS | 29236 | 15:41:02 INFO - PROCESS | 29236 | Cycle 1(1): loaded http://localhost:60065/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60065/tests/kraken/driver.html?testName=ai-astar) 15:41:02 INFO - PROCESS | 29236 | RSS: Main: 237711360 15:41:02 INFO - PROCESS | 29236 | 15:41:02 INFO - PROCESS | 29236 | __start_tp_report 15:41:02 INFO - PROCESS | 29236 | _x_x_mozilla_page_load 15:41:02 INFO - PROCESS | 29236 | _x_x_mozilla_page_load_details 15:41:02 INFO - PROCESS | 29236 | |i|pagename|runs| 15:41:02 INFO - PROCESS | 29236 | |0;ai-astar;103;112;113;113;115;112;111;111;111;111 15:41:03 INFO - PROCESS | 29236 | |1;audio-beat-detection;141;114;123;153;120;119;114;114;129;119 15:41:03 INFO - PROCESS | 29236 | |2;audio-dft;162;160;161;160;161;161;159;161;160;159 15:41:03 INFO - PROCESS | 29236 | |3;audio-fft;74;77;75;113;76;75;78;76;96;76 15:41:03 INFO - PROCESS | 29236 | |4;audio-oscillator;82;82;100;83;83;82;102;83;82;104 15:41:03 INFO - PROCESS | 29236 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;114;112;113 15:41:03 INFO - PROCESS | 29236 | |6;imaging-darkroom;146;146;146;147;147;147;147;147;147;147 15:41:03 INFO - PROCESS | 29236 | |7;imaging-desaturate;95;94;94;94;94;94;94;93;93;93 15:41:03 INFO - PROCESS | 29236 | |8;json-parse-financial;55;56;56;56;56;55;57;60;57;58 15:41:03 INFO - PROCESS | 29236 | |9;json-stringify-tinderbox;54;54;54;54;53;54;54;54;55;54 15:41:03 INFO - PROCESS | 29236 | |10;stanford-crypto-aes;85;77;79;75;80;84;80;75;79;97 15:41:03 INFO - PROCESS | 29236 | |11;stanford-crypto-ccm;129;120;121;136;119;125;118;139;132;118 15:41:03 INFO - PROCESS | 29236 | |12;stanford-crypto-pbkdf2;157;164;164;159;163;160;158;159;162;158 15:41:03 INFO - PROCESS | 29236 | |13;stanford-crypto-sha256-iterative;60;59;64;60;59;65;61;60;59;58 15:41:03 INFO - PROCESS | 29236 | __end_tp_report 15:41:03 INFO - PROCESS | 29236 | __start_cc_report 15:41:03 INFO - PROCESS | 29236 | _x_x_mozilla_cycle_collect,1647 15:41:03 INFO - PROCESS | 29236 | __end_cc_report 15:41:03 INFO - PROCESS | 29236 | __startTimestamp1494974462996__endTimestamp 15:41:03 INFO - PROCESS | 29236 | 15:41:03 INFO - PROCESS | 29236 | ------- Summary: start ------- 15:41:03 INFO - PROCESS | 29236 | Number of tests: 14 15:41:03 INFO - PROCESS | 29236 | 15:41:03 INFO - PROCESS | 29236 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:3.16 (2.8%) stddev-sans-first:1.36 15:41:03 INFO - PROCESS | 29236 | Values: 103.0 112.0 113.0 113.0 115.0 112.0 111.0 111.0 111.0 111.0 15:41:03 INFO - PROCESS | 29236 | 15:41:03 INFO - PROCESS | 29236 | [#1] audio-beat-detection Cycles:10 Average:124.60 Median:121.50 stddev:12.99 (10.7%) stddev-sans-first:12.35 15:41:03 INFO - PROCESS | 29236 | Values: 141.0 114.0 123.0 153.0 120.0 119.0 114.0 114.0 129.0 119.0 15:41:03 INFO - PROCESS | 29236 | 15:41:03 INFO - PROCESS | 29236 | [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:0.83 15:41:03 INFO - PROCESS | 29236 | Values: 162.0 160.0 161.0 160.0 161.0 161.0 159.0 161.0 160.0 159.0 15:41:03 INFO - PROCESS | 29236 | 15:41:03 INFO - PROCESS | 29236 | [#3] audio-fft Cycles:10 Average:81.60 Median:76.00 stddev:12.76 (16.8%) stddev-sans-first:13.24 15:41:03 INFO - PROCESS | 29236 | Values: 74.0 77.0 75.0 113.0 76.0 75.0 78.0 76.0 96.0 76.0 15:41:03 INFO - PROCESS | 29236 | 15:41:03 INFO - PROCESS | 29236 | [#4] audio-oscillator Cycles:10 Average:88.30 Median:82.00 stddev:9.51 (11.6%) stddev-sans-first:9.81 15:41:03 INFO - PROCESS | 29236 | Values: 82.0 82.0 100.0 83.0 83.0 82.0 102.0 83.0 82.0 104.0 15:41:03 INFO - PROCESS | 29236 | 15:41:03 INFO - PROCESS | 29236 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 15:41:03 INFO - PROCESS | 29236 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 114.0 112.0 113.0 15:41:03 INFO - PROCESS | 29236 | 15:41:03 INFO - PROCESS | 29236 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 15:41:03 INFO - PROCESS | 29236 | Values: 146.0 146.0 146.0 147.0 147.0 147.0 147.0 147.0 147.0 147.0 15:41:03 INFO - PROCESS | 29236 | 15:41:03 INFO - PROCESS | 29236 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 15:41:03 INFO - PROCESS | 29236 | Values: 95.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 93.0 93.0 15:41:03 INFO - PROCESS | 29236 | 15:41:03 INFO - PROCESS | 29236 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:56.50 stddev:1.51 (2.7%) stddev-sans-first:1.48 15:41:03 INFO - PROCESS | 29236 | Values: 55.0 56.0 56.0 56.0 56.0 55.0 57.0 60.0 57.0 58.0 15:41:03 INFO - PROCESS | 29236 | 15:41:03 INFO - PROCESS | 29236 | [#9] json-stringify-tinderbox Cycles:10 Average:54.00 Median:54.00 stddev:0.47 (0.9%) stddev-sans-first:0.50 15:41:03 INFO - PROCESS | 29236 | Values: 54.0 54.0 54.0 54.0 53.0 54.0 54.0 54.0 55.0 54.0 15:41:03 INFO - PROCESS | 29236 | 15:41:03 INFO - PROCESS | 29236 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:80.00 stddev:6.49 (8.1%) stddev-sans-first:6.73 15:41:03 INFO - PROCESS | 29236 | Values: 85.0 77.0 79.0 75.0 80.0 84.0 80.0 75.0 79.0 97.0 15:41:03 INFO - PROCESS | 29236 | 15:41:03 INFO - PROCESS | 29236 | [#11] stanford-crypto-ccm Cycles:10 Average:125.70 Median:127.00 stddev:7.83 (6.2%) stddev-sans-first:8.22 15:41:03 INFO - PROCESS | 29236 | Values: 129.0 120.0 121.0 136.0 119.0 125.0 118.0 139.0 132.0 118.0 15:41:03 INFO - PROCESS | 29236 | 15:41:03 INFO - PROCESS | 29236 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.40 Median:161.00 stddev:2.63 (1.6%) stddev-sans-first:2.49 15:41:03 INFO - PROCESS | 29236 | Values: 157.0 164.0 164.0 159.0 163.0 160.0 158.0 159.0 162.0 158.0 15:41:03 INFO - PROCESS | 29236 | 15:41:03 INFO - PROCESS | 29236 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.50 Median:60.00 stddev:2.27 (3.8%) stddev-sans-first:2.40 15:41:03 INFO - PROCESS | 29236 | Values: 60.0 59.0 64.0 60.0 59.0 65.0 61.0 60.0 59.0 58.0 15:41:03 INFO - PROCESS | 29236 | -------- Summary: end -------- 15:41:03 INFO - PROCESS | 29236 | 15:41:04 INFO - TEST-INFO | 29236: exit 0 15:41:04 INFO - TEST-OK | kraken | took 59962ms 15:41:04 INFO - SUITE-END | took 1174s 15:41:04 INFO - Completed test suite (00:19:34) 15:41:04 INFO - javascript benchmark 15:41:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7942.53709479422, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3334.4498157803964, 3345.2138747394356, 3378.206121561434, 3378.2230087079956, 3422.503736958141, 5730.942690573093, 5817.418258174181, 5825.067989121736, 5835.854103647405, 5844.736986835592, 5764.25064741583, 5818.0400233961345, 5822.330432000321, 5823.941760582393, 5825.417458254183, 5245.973770131144, 5268.236105764662, 5320.361556613212, 5326.174442961347, 5363.570914326844, 5171.0, 5194.2208668699805, 5375.274337964363, 5383.219433182187, 5385.623006389555, 5262.315898933152, 5271.0, 5285.735713214343, 5295.682259064469, 5304.257403963448, 5365.248865158881, 5369.060414427459, 5370.43610420904, 5373.301470808769, 5380.865478363033, 5118.539331460149, 5226.7909283628605, 5274.973625131888, 5338.332708411449, 5341.359036915595, 5656.264685590888, 5668.291463567054, 5713.771449142029, 5779.306483221998, 5790.218320526717, 6170.228721409824, 6203.348648391899, 6212.974859148235, 6233.283172435183, 6239.65681887496, 6223.782167624111, 6243.063540468921, 6266.3420340864, 6273.87452250957, 6283.465905398047, 5640.633358831663, 5671.347795003545, 5731.570132240098, 5776.768929242868, 5785.855353616109, 6340.968295158495, 6347.904781428282, 6350.841228969312, 6354.364563543609, 6354.904676429857, 6017.368176341509, 6119.357467465941, 6133.202683651097, 6139.815805525842, 6143.815685529441, 6007.279126504847, 6031.60794548353, 6101.908471372933, 6188.9690551546955, 6201.534884883652, 6170.938290617126, 6180.351063138396, 6183.938160618426, 6184.443400094013, 6185.907211391833, 5970.820875373745, 5998.040313549811, 6002.279726432856, 6090.908636370458, 6191.783287584913, 4673.579377856009, 4713.6936099153445, 4730.88172795676, 4731.787069581877, 4741.857744267678, 4150.190712810973, 4150.418941348214, 4159.729617574909, 4215.78921053946, 4235.322348424237, 5947.405259474104, 5993.0, 5995.46040856325, 6018.3680713524445, 6030.6080104793045, 6195.907061394083, 6222.142451165185, 6245.375462453809, 6245.875082498415, 6264.2796078451565, 5803.593748437569, 5843.795467158714, 5885.735141918624, 5978.432048955342, 6014.879702405928, 5273.604479664041, 5302.09864323066, 5320.680759154463, 5322.733863306819, 5325.653832500875, 6450.193725784277, 6491.188601424822, 6493.480521558264, 6497.15536980199, 6499.3175716549085, 6068.848278793065, 6183.783567575113, 6253.5622506424115, 6267.561270711007, 6269.12232287471, 6337.6197428154455, 6361.300256971637, 6374.298827129105, 6376.3623637635865, 6404.199475065617, 6125.356837531995, 6127.938720612737, 6128.32588415265, 6135.754569817157, 6138.109974053827, 6185.319614842454, 6253.624782513064, 6254.21822272216, 6256.7810126645345, 6259.593126446766, 5882.735276912635, 5930.28836539618, 6020.458158765732, 6062.151298818081, 6136.20229370179, 6013.188219590299, 6082.087686847009, 6086.239220097488, 6093.268807743099, 6112.602680825732, 5867.73595188209, 6072.362401947732, 6076.210092687922, 6081.5134789217645, 6088.8782224356155, 5947.553933454921, 6009.128676341993, 6063.393660634078, 6084.33072362031, 6121.724522396414, 5979.073243647242, 5996.580239383201, 5998.4601385875485, 6001.7299221536005, 6001.849953751192], "value": 5718.510206899366, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.6244808819636, 839.5634270179505, 850.0054935725203, 920.4339331311241, 927.0358826820108, 31681.0, 45300.54699453004, 45955.54044459554, 46041.30938035932, 46060.309095363555, 46961.0, 46995.76502117485, 47230.76384618081, 47258.0, 47276.763616181954, 7731.845363092735, 9311.394759340656, 9439.480828554426, 9448.196903263231, 9452.952735236315, 7811.726589569366, 10703.1437485001, 10984.890151098507, 10989.395583242918, 10992.17558683098, 11154.498047587838, 11155.553777848876, 11157.38634375109, 11180.776384472305, 11191.440427978569, 11054.557817687282, 11057.170712196577, 11085.445727713583, 11091.667249982513, 11097.16771242156, 10883.78232435347, 10898.0, 10918.617848375268, 10946.671599852018, 10949.178811589163, 43281.567184328065, 43789.34315985263, 43858.0, 43914.78042609766, 43941.340879886826, 4322.092360604245, 4356.520782713899, 4385.758783266919, 4488.977555112237, 4498.842540511099, 30515.694843051508, 30640.2339941501, 30758.53862192069, 31225.0, 32745.508817367758, 4161.854335098257, 4199.9790001050105, 4203.495580530325, 4241.257779888538, 4274.978625106885, 251119.0, 251836.0, 251935.0, 253060.0, 253466.73266633734, 57381.139282910786, 57424.0, 57796.0, 57828.71085644545, 57851.132233016535, 57418.13872791911, 58475.41524584785, 58686.41313586895, 58734.70632646809, 58765.41234587685, 16854.0, 17298.913505432392, 17306.048167350666, 17309.30762769475, 17311.220995055253, 12686.682832929251, 13153.53962611304, 15417.768733469007, 15437.459688910834, 15444.613884652748, 13757.587272381845, 14133.434662613587, 14174.14955102697, 14764.114153150631, 14891.851081489263, 5468.644538105011, 5504.697241651747, 5521.4478552144465, 5592.356878958891, 5612.326520817528, 1008.626808081, 1010.2120346130031, 1018.6638409324905, 1019.1490105761771, 1022.6574097677361, 28944.855275723487, 29335.706642933725, 29430.852845735633, 29716.2570935724, 30013.0, 1093.0435868614961, 1093.786711591248, 1106.1427393769893, 1114.4093630375914, 1115.6931843743037, 16100.838991610168, 16668.249928753234, 17073.48779536616, 17122.14389280531, 17630.118494075246, 312945.0, 315241.0, 315698.0, 316136.0, 317099.0, 52550.73724631429, 52613.0, 52663.473365265854, 52696.20955685668, 52756.47243527592, 1501.5045035138378, 1505.52575938579, 1509.222750283605, 1528.9464868729763, 1539.692061587687, 28306.0, 29267.853660731562, 29305.120846374644, 29660.0, 29896.103116906528, 25200.0, 25283.620745688462, 25338.493230135664, 25374.365640859127, 25385.0, 277841.0, 281014.0, 281684.0, 282254.0, 312850.0, 27194.864025679744, 27424.862875685492, 29999.550006750353, 30963.225919352197, 31146.68853311438, 40144.0, 41395.0, 41450.0, 41465.58534414617, 41659.7917010413, 31525.684743153193, 31619.36761264716, 31657.0, 31825.6817431832, 31920.5211921826, 31014.53478197874, 31725.239616613235, 32117.0, 32308.676913230567, 32519.349613008082, 35245.29509409746, 36025.0993725159, 36781.08047298839, 39672.2065558693, 40294.19411611693, 30797.0, 31020.379592408477, 31035.379292414476, 31277.530837037917, 31727.365452690356, 25021.374465638506, 25796.0, 25799.226023219333, 25925.6111158329, 26113.456621275607], "value": 18979.04638385354, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.08840407588906, 467.3877220840699, 479.75532478435986, 484.93695819543456, 497.788439913037, 20299.797002029976, 20897.686534701992, 22093.33719988403, 22272.331830045103, 22460.88769556154, 23119.768802311974, 23251.069957201693, 23348.299551013497, 23639.645405318934, 23824.736650018265, 2117.12139462123, 2118.3433135727896, 2119.0993827623274, 2119.5760847830497, 2383.974890796961, 6535.34646535345, 6883.724651013953, 6914.101166848328, 6917.792466225996, 6917.89623155653, 6690.230623478314, 6917.13535808024, 6929.341712537301, 6932.549384290005, 6932.72269109235, 6919.481038922101, 6959.373656370951, 6959.547629404073, 6960.582365058112, 6964.0, 6447.2263328400895, 6653.733850645968, 6688.665566721645, 6696.631685257309, 6701.463882889358, 25560.233193004053, 25620.103296384725, 25701.485970280675, 25718.357041073934, 25834.87082564594, 3063.5251536011733, 3140.2149462634343, 3149.8425078745972, 3232.515122731609, 3245.1238165695368, 16193.352265909349, 16984.575385615335, 17002.574935626584, 17124.828751712448, 17574.560635984075, 2282.429392651837, 2361.2207971369407, 2404.2666986569266, 2405.374602603318, 2558.270892795544, 120428.0, 125039.0, 127951.36024319819, 128087.0, 128208.35895820461, 21741.347759567238, 22162.33512994613, 22444.55110897773, 22651.43371415728, 23108.65337019946, 44217.33673994893, 44440.77779611146, 44761.55238447639, 45078.0, 45368.3194752079, 12308.50765969369, 12313.876861231272, 12397.93801030989, 12419.503219871103, 12422.571404288441, 10274.178065754722, 10314.793704125876, 10350.844737328945, 10411.010953959361, 10545.10366618846, 11282.717932051764, 11658.834116588443, 11687.474063667156, 11760.176787624956, 11834.408279585987, 3865.342891708416, 3910.7066969977363, 4359.885455554054, 4387.0, 4497.190505708938, 966.3139171188535, 967.308374512213, 972.9075737804898, 974.468914441631, 981.7790997025755, 19831.40505784829, 20954.37136885896, 21177.470563235733, 21572.12083102512, 22023.66964495534, 973.7030205787303, 974.1962880623514, 974.5517062151348, 992.1368409483796, 998.3810037776625, 11272.492737826817, 11835.0, 11899.345535995604, 11946.820797688042, 11961.820572691417, 614058.0, 644707.0, 646627.0, 654913.0, 656984.0055598031, 64737.676311619085, 79861.0, 88683.11316886877, 114670.42664786623, 115901.420492897, 1248.918820276679, 1266.341502418737, 1293.618382577142, 1304.0545604436707, 1311.5934060441102, 18650.533736656693, 19055.80944190596, 19060.714089288394, 19126.0, 19173.137208825916, 37017.81491092527, 44673.0, 48417.0, 50076.74961625168, 51908.74045629748, 67281.66359168173, 67946.0, 68401.65799170973, 69372.65313673399, 69849.65075174591, 11751.941240293743, 12156.331401772633, 12234.510619575118, 12384.504619815467, 12568.486302739671, 13724.862751372359, 13763.174209547058, 14349.426022958965, 14374.928125359726, 14383.280835958369, 11395.202335836411, 11407.258528195973, 11653.6503904883, 12158.088143389105, 12598.685032873886, 10983.450827458435, 11014.44927753625, 11040.668779936615, 11807.822882656596, 11821.468033938665, 11691.532338706696, 11823.82264266054, 12122.151449398458, 12165.513379464723, 12835.0, 11949.342786146497, 12051.0, 12074.879251207376, 12250.0, 12445.0, 11893.524259029888, 11900.880991189977, 12019.75960480803, 12222.75554488923, 12521.0], "value": 11466.16586911582, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1548.3496931288857, 1580.4231455518739, 1596.8323326050765, 1599.976000359994, 1614.2171047042182, 10673.252872298932, 11293.20947533672, 11299.548018079267, 11317.207795454322, 11485.540578376855, 11324.32054076756, 11517.596884109058, 11528.077753779698, 11529.481173347198, 11535.250208736446, 2487.4900645367748, 2523.671922650053, 2528.88620012099, 2531.4177739120014, 2534.0623969131357, 2385.809135269183, 2471.851688898672, 2530.8354956927833, 2535.429528356114, 2539.517491676585, 2419.2258477287273, 2447.963280550793, 2454.7913427358694, 2463.3964678653756, 2463.741307162749, 2382.7498112698263, 2401.6997875265592, 2406.193925035115, 2425.3451568076607, 2434.0, 2394.2817154853474, 2396.424858034077, 2396.508715713275, 2407.855528668268, 2415.3478560788662, 11789.823152652718, 11829.526818927232, 11845.23006004607, 11890.0, 11900.166988310822, 5634.4365563443735, 5784.0, 5792.68140252286, 5792.82621521355, 5797.565182611279, 3445.896623101286, 3510.1751088494184, 3516.7186625069935, 3554.591222009476, 3581.0, 1284.9614511564669, 1310.803379493074, 1339.2567125245484, 1394.2262044565261, 1409.0770545292746, 14612.561623151429, 14621.195834229113, 14985.100893946397, 17578.82421175785, 17766.55583610407, 3965.5836137205756, 4487.708298960557, 4517.774111294422, 4518.254488009507, 4526.705764125321, 12724.872751272369, 12737.55418560346, 12752.872471275354, 12764.297963611905, 12766.553170639168, 5685.573581981368, 5687.772489100473, 5693.857653558694, 5695.231143795618, 5698.059820129715, 5362.463753624606, 5558.249636299129, 5583.525400340935, 5590.944090559043, 5595.608307418523, 3968.5949767587454, 4043.0, 4082.2447847148374, 4092.877213683594, 4119.299719047769, 1033.1734612310272, 1035.8549803027581, 1041.1878734587035, 1045.1012129568564, 1047.7904419116055, 926.8841394825647, 930.223263574909, 936.6300311376968, 947.1570302430704, 948.5162567090833, 6986.126734158231, 7082.468814838805, 7153.928460715326, 7218.60297683622, 7346.375558077623, 1053.8419237114495, 1073.388168743824, 1084.7396624810146, 1090.6346373964652, 1108.024938054507, 4287.2711639021445, 4291.699581029298, 4322.978385108055, 4353.695241333076, 4375.693701440869, 14502.85497145015, 15068.0, 15108.39566417353, 15111.697766044617, 15115.0, 14213.147211167363, 14710.779338309934, 14741.41034358635, 14746.0, 15265.23673816327, 9183.494907780003, 9376.390534615228, 10394.376337419779, 10583.364998100138, 10671.999999999845, 6955.0, 7017.122859642544, 7082.822929426599, 7093.829518129451, 7134.714611415485, 13252.86747132555, 13309.267990260629, 13356.065075444625, 13589.728205435638, 13710.314484275947, 13439.529616463376, 13475.460981560627, 13534.59396218115, 13542.59372218835, 13690.178589284675, 9633.855492167764, 9872.605095796087, 9884.901150988686, 9885.060919212663, 9890.604375825174, 10692.305000174963, 10795.838062429228, 10827.296225745302, 10963.725906852074, 10986.890131098906, 9003.504807235666, 9033.0, 9139.086091390807, 9519.095685909828, 9631.0, 9472.95263523678, 9592.376495527767, 9635.084666956627, 9637.084476974676, 9694.46680432583, 8750.299976002032, 8776.605052772513, 8870.645174193218, 8872.86690699627, 8875.955620221857, 6235.469985051322, 6327.778527751507, 6355.713992870425, 6397.104405383343, 6478.611283323015, 6208.037754148114, 6218.160548325918, 6282.0, 6285.748570057147, 6315.684215789284], "value": 5385.3124443381175, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.9662308196338, 402.20965802198674, 404.50852214559313, 412.93186624207, 420.4072258116057, 5105.5149760772665, 5247.86880327992, 5254.684718916858, 5343.438938911417, 5391.326084239475, 5124.9743751281285, 5220.321358223426, 5240.240165176047, 5247.763850626721, 5296.417394086648, 4624.2370008948565, 4624.398828152335, 4630.166570017395, 4633.0, 4642.721436713791, 4268.996785755345, 4594.862154135381, 4599.8390056348035, 4646.744429056401, 4650.883727906796, 4541.750203738793, 4577.496475387705, 4608.5621865922685, 4610.608098311631, 4614.653900957424, 4553.180427523044, 4565.3836732041245, 4567.794449249792, 4569.611583015448, 4581.702189357715, 4279.721818081845, 4418.3593378960195, 4422.800973956179, 4425.402570652953, 4434.800433980478, 5099.13314736492, 5123.333966584358, 5154.20109882965, 5160.716160611165, 5198.532132108128, 5323.619501616463, 5340.198970154469, 5348.705821179834, 5350.197470379435, 5357.249985002103, 5388.946110538923, 5545.77816887324, 5549.833504994816, 5601.971990140023, 5607.7476513556985, 4833.824778849496, 4841.782119804618, 4858.020528957812, 4866.221404575286, 4914.262860570907, 5810.941890581083, 5823.941760582382, 5839.270091238595, 5880.177514792923, 5886.9705651471895, 4903.583195428358, 5036.169032109734, 5038.370203724535, 5038.496150384932, 5057.6459647824295, 5125.666831655931, 5135.768890399941, 5210.843674689765, 5213.478652134756, 5241.895162096736, 5222.190560463135, 5316.298784388959, 5337.599680024014, 5359.732013399314, 5425.430329815392, 5540.722963851791, 5547.833564993057, 5553.861153471114, 5608.242887210256, 5622.353429355595, 1680.126334306154, 1719.5185348102302, 1735.4273089880562, 1768.0805980889866, 1778.7865456145346, 1374.7662897307482, 1422.4096999745198, 1440.7910852926268, 1450.789635502846, 1463.7365274250524, 5444.863878403072, 5444.91832622511, 5462.535684466785, 5464.23500709901, 5496.945030549643, 5291.814786482455, 5724.570657200727, 5730.9024047806015, 5743.712814359349, 5747.3103227612955, 5418.810341638024, 5440.863978400572, 5477.589180811455, 5484.025750729734, 5495.093309603951, 2992.6408830940427, 2993.431248062861, 3007.992322571963, 3009.653889802657, 3020.622422197225, 5734.0, 5869.618474799125, 5885.911711324334, 5889.734961926723, 5906.911396329058, 5759.568032397587, 5780.75114790637, 5816.673330667682, 5863.736131874076, 5875.529957603381, 5573.944260557342, 5900.822975310747, 5943.910841337383, 5946.078357854453, 6022.638641681426, 5621.494065534122, 5627.738064909323, 5644.604877658525, 5652.830415087554, 5684.602077854511, 5563.972180139073, 5596.328440586993, 5630.971845140748, 5685.374608792947, 5704.572157088152, 5674.687892165971, 5823.7586172445135, 5830.358660547252, 5865.973454645335, 5866.6186697864505, 5025.723585202702, 5052.9494705052475, 5060.974695126501, 5061.670991385548, 5067.569256613229, 5491.203775452617, 5592.245046918777, 5622.453825198103, 5748.0, 5782.5663075268685, 5402.459754024566, 5449.182622606482, 5461.426550212171, 5461.863453413696, 5498.0, 5471.0, 5507.7246137692155, 5527.700990267364, 5546.639468434539, 5555.500004999408, 5325.840224793263, 5402.486763757437, 5501.559875210054, 5505.889882202414, 5544.140658197985, 5489.615726899238, 5556.138798486241, 5557.44425557441, 5592.517982734497, 5596.768710883599, 5206.255904272111, 5402.918956215739, 5438.857839853675, 5458.072127738374, 5460.208269800777], "value": 4548.038215317166, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [863.4128792421151, 879.2306731609841, 919.4115765909819, 920.6327112931607, 931.3759780946765, 17701.380451684192, 19199.232030718755, 19230.615387692254, 19238.615227695438, 19588.314408995688, 19530.31643892464, 19816.20735170595, 19989.800101998975, 20018.399448016546, 20034.1986320547, 884.0496466298722, 900.6172376739892, 908.9318301127408, 921.8386782313069, 924.6347692661428, 909.3180114913814, 915.2860768600502, 916.5096673279796, 917.3028498341275, 917.3716004536881, 980.7842274699555, 981.1415011864618, 981.8478135888948, 982.5431174503855, 983.188869182927, 976.5361453309692, 984.2863923655365, 984.7193549838262, 984.9507524623776, 985.0, 719.8524302517972, 719.9244079371696, 719.931606497382, 720.4020662849822, 720.8702433561957, 16237.594060148473, 16343.591410214722, 16344.427945021867, 16961.067141307223, 16969.23638436273, 16530.58673533159, 16708.916455417766, 16863.241154148094, 17043.318267269417, 17528.211230494657, 13405.597832064956, 13649.11280766747, 13774.586762397144, 13833.79249311261, 13857.4457021719, 4016.2168377166463, 4101.610347017028, 4101.938470922939, 4111.9177616447505, 4211.073563815956, 88773.0, 88885.11114888833, 89145.99999999935, 90137.54931225367, 91259.0, 13250.403731831899, 13401.463941442427, 13434.731305373838, 13439.596812095653, 13542.45830166802, 24012.27963161108, 24240.7575924242, 24281.514369712506, 24347.878260608937, 24689.753102468745, 9525.237980961507, 9597.136257736836, 9837.163841073445, 9864.0, 9904.405735655884, 4965.1807451770055, 5129.512696293846, 5164.457731938092, 5245.842624721265, 5264.973675131677, 3874.786886721201, 3967.7024223183375, 3968.0873399118364, 3985.4221137934837, 3987.6211759882763, 673.5891106425076, 679.7281087565013, 682.2154522299296, 689.917209934811, 694.0248950224942, 851.0935853316198, 852.7527017165077, 861.5606040919176, 885.6634478897978, 901.1394118616705, 11298.60454884075, 11902.226355286879, 11966.940165299291, 12030.4586293617, 12140.028797696163, 861.3367706865804, 865.4936861935838, 866.5277423804042, 870.8301881133245, 874.9825003499894, 5594.580406469449, 5663.32040155184, 5742.856428589319, 5765.625234359753, 5798.536117110621, 81058.59470702727, 81082.59458702669, 82590.0, 84177.15822841816, 87930.0, 56851.14723279154, 59832.70083649554, 70883.2911670887, 71199.64400177966, 71565.6421717888, 65677.40258181977, 66048.0, 66308.0, 67629.0, 68952.3104768956, 10822.080123189302, 10881.478706132135, 11180.608678696206, 11198.16013798952, 11271.267367621078, 42832.357514636686, 43147.0, 43598.34602480902, 43682.34476482792, 43688.34467482927, 51322.23016654678, 52191.47808522018, 52264.0, 52267.47732522626, 52337.47662523478, 12803.35983200855, 12896.935515322364, 12928.418221179882, 13082.411291491715, 13674.247916364695, 14801.777973330623, 15142.318595663442, 15354.155521446428, 16366.0, 17034.48896533106, 16168.191590420196, 16565.834341656428, 17311.13444327804, 17338.13309334503, 17472.475825725247, 15767.68464630678, 16680.332786688396, 16701.832981670028, 16703.498895033168, 16822.0, 15499.361481165151, 15824.21296434373, 16008.919955400614, 16366.0, 16539.849819523384, 11996.400179990791, 12411.441485133371, 12599.18105323151, 12674.302913339854, 12938.353082346033, 13373.732525349633, 13488.831666566828, 13510.729785404434, 13551.932240339129, 13655.385507651981], "value": 8235.832552203092, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1457.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 113.0, 113.0, 115.0, 112.0, 111.0, 111.0, 111.0, 111.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 114.0, 123.0, 153.0, 120.0, 119.0, 114.0, 114.0, 129.0, 119.0], "value": 124.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 161.0, 160.0, 161.0, 161.0, 159.0, 161.0, 160.0, 159.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 75.0, 113.0, 76.0, 75.0, 78.0, 76.0, 96.0, 76.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 100.0, 83.0, 83.0, 82.0, 102.0, 83.0, 82.0, 104.0], "value": 88.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 114.0, 112.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 93.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 56.0, 56.0, 56.0, 55.0, 57.0, 60.0, 57.0, 58.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 54.0, 53.0, 54.0, 54.0, 54.0, 55.0, 54.0], "value": 54.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 77.0, 79.0, 75.0, 80.0, 84.0, 80.0, 75.0, 79.0, 97.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 120.0, 121.0, 136.0, 119.0, 125.0, 118.0, 139.0, 132.0, 118.0], "value": 125.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 164.0, 164.0, 159.0, 163.0, 160.0, 158.0, 159.0, 162.0, 158.0], "value": 160.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 64.0, 60.0, 59.0, 65.0, 61.0, 60.0, 59.0, 58.0], "value": 60.5, "unit": "score"}]}]} 15:41:04 INFO - Return code: 0 15:41:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:41:04 INFO - # TBPL SUCCESS # 15:41:04 INFO - Running post-action listener: _resource_record_post_action 15:41:04 INFO - [mozharness: 2017-05-16 22:41:04.300667Z] Finished run-tests step (success) 15:41:04 INFO - Running post-run listener: _resource_record_post_run 15:41:04 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 327434240; Read time: 16; Write time: 220032 15:41:04 INFO - TinderboxPrint: CPU usage
13.1% 15:41:04 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 15:41:04 INFO - TinderboxPrint: I/O write bytes / time
327,434,240 / 220,032 15:41:04 INFO - TinderboxPrint: CPU idle
8,244.7 (86.9%) 15:41:04 INFO - TinderboxPrint: CPU user
1,200.0 (12.6%) 15:41:04 INFO - TinderboxPrint: Swap in / out
0 / 0 15:41:04 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 97320960; Read time: 0; Write time: 89748 15:41:04 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 230113280; Read time: 0; Write time: 130284 15:41:04 INFO - Running post-run listener: _upload_blobber_files 15:41:04 INFO - Blob upload gear active. 15:41:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:41:04 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:41:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:41:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:41:05 INFO - (blobuploader) - INFO - Open directory for files ... 15:41:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:41:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:41:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:41:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:41:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:41:06 INFO - (blobuploader) - INFO - Done attempting. 15:41:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:41:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:41:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:41:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:41:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:41:06 INFO - (blobuploader) - INFO - Done attempting. 15:41:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:41:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:41:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:41:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:41:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:41:10 INFO - (blobuploader) - INFO - Done attempting. 15:41:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:41:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:41:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:41:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:41:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:41:10 INFO - (blobuploader) - INFO - Done attempting. 15:41:10 INFO - (blobuploader) - INFO - Iteration through files over. 15:41:10 INFO - Return code: 0 15:41:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:41:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:41:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/10af8c96679a1ab1a651448df1c6184a62361d5f15504cb2c594583a69d1bfabe3f014febe0dcb522e30cc10273d9fd32c5980fc4b2d5cc39284e02e0121ceeb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e3d4959b6b074056c4f81469347f03a314311aa360ec1993ebed6d75ff3d62253082334fe4e92d1215b265f5f5cefcb81585486bc35c22853ca284ce67677256", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fa3c48383ccb3591855ac6032f86aef76c4a7ee85b689f63dacf1276788d4c51a98e0944667d96695f79cf4e3051b7c71c45625a70f407dff48dfa6600fec4ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e4516eddb0e2332383e720f65975e6742c98709936f67a63b35cd280f8b457e09dced83e5769191e91f505c3d212c99d4b06f4706a710a552a84c42523743151"} 15:41:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:41:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:41:10 INFO - Contents: 15:41:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/10af8c96679a1ab1a651448df1c6184a62361d5f15504cb2c594583a69d1bfabe3f014febe0dcb522e30cc10273d9fd32c5980fc4b2d5cc39284e02e0121ceeb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e3d4959b6b074056c4f81469347f03a314311aa360ec1993ebed6d75ff3d62253082334fe4e92d1215b265f5f5cefcb81585486bc35c22853ca284ce67677256", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fa3c48383ccb3591855ac6032f86aef76c4a7ee85b689f63dacf1276788d4c51a98e0944667d96695f79cf4e3051b7c71c45625a70f407dff48dfa6600fec4ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e4516eddb0e2332383e720f65975e6742c98709936f67a63b35cd280f8b457e09dced83e5769191e91f505c3d212c99d4b06f4706a710a552a84c42523743151"} 15:41:10 INFO - Running post-run listener: copy_logs_to_upload_dir 15:41:10 INFO - Copying logs to upload dir... 15:41:10 INFO - mkdir: /builds/slave/test/build/upload/logs 15:41:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.255131 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-05-16 15:41:10.657540) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 15:41:10.660960) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/10af8c96679a1ab1a651448df1c6184a62361d5f15504cb2c594583a69d1bfabe3f014febe0dcb522e30cc10273d9fd32c5980fc4b2d5cc39284e02e0121ceeb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e3d4959b6b074056c4f81469347f03a314311aa360ec1993ebed6d75ff3d62253082334fe4e92d1215b265f5f5cefcb81585486bc35c22853ca284ce67677256", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fa3c48383ccb3591855ac6032f86aef76c4a7ee85b689f63dacf1276788d4c51a98e0944667d96695f79cf4e3051b7c71c45625a70f407dff48dfa6600fec4ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e4516eddb0e2332383e720f65975e6742c98709936f67a63b35cd280f8b457e09dced83e5769191e91f505c3d212c99d4b06f4706a710a552a84c42523743151"} build_url:https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011165 build_url: 'https://queue.taskcluster.net/v1/task/Ov489HNHSpqRPCA2Uek3ig/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/10af8c96679a1ab1a651448df1c6184a62361d5f15504cb2c594583a69d1bfabe3f014febe0dcb522e30cc10273d9fd32c5980fc4b2d5cc39284e02e0121ceeb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e3d4959b6b074056c4f81469347f03a314311aa360ec1993ebed6d75ff3d62253082334fe4e92d1215b265f5f5cefcb81585486bc35c22853ca284ce67677256", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fa3c48383ccb3591855ac6032f86aef76c4a7ee85b689f63dacf1276788d4c51a98e0944667d96695f79cf4e3051b7c71c45625a70f407dff48dfa6600fec4ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e4516eddb0e2332383e720f65975e6742c98709936f67a63b35cd280f8b457e09dced83e5769191e91f505c3d212c99d4b06f4706a710a552a84c42523743151"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 15:41:10.687163) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 15:41:10.687814) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=88e9d754c8c9af9f7805d7f3000001bd-1494971291.245220-1806627283 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004548 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 15:41:10.771599) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 15:41:10.771931) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 15:41:10.772282) ========= ========= Total master_lag: 0.19 =========