builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-032 starttime: 1469509976.79 results: success (0) buildid: 20160725203026 builduid: 2b87fc9b155e4445804412d751ffaebf revision: 94968a940273882150fc98556d4abf961b287ad8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 22:12:56.790134) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 22:12:56.790638) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-25 22:12:56.790915) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-25 22:12:56.829066) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 22:12:56.829401) ========= 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=745a4373a2a16df560f216e000000264-1469509733.423385-447909566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-25 22:12:57-- 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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.6M=0s 2016-07-25 22:12:57 (37.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117639 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 22:12:56.961575) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 22:12:56.962170) ========= 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=745a4373a2a16df560f216e000000264-1469509733.423385-447909566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014927 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 22:12:57.012152) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 22:12:57.012537) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 94968a940273882150fc98556d4abf961b287ad8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 94968a940273882150fc98556d4abf961b287ad8 --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=745a4373a2a16df560f216e000000264-1469509733.423385-447909566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-25 22:12:57,575 truncating revision to first 12 chars 2016-07-25 22:12:57,575 Setting DEBUG logging. 2016-07-25 22:12:57,575 attempt 1/10 2016-07-25 22:12:57,575 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/94968a940273?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-25 22:12:57,893 unpacking tar archive at: fx-team-94968a940273/testing/mozharness/ program finished with exit code 0 elapsedTime=0.685203 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 22:12:57.711301) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 22:12:57.711734) ========= script_repo_revision: 94968a940273882150fc98556d4abf961b287ad8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 22:12:57.712136) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-25 22:12:57.712418) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-25 22:12:57.722113) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-25 22:12:57.722424) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=745a4373a2a16df560f216e000000264-1469509733.423385-447909566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:12:58 INFO - MultiFileLogger online at 20160725 22:12:58 in /builds/slave/test 22:12:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:12:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:12:58 INFO - {'append_to_log': False, 22:12:58 INFO - 'base_work_dir': '/builds/slave/test', 22:12:58 INFO - 'blob_upload_branch': 'Fx-Team', 22:12:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:12:58 INFO - 'branch': 'Fx-Team', 22:12:58 INFO - 'buildbot_json_path': 'buildprops.json', 22:12:58 INFO - 'config_files': ('talos/linux_config.py',), 22:12:58 INFO - 'default_actions': ('clobber', 22:12:58 INFO - 'read-buildbot-config', 22:12:58 INFO - 'download-and-extract', 22:12:58 INFO - 'populate-webroot', 22:12:58 INFO - 'create-virtualenv', 22:12:58 INFO - 'install', 22:12:58 INFO - 'run-tests'), 22:12:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:12:58 INFO - 'download_minidump_stackwalk': True, 22:12:58 INFO - 'download_symbols': 'ondemand', 22:12:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:12:58 INFO - 'tooltool.py': '/tools/tooltool.py', 22:12:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:12:58 INFO - '/tools/misc-python/virtualenv.py')}, 22:12:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:12:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:12:58 INFO - 'installer_path': 'installer.exe', 22:12:58 INFO - 'log_level': 'info', 22:12:58 INFO - 'log_name': 'talos', 22:12:58 INFO - 'log_to_console': True, 22:12:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:12:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:12:58 INFO - 'opt_config_files': (), 22:12:58 INFO - 'pip_index': False, 22:12:58 INFO - 'sps_profile': False, 22:12:58 INFO - 'sps_profile_interval': 0, 22:12:58 INFO - 'suite': 'dromaeojs', 22:12:58 INFO - 'system_bits': '32', 22:12:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:12:58 INFO - 'title': 'talos-linux64-ix-032', 22:12:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:12:58 INFO - 'use_talos_json': True, 22:12:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:12:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:12:58 INFO - 'work_dir': 'build'} 22:12:58 INFO - [mozharness: 2016-07-26 05:12:58.373914Z] Running clobber step. 22:12:58 INFO - Running pre-action listener: _resource_record_pre_action 22:12:58 INFO - Running main action method: clobber 22:12:58 INFO - rmtree: /builds/slave/test/build 22:12:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:12:58 INFO - Running post-action listener: _resource_record_post_action 22:12:58 INFO - [mozharness: 2016-07-26 05:12:58.851789Z] Finished clobber step (success) 22:12:58 INFO - [mozharness: 2016-07-26 05:12:58.851893Z] Running read-buildbot-config step. 22:12:58 INFO - Running pre-action listener: _resource_record_pre_action 22:12:58 INFO - Running main action method: read_buildbot_config 22:12:58 INFO - Using buildbot properties: 22:12:58 INFO - { 22:12:58 INFO - "project": "", 22:12:58 INFO - "product": "firefox", 22:12:58 INFO - "script_repo_revision": "production", 22:12:58 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 22:12:58 INFO - "repository": "", 22:12:58 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 22:12:58 INFO - "buildid": "20160725203026", 22:12:58 INFO - "pgo_build": "True", 22:12:58 INFO - "basedir": "/builds/slave/test", 22:12:58 INFO - "buildnumber": 1360, 22:12:58 INFO - "slavename": "talos-linux64-ix-032", 22:12:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:12:58 INFO - "platform": "ubuntu64_hw", 22:12:58 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 22:12:58 INFO - "branch": "fx-team", 22:12:58 INFO - "repo_path": "integration/fx-team", 22:12:58 INFO - "revision": "94968a940273882150fc98556d4abf961b287ad8", 22:12:58 INFO - "stage_platform": "linux64-pgo", 22:12:58 INFO - "builduid": "2b87fc9b155e4445804412d751ffaebf", 22:12:58 INFO - "slavebuilddir": "test" 22:12:58 INFO - } 22:12:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 22:12:58 INFO - Running post-action listener: _resource_record_post_action 22:12:58 INFO - [mozharness: 2016-07-26 05:12:58.854353Z] Finished read-buildbot-config step (success) 22:12:58 INFO - [mozharness: 2016-07-26 05:12:58.854441Z] Running download-and-extract step. 22:12:58 INFO - Running pre-action listener: _resource_record_pre_action 22:12:58 INFO - Running main action method: download_and_extract 22:12:58 INFO - mkdir: /builds/slave/test/build/tests 22:12:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:12:58 INFO - https://queue.taskcluster.net/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:12:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:12:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:12:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:12:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:12:59 INFO - Downloaded 1361 bytes. 22:12:59 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:12:59 INFO - Using the following test package requirements: 22:12:59 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:12:59 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:12:59 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:12:59 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:12:59 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:12:59 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:12:59 INFO - u'jsshell-linux-x86_64.zip'], 22:12:59 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:12:59 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:12:59 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:12:59 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:12:59 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:12:59 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:12:59 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:12:59 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:12:59 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:12:59 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:12:59 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:12:59 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:12:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:12:59 INFO - https://queue.taskcluster.net/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:12:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:12:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:12:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:12:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:13:01 INFO - Downloaded 24311872 bytes. 22:13:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:13:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:13:02 INFO - Return code: 0 22:13:02 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:13:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:02 INFO - https://queue.taskcluster.net/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:13:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:13:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:13:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:13:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:13:04 INFO - Downloaded 24311872 bytes. 22:13:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:13:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:13:05 INFO - Return code: 0 22:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:05 INFO - https://queue.taskcluster.net/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:13:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:13:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:13:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:13:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:13:06 INFO - Downloaded 14403248 bytes. 22:13:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:13:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:13:06 INFO - Return code: 0 22:13:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:06 INFO - https://queue.taskcluster.net/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:13:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:13:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:13:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:13:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:13:08 INFO - Downloaded 60891209 bytes. 22:13:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:13:08 INFO - mkdir: /builds/slave/test/properties 22:13:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:13:08 INFO - Writing to file /builds/slave/test/properties/build_url 22:13:08 INFO - Contents: 22:13:08 INFO - build_url:https://queue.taskcluster.net/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:13:09 INFO - Running post-action listener: _resource_record_post_action 22:13:09 INFO - Running post-action listener: set_extra_try_arguments 22:13:09 INFO - [mozharness: 2016-07-26 05:13:09.222652Z] Finished download-and-extract step (success) 22:13:09 INFO - [mozharness: 2016-07-26 05:13:09.222888Z] Running populate-webroot step. 22:13:09 INFO - Running pre-action listener: _resource_record_pre_action 22:13:09 INFO - Running main action method: populate_webroot 22:13:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:13:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:13:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:13:09 INFO - '--tppagecycles', 22:13:09 INFO - '7'], 22:13:09 INFO - 'tests': ['tsvgm']}}, 22:13:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:13:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:13:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:13:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:13:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:13:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:13:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:09 INFO - 'talos_options': ['--disable-e10s'], 22:13:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:13:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:13:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:09 INFO - 'talos_options': ['--disable-e10s'], 22:13:09 INFO - 'tests': ['damp', 'tps']}, 22:13:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:09 INFO - 'tests': ['damp', 'tps']}, 22:13:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:13:09 INFO - 'tests': ['dromaeo_dom']}, 22:13:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:13:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:13:09 INFO - 'tests': ['basic_compositor_video']}, 22:13:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:13:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:13:09 INFO - 'tests': ['a11yr', 22:13:09 INFO - 'ts_paint', 22:13:09 INFO - 'tpaint', 22:13:09 INFO - 'sessionrestore', 22:13:09 INFO - 'sessionrestore_no_auto_restore', 22:13:09 INFO - 'tabpaint']}, 22:13:09 INFO - 'other-e10s': {'tests': ['a11yr', 22:13:09 INFO - 'ts_paint', 22:13:09 INFO - 'tpaint', 22:13:09 INFO - 'sessionrestore', 22:13:09 INFO - 'sessionrestore_no_auto_restore', 22:13:09 INFO - 'tabpaint']}, 22:13:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:13:09 INFO - 'ts_paint', 22:13:09 INFO - 'tpaint', 22:13:09 INFO - 'sessionrestore', 22:13:09 INFO - 'sessionrestore_no_auto_restore', 22:13:09 INFO - 'tabpaint']}, 22:13:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:13:09 INFO - 'ts_paint', 22:13:09 INFO - 'tpaint', 22:13:09 INFO - 'sessionrestore', 22:13:09 INFO - 'sessionrestore_no_auto_restore', 22:13:09 INFO - 'tabpaint']}, 22:13:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:13:09 INFO - 'tests': ['a11yr', 22:13:09 INFO - 'ts_paint', 22:13:09 INFO - 'tpaint', 22:13:09 INFO - 'sessionrestore', 22:13:09 INFO - 'sessionrestore_no_auto_restore', 22:13:09 INFO - 'tabpaint']}, 22:13:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:13:09 INFO - 'tests': ['a11yr', 22:13:09 INFO - 'ts_paint', 22:13:09 INFO - 'tpaint', 22:13:09 INFO - 'sessionrestore', 22:13:09 INFO - 'sessionrestore_no_auto_restore', 22:13:09 INFO - 'tabpaint']}, 22:13:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:13:09 INFO - 'tests': ['tsvgx', 22:13:09 INFO - 'tsvgr_opacity', 22:13:09 INFO - 'tart', 22:13:09 INFO - 'tscrollx', 22:13:09 INFO - 'cart']}, 22:13:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:13:09 INFO - 'tsvgr_opacity', 22:13:09 INFO - 'tart', 22:13:09 INFO - 'tscrollx', 22:13:09 INFO - 'cart']}, 22:13:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:09 INFO - 'talos_options': ['--disable-e10s'], 22:13:09 INFO - 'tests': ['tp5o']}, 22:13:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:09 INFO - 'tests': ['tp5o']}, 22:13:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:09 INFO - 'talos_options': ['--disable-e10s', 22:13:09 INFO - '--xperf_path', 22:13:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:13:09 INFO - 'tests': ['tp5n']}, 22:13:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:09 INFO - 'talos_options': ['--xperf_path', 22:13:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:13:09 INFO - 'tests': ['tp5n']}}, 22:13:09 INFO - 'talos.zip': {'path': '', 22:13:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:13:09 INFO - Running post-action listener: _resource_record_post_action 22:13:09 INFO - [mozharness: 2016-07-26 05:13:09.240469Z] Finished populate-webroot step (success) 22:13:09 INFO - [mozharness: 2016-07-26 05:13:09.240558Z] Running create-virtualenv step. 22:13:09 INFO - Running pre-action listener: _resource_record_pre_action 22:13:09 INFO - Running main action method: create_virtualenv 22:13:09 INFO - Creating virtualenv /builds/slave/test/build/venv 22:13:09 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 22:13:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:13:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:13:09 INFO - Using real prefix '/usr' 22:13:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:13:09 INFO - Installing distribute.............................................................................................................................................................................................done. 22:13:11 INFO - Installing pip.................done. 22:13:11 INFO - Return code: 0 22:13:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:13:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:11 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xef4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b33b0>, '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 0x11b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b4740>, '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': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', '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 22:13:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 22:13:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:13:11 INFO - Using env: {'DISPLAY': ':0', 22:13:11 INFO - 'HOME': '/home/cltbld', 22:13:11 INFO - 'LANG': 'en_US.UTF-8', 22:13:11 INFO - 'LANGUAGE': 'en_US:en', 22:13:11 INFO - 'LOGNAME': 'cltbld', 22:13:11 INFO - 'MAIL': '/var/mail/cltbld', 22:13:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:11 INFO - 'MOZ_NO_REMOTE': '1', 22:13:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:11 INFO - 'NO_EM_RESTART': '1', 22:13:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:11 INFO - 'PWD': '/builds/slave/test', 22:13:11 INFO - 'SHELL': '/bin/bash', 22:13:11 INFO - 'SHLVL': '1', 22:13:11 INFO - 'TERM': 'linux', 22:13:11 INFO - 'TMOUT': '86400', 22:13:11 INFO - 'USER': 'cltbld', 22:13:11 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', 22:13:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:11 INFO - '_': '/tools/buildbot/bin/python'} 22:13:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:12 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 22:13:12 INFO - Cleaning up... 22:13:12 INFO - Return code: 0 22:13:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:13:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:12 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xef4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b33b0>, '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 0x11b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b4740>, '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': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', '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 22:13:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 22:13:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:13:12 INFO - Using env: {'DISPLAY': ':0', 22:13:12 INFO - 'HOME': '/home/cltbld', 22:13:12 INFO - 'LANG': 'en_US.UTF-8', 22:13:12 INFO - 'LANGUAGE': 'en_US:en', 22:13:12 INFO - 'LOGNAME': 'cltbld', 22:13:12 INFO - 'MAIL': '/var/mail/cltbld', 22:13:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:12 INFO - 'MOZ_NO_REMOTE': '1', 22:13:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:12 INFO - 'NO_EM_RESTART': '1', 22:13:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:12 INFO - 'PWD': '/builds/slave/test', 22:13:12 INFO - 'SHELL': '/bin/bash', 22:13:12 INFO - 'SHLVL': '1', 22:13:12 INFO - 'TERM': 'linux', 22:13:12 INFO - 'TMOUT': '86400', 22:13:12 INFO - 'USER': 'cltbld', 22:13:12 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', 22:13:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:12 INFO - '_': '/tools/buildbot/bin/python'} 22:13:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:12 INFO - Downloading/unpacking psutil>=3.1.1 22:13: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 22:13: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 22:13:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13: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 22:13: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 22:13:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:13:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:13:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:13:14 INFO - Installing collected packages: psutil 22:13:14 INFO - Running setup.py install for psutil 22:13:14 INFO - building 'psutil._psutil_linux' extension 22:13:14 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 22:13:14 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 22:13:14 INFO - building 'psutil._psutil_posix' extension 22:13:14 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 22:13:14 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 22:13:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:13:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:13:14 INFO - Successfully installed psutil 22:13:14 INFO - Cleaning up... 22:13:14 INFO - Return code: 0 22:13:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:13:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xef4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b33b0>, '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 0x11b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b4740>, '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': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', '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 22:13:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 22:13:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:13:14 INFO - Using env: {'DISPLAY': ':0', 22:13:14 INFO - 'HOME': '/home/cltbld', 22:13:14 INFO - 'LANG': 'en_US.UTF-8', 22:13:14 INFO - 'LANGUAGE': 'en_US:en', 22:13:14 INFO - 'LOGNAME': 'cltbld', 22:13:14 INFO - 'MAIL': '/var/mail/cltbld', 22:13:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:14 INFO - 'MOZ_NO_REMOTE': '1', 22:13:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:14 INFO - 'NO_EM_RESTART': '1', 22:13:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:14 INFO - 'PWD': '/builds/slave/test', 22:13:14 INFO - 'SHELL': '/bin/bash', 22:13:14 INFO - 'SHLVL': '1', 22:13:14 INFO - 'TERM': 'linux', 22:13:14 INFO - 'TMOUT': '86400', 22:13:14 INFO - 'USER': 'cltbld', 22:13:14 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', 22:13:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:14 INFO - '_': '/tools/buildbot/bin/python'} 22:13:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:13:15 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 22:13:15 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 22:13:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:15 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 22:13:15 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 22:13:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:13:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:13:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:13:17 INFO - Installing collected packages: mozsystemmonitor 22:13:17 INFO - Running setup.py install for mozsystemmonitor 22:13:17 INFO - Successfully installed mozsystemmonitor 22:13:17 INFO - Cleaning up... 22:13:17 INFO - Return code: 0 22:13:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:13:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:17 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xef4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b33b0>, '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 0x11b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b4740>, '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': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', '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 22:13:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 22:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:13:17 INFO - Using env: {'DISPLAY': ':0', 22:13:17 INFO - 'HOME': '/home/cltbld', 22:13:17 INFO - 'LANG': 'en_US.UTF-8', 22:13:17 INFO - 'LANGUAGE': 'en_US:en', 22:13:17 INFO - 'LOGNAME': 'cltbld', 22:13:17 INFO - 'MAIL': '/var/mail/cltbld', 22:13:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:17 INFO - 'MOZ_NO_REMOTE': '1', 22:13:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:17 INFO - 'NO_EM_RESTART': '1', 22:13:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:17 INFO - 'PWD': '/builds/slave/test', 22:13:17 INFO - 'SHELL': '/bin/bash', 22:13:17 INFO - 'SHLVL': '1', 22:13:17 INFO - 'TERM': 'linux', 22:13:17 INFO - 'TMOUT': '86400', 22:13:17 INFO - 'USER': 'cltbld', 22:13:17 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', 22:13:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:17 INFO - '_': '/tools/buildbot/bin/python'} 22:13:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:17 INFO - Downloading/unpacking jsonschema==2.5.1 22:13:17 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 22:13:17 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 22:13:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:17 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 22:13:17 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 22:13:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:13:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:13:19 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 22:13:19 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 22:13:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:19 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 22:13:19 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 22:13:19 INFO - Downloading functools32-3.2.3-2.tar.gz 22:13:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:13:19 INFO - warning: no files found matching '*.txt' 22:13:19 INFO - no previously-included directories found matching 'build' 22:13:19 INFO - no previously-included directories found matching 'dist' 22:13:19 INFO - no previously-included directories found matching '.git*' 22:13:19 INFO - Installing collected packages: jsonschema, functools32 22:13:19 INFO - Running setup.py install for functools32 22:13:19 INFO - warning: no files found matching '*.txt' 22:13:19 INFO - no previously-included directories found matching 'build' 22:13:19 INFO - no previously-included directories found matching 'dist' 22:13:19 INFO - no previously-included directories found matching '.git*' 22:13:19 INFO - Successfully installed jsonschema functools32 22:13:19 INFO - Cleaning up... 22:13:19 INFO - Return code: 0 22:13:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:13:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:19 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xef4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b33b0>, '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 0x11b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b4740>, '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': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', '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 22:13:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 22:13:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:13:19 INFO - Using env: {'DISPLAY': ':0', 22:13:19 INFO - 'HOME': '/home/cltbld', 22:13:19 INFO - 'LANG': 'en_US.UTF-8', 22:13:19 INFO - 'LANGUAGE': 'en_US:en', 22:13:19 INFO - 'LOGNAME': 'cltbld', 22:13:19 INFO - 'MAIL': '/var/mail/cltbld', 22:13:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:19 INFO - 'MOZ_NO_REMOTE': '1', 22:13:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:19 INFO - 'NO_EM_RESTART': '1', 22:13:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:19 INFO - 'PWD': '/builds/slave/test', 22:13:19 INFO - 'SHELL': '/bin/bash', 22:13:19 INFO - 'SHLVL': '1', 22:13:19 INFO - 'TERM': 'linux', 22:13:19 INFO - 'TMOUT': '86400', 22:13:19 INFO - 'USER': 'cltbld', 22:13:19 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', 22:13:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:19 INFO - '_': '/tools/buildbot/bin/python'} 22:13:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:13:19 INFO - Cleaning up... 22:13:19 INFO - Return code: 0 22:13:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:13:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:19 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xef4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b33b0>, '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 0x11b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b4740>, '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': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', '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 22:13:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 22:13:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:13:19 INFO - Using env: {'DISPLAY': ':0', 22:13:19 INFO - 'HOME': '/home/cltbld', 22:13:19 INFO - 'LANG': 'en_US.UTF-8', 22:13:19 INFO - 'LANGUAGE': 'en_US:en', 22:13:19 INFO - 'LOGNAME': 'cltbld', 22:13:19 INFO - 'MAIL': '/var/mail/cltbld', 22:13:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:19 INFO - 'MOZ_NO_REMOTE': '1', 22:13:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:19 INFO - 'NO_EM_RESTART': '1', 22:13:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:19 INFO - 'PWD': '/builds/slave/test', 22:13:19 INFO - 'SHELL': '/bin/bash', 22:13:19 INFO - 'SHLVL': '1', 22:13:19 INFO - 'TERM': 'linux', 22:13:19 INFO - 'TMOUT': '86400', 22:13:19 INFO - 'USER': 'cltbld', 22:13:19 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', 22:13:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:19 INFO - '_': '/tools/buildbot/bin/python'} 22:13:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:20 INFO - Downloading/unpacking blobuploader==1.2.4 22:13:20 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 22:13:20 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 22:13:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:20 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 22:13:20 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 22:13:22 INFO - Downloading blobuploader-1.2.4.tar.gz 22:13:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:13:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:13:22 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 22:13:22 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 22:13:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:22 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 22:13:22 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 22:13:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:13:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:13:22 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 22:13:22 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 22:13:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:22 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 22:13:22 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 22:13:22 INFO - Downloading docopt-0.6.1.tar.gz 22:13:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:13:23 INFO - Installing collected packages: blobuploader, requests, docopt 22:13:23 INFO - Running setup.py install for blobuploader 22:13:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:13:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:13:23 INFO - Running setup.py install for requests 22:13:23 INFO - Running setup.py install for docopt 22:13:23 INFO - Successfully installed blobuploader requests docopt 22:13:23 INFO - Cleaning up... 22:13:23 INFO - Return code: 0 22:13:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:13:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:23 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xef4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b33b0>, '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 0x11b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b4740>, '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': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', '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 22:13:23 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 22:13:23 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:13:23 INFO - Using env: {'DISPLAY': ':0', 22:13:23 INFO - 'HOME': '/home/cltbld', 22:13:23 INFO - 'LANG': 'en_US.UTF-8', 22:13:23 INFO - 'LANGUAGE': 'en_US:en', 22:13:23 INFO - 'LOGNAME': 'cltbld', 22:13:23 INFO - 'MAIL': '/var/mail/cltbld', 22:13:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:23 INFO - 'MOZ_NO_REMOTE': '1', 22:13:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:23 INFO - 'NO_EM_RESTART': '1', 22:13:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:23 INFO - 'PWD': '/builds/slave/test', 22:13:23 INFO - 'SHELL': '/bin/bash', 22:13:23 INFO - 'SHLVL': '1', 22:13:23 INFO - 'TERM': 'linux', 22:13:23 INFO - 'TMOUT': '86400', 22:13:23 INFO - 'USER': 'cltbld', 22:13:23 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', 22:13:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:23 INFO - '_': '/tools/buildbot/bin/python'} 22:13:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:13:23 INFO - Running setup.py (path:/tmp/pip-B74LrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:13:23 INFO - Running setup.py (path:/tmp/pip-08AXhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:13:24 INFO - Running setup.py (path:/tmp/pip-8rqliX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:13:24 INFO - Running setup.py (path:/tmp/pip-nw3vQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:13:24 INFO - Running setup.py (path:/tmp/pip-iQy5zn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:13:24 INFO - Running setup.py (path:/tmp/pip-qduWWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:13:24 INFO - Running setup.py (path:/tmp/pip-Amk941-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:13:24 INFO - Running setup.py (path:/tmp/pip-4iz5Mp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:13:24 INFO - Running setup.py (path:/tmp/pip-TH5bmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:13:24 INFO - Running setup.py (path:/tmp/pip-LdMejM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:13:24 INFO - Running setup.py (path:/tmp/pip-4An75B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:13:24 INFO - Running setup.py (path:/tmp/pip-0X1bXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:13:24 INFO - Running setup.py (path:/tmp/pip-tSDvfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:13:24 INFO - Running setup.py (path:/tmp/pip-wVjcje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:13:24 INFO - Running setup.py (path:/tmp/pip-vAu2Fb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:13:24 INFO - Running setup.py (path:/tmp/pip-Rxbn4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:13:25 INFO - Running setup.py (path:/tmp/pip-83Qetr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:13:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:13:25 INFO - Running setup.py install for manifestparser 22:13:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:13:25 INFO - Running setup.py install for mozcrash 22:13:25 INFO - Running setup.py install for mozdebug 22:13:25 INFO - Running setup.py install for mozdevice 22:13:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:13:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:13:25 INFO - Running setup.py install for mozfile 22:13:25 INFO - Running setup.py install for mozhttpd 22:13:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:13:25 INFO - Running setup.py install for mozinfo 22:13:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:13:25 INFO - Running setup.py install for mozInstall 22:13:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:13:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:13:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:13:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:13:25 INFO - Running setup.py install for mozleak 22:13:26 INFO - Running setup.py install for mozlog 22:13:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:13:26 INFO - Running setup.py install for moznetwork 22:13:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:13:26 INFO - Running setup.py install for mozprocess 22:13:26 INFO - Running setup.py install for mozprofile 22:13:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:13:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:13:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:13:26 INFO - Running setup.py install for mozrunner 22:13:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:13:26 INFO - Running setup.py install for mozscreenshot 22:13:26 INFO - Running setup.py install for moztest 22:13:26 INFO - Running setup.py install for mozversion 22:13:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:13:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:13:26 INFO - Cleaning up... 22:13:26 INFO - Return code: 0 22:13:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:13:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:26 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xef4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b33b0>, '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 0x11b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b4740>, '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': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', '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 22:13:26 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 22:13:26 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:13:26 INFO - Using env: {'DISPLAY': ':0', 22:13:26 INFO - 'HOME': '/home/cltbld', 22:13:26 INFO - 'LANG': 'en_US.UTF-8', 22:13:26 INFO - 'LANGUAGE': 'en_US:en', 22:13:26 INFO - 'LOGNAME': 'cltbld', 22:13:26 INFO - 'MAIL': '/var/mail/cltbld', 22:13:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:26 INFO - 'MOZ_NO_REMOTE': '1', 22:13:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:26 INFO - 'NO_EM_RESTART': '1', 22:13:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:26 INFO - 'PWD': '/builds/slave/test', 22:13:26 INFO - 'SHELL': '/bin/bash', 22:13:26 INFO - 'SHLVL': '1', 22:13:26 INFO - 'TERM': 'linux', 22:13:26 INFO - 'TMOUT': '86400', 22:13:26 INFO - 'USER': 'cltbld', 22:13:26 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', 22:13:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:26 INFO - '_': '/tools/buildbot/bin/python'} 22:13:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:13:27 INFO - Running setup.py (path:/tmp/pip-yoMds5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:13:27 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)) 22:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:13:27 INFO - Running setup.py (path:/tmp/pip-5hJkVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 22:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:13:27 INFO - Running setup.py (path:/tmp/pip-TjWPBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:13:27 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)) 22:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:13:27 INFO - Running setup.py (path:/tmp/pip-G9V5Dl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 22:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:13:27 INFO - Running setup.py (path:/tmp/pip-REdcma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:13:27 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)) 22:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:13:27 INFO - Running setup.py (path:/tmp/pip-KwtIKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:13:27 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)) 22:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:13:27 INFO - Running setup.py (path:/tmp/pip-5_vR1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:13:27 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)) 22:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:13:27 INFO - Running setup.py (path:/tmp/pip-3mj7tR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:13:27 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)) 22:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:13:27 INFO - Running setup.py (path:/tmp/pip-XfzMqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:13:27 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)) 22:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:13:27 INFO - Running setup.py (path:/tmp/pip-I15TaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 22:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:13:27 INFO - Running setup.py (path:/tmp/pip-Bvu1Sn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:13:27 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)) 22:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:13:27 INFO - Running setup.py (path:/tmp/pip-QDiihZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:13:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 22:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:13:28 INFO - Running setup.py (path:/tmp/pip-hAAVNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:13:28 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)) 22:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:13:28 INFO - Running setup.py (path:/tmp/pip-viz0P5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:13:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 22:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:13:28 INFO - Running setup.py (path:/tmp/pip-upfZy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:13:28 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)) 22:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:13:28 INFO - Running setup.py (path:/tmp/pip-T_gWFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:13:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 22:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:13:28 INFO - Running setup.py (path:/tmp/pip-af_uya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:13:28 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)) 22:13:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:13:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:13:28 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)) 22:13:28 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:13:28 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:13:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:13:28 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 22:13:28 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 22:13:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:28 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 22:13:28 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 22:13:30 INFO - Downloading blessings-1.6.tar.gz 22:13:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:13:30 INFO - Installing collected packages: blessings 22:13:30 INFO - Running setup.py install for blessings 22:13:30 INFO - Successfully installed blessings 22:13:30 INFO - Cleaning up... 22:13:30 INFO - Return code: 0 22:13:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:13:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:13:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:13:30 INFO - Reading from file tmpfile_stdout 22:13:30 INFO - Current package versions: 22:13:30 INFO - argparse == 1.2.1 22:13:30 INFO - blessings == 1.6 22:13:30 INFO - blobuploader == 1.2.4 22:13:30 INFO - docopt == 0.6.1 22:13:30 INFO - functools32 == 3.2.3-2 22:13:30 INFO - jsonschema == 2.5.1 22:13:30 INFO - manifestparser == 1.1 22:13:30 INFO - mozInstall == 1.12 22:13:30 INFO - mozcrash == 0.17 22:13:30 INFO - mozdebug == 0.1 22:13:30 INFO - mozdevice == 0.48 22:13:30 INFO - mozfile == 1.2 22:13:30 INFO - mozhttpd == 0.7 22:13:30 INFO - mozinfo == 0.9 22:13:30 INFO - mozleak == 0.1 22:13:30 INFO - mozlog == 3.3 22:13:30 INFO - moznetwork == 0.27 22:13:30 INFO - mozprocess == 0.23 22:13:30 INFO - mozprofile == 0.28 22:13:30 INFO - mozrunner == 6.12 22:13:30 INFO - mozscreenshot == 0.1 22:13:30 INFO - mozsystemmonitor == 0.3 22:13:30 INFO - moztest == 0.7 22:13:30 INFO - mozversion == 1.4 22:13:30 INFO - psutil == 3.1.1 22:13:30 INFO - requests == 1.2.3 22:13:30 INFO - wsgiref == 0.1.2 22:13:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:13:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:30 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xef4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b33b0>, '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 0x11b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b4740>, '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': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', '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 22:13:30 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 22:13:30 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:13:30 INFO - Using env: {'DISPLAY': ':0', 22:13:30 INFO - 'HOME': '/home/cltbld', 22:13:30 INFO - 'LANG': 'en_US.UTF-8', 22:13:30 INFO - 'LANGUAGE': 'en_US:en', 22:13:30 INFO - 'LOGNAME': 'cltbld', 22:13:30 INFO - 'MAIL': '/var/mail/cltbld', 22:13:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:30 INFO - 'MOZ_NO_REMOTE': '1', 22:13:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:30 INFO - 'NO_EM_RESTART': '1', 22:13:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:30 INFO - 'PWD': '/builds/slave/test', 22:13:30 INFO - 'SHELL': '/bin/bash', 22:13:30 INFO - 'SHLVL': '1', 22:13:30 INFO - 'TERM': 'linux', 22:13:30 INFO - 'TMOUT': '86400', 22:13:30 INFO - 'USER': 'cltbld', 22:13:30 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', 22:13:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:30 INFO - '_': '/tools/buildbot/bin/python'} 22:13:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:30 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)) 22:13:30 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)) 22:13:30 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)) 22:13:30 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)) 22:13:30 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)) 22:13:30 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)) 22:13:30 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)) 22:13:30 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)) 22:13:30 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)) 22:13:30 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)) 22:13:30 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)) 22:13:30 INFO - Cleaning up... 22:13:30 INFO - Return code: 0 22:13:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:13:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:30 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xef4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b33b0>, '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 0x11b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b4740>, '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': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', '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 22:13:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:13:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:13:30 INFO - Using env: {'DISPLAY': ':0', 22:13:30 INFO - 'HOME': '/home/cltbld', 22:13:30 INFO - 'LANG': 'en_US.UTF-8', 22:13:30 INFO - 'LANGUAGE': 'en_US:en', 22:13:30 INFO - 'LOGNAME': 'cltbld', 22:13:30 INFO - 'MAIL': '/var/mail/cltbld', 22:13:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:30 INFO - 'MOZ_NO_REMOTE': '1', 22:13:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:30 INFO - 'NO_EM_RESTART': '1', 22:13:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:30 INFO - 'PWD': '/builds/slave/test', 22:13:30 INFO - 'SHELL': '/bin/bash', 22:13:30 INFO - 'SHLVL': '1', 22:13:30 INFO - 'TERM': 'linux', 22:13:30 INFO - 'TMOUT': '86400', 22:13:30 INFO - 'USER': 'cltbld', 22:13:30 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', 22:13:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:30 INFO - '_': '/tools/buildbot/bin/python'} 22:13:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:13:31 INFO - Cleaning up... 22:13:31 INFO - Return code: 0 22:13:31 INFO - Running post-action listener: _resource_record_post_action 22:13:31 INFO - Running post-action listener: _start_resource_monitoring 22:13:31 INFO - Starting resource monitoring. 22:13:31 INFO - [mozharness: 2016-07-26 05:13:31.227523Z] Finished create-virtualenv step (success) 22:13:31 INFO - [mozharness: 2016-07-26 05:13:31.227995Z] Running install step. 22:13:31 INFO - Running pre-action listener: _resource_record_pre_action 22:13:31 INFO - Running main action method: install 22:13:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:13:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:13:31 INFO - Reading from file tmpfile_stdout 22:13:31 INFO - Detecting whether we're running mozinstall >=1.0... 22:13:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:13:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:13:31 INFO - Reading from file tmpfile_stdout 22:13:31 INFO - Output received: 22:13:31 INFO - Usage: mozinstall [options] installer 22:13:31 INFO - Options: 22:13:31 INFO - -h, --help show this help message and exit 22:13:31 INFO - -d DEST, --destination=DEST 22:13:31 INFO - Directory to install application into. [default: 22:13:31 INFO - "/builds/slave/test"] 22:13:31 INFO - --app=APP Application being installed. [default: firefox] 22:13:31 INFO - mkdir: /builds/slave/test/build/application 22:13:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:13:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:13:50 INFO - Reading from file tmpfile_stdout 22:13:50 INFO - Output received: 22:13:50 INFO - /builds/slave/test/build/application/firefox/firefox 22:13:50 INFO - Running post-action listener: _resource_record_post_action 22:13:50 INFO - [mozharness: 2016-07-26 05:13:50.077241Z] Finished install step (success) 22:13:50 INFO - [mozharness: 2016-07-26 05:13:50.077588Z] Running run-tests step. 22:13:50 INFO - Running pre-action listener: _resource_record_pre_action 22:13:50 INFO - Running main action method: run_tests 22:13:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:13:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:13:50 INFO - Python 2.7.3 22:13:50 INFO - Return code: 0 22:13:50 INFO - grabbing minidump binary from tooltool 22:13:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:50 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 0x11b33b0>, '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 0x11b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b4740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:13:50 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 22:13:50 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 22:13:50 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 22:13:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:13:50 INFO - Return code: 0 22:13:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:13:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:13:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:13:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:13:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:13:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 22:13:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --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 22:13:50 INFO - Using env: {'DISPLAY': ':0', 22:13:50 INFO - 'HOME': '/home/cltbld', 22:13:50 INFO - 'LANG': 'en_US.UTF-8', 22:13:50 INFO - 'LANGUAGE': 'en_US:en', 22:13:50 INFO - 'LOGNAME': 'cltbld', 22:13:50 INFO - 'MAIL': '/var/mail/cltbld', 22:13:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:13:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:13:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:50 INFO - 'MOZ_NO_REMOTE': '1', 22:13:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:13:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:50 INFO - 'NO_EM_RESTART': '1', 22:13:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:50 INFO - 'PWD': '/builds/slave/test', 22:13:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:13:50 INFO - 'SHELL': '/bin/bash', 22:13:50 INFO - 'SHLVL': '1', 22:13:50 INFO - 'TERM': 'linux', 22:13:50 INFO - 'TMOUT': '86400', 22:13:50 INFO - 'USER': 'cltbld', 22:13:50 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469509733.423385-447909566', 22:13:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:50 INFO - '_': '/tools/buildbot/bin/python'} 22:13:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 22:13:50 INFO - mozversion application_buildid: 20160725203026 22:13:50 INFO - mozversion application_changeset: 94968a940273882150fc98556d4abf961b287ad8 22:13:50 INFO - mozversion application_display_name: Nightly 22:13:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:13:50 INFO - mozversion application_name: Firefox 22:13:50 INFO - mozversion application_remotingname: firefox 22:13:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:13:50 INFO - mozversion application_vendor: Mozilla 22:13:50 INFO - mozversion application_version: 50.0a1 22:13:50 INFO - mozversion platform_buildid: 20160725203026 22:13:50 INFO - mozversion platform_changeset: 94968a940273882150fc98556d4abf961b287ad8 22:13:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:13:50 INFO - mozversion platform_version: 50.0a1 22:13:50 INFO - using testdate: 1469510030 22:13:50 INFO - actual date: 1469510030 22:13:50 INFO - starting webserver on 'localhost:54928' 22:13:50 INFO - SUITE-START | Running 2 tests 22:13:50 INFO - TEST-START | dromaeo_css 22:13:50 INFO - operating with platform_type : linux_ 22:13:50 INFO - Initialising browser for dromaeo_css test... 22:13:50 INFO - TEST-INFO | started process 21210 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpA5Yzge/profile http://localhost:54928/getInfo.html) 22:13:54 INFO - PROCESS | 21210 | JavaScript error: , line 0: uncaught exception: undefined 22:13:54 INFO - PROCESS | 21210 | __metrics Screen width/height:1600/1200 22:13:54 INFO - PROCESS | 21210 | colorDepth:24 22:13:54 INFO - PROCESS | 21210 | Browser inner width/height: 1024/697 22:13:54 INFO - PROCESS | 21210 | __metrics 22:13:55 INFO - PROCESS | 21210 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:13:55 INFO - PROCESS | 21210 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:13:56 INFO - PROCESS | 21210 | console.error: PushService: 22:13:56 INFO - PROCESS | 21210 | stateChangeProcessEnqueue: Error transitioning state 22:13:56 INFO - PROCESS | 21210 | UnknownError 22:13:56 INFO - TEST-INFO | 21210: exit 0 22:13:56 INFO - Browser initialized. 22:13:56 INFO - Running cycle 1/1 for dromaeo_css test... 22:13:56 INFO - TEST-INFO | started process 21289 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpA5Yzge/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:14:08 INFO - PROCESS | 21289 | RSS: Main: 157790208 22:14:08 INFO - PROCESS | 21289 | 22:15:00 INFO - PROCESS | 21289 | 1469510100121 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:17:08 INFO - PROCESS | 21289 | Cycle 1(1): loaded http://localhost:54928/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54928/tests/dromaeo/cssquery-ext.html) 22:17:09 INFO - PROCESS | 21289 | RSS: Main: 265015296 22:17:09 INFO - PROCESS | 21289 | 22:20:11 INFO - PROCESS | 21289 | Cycle 1(1): loaded http://localhost:54928/tests/dromaeo/cssquery-ext.html (next: http://localhost:54928/tests/dromaeo/cssquery-jquery.html) 22:20:12 INFO - PROCESS | 21289 | RSS: Main: 242343936 22:20:12 INFO - PROCESS | 21289 | 22:23:14 INFO - PROCESS | 21289 | Cycle 1(1): loaded http://localhost:54928/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54928/tests/dromaeo/cssquery-mootools.html) 22:23:14 INFO - PROCESS | 21289 | RSS: Main: 187723776 22:23:14 INFO - PROCESS | 21289 | 22:26:16 INFO - PROCESS | 21289 | Cycle 1(1): loaded http://localhost:54928/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54928/tests/dromaeo/cssquery-prototype.html) 22:26:17 INFO - PROCESS | 21289 | RSS: Main: 215965696 22:26:17 INFO - PROCESS | 21289 | 22:29:20 INFO - PROCESS | 21289 | Cycle 1(1): loaded http://localhost:54928/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54928/tests/dromaeo/cssquery-yui.html) 22:29:20 INFO - PROCESS | 21289 | RSS: Main: 185524224 22:29:20 INFO - PROCESS | 21289 | 22:32:23 INFO - PROCESS | 21289 | Cycle 1(1): loaded http://localhost:54928/tests/dromaeo/cssquery-yui.html (next: http://localhost:54928/tests/dromaeo/cssquery-dojo.html) 22:32:23 INFO - PROCESS | 21289 | RSS: Main: 276783104 22:32:23 INFO - PROCESS | 21289 | 22:32:23 INFO - PROCESS | 21289 | __start_tp_report 22:32:23 INFO - PROCESS | 21289 | _x_x_mozilla_page_load 22:32:23 INFO - PROCESS | 21289 | _x_x_mozilla_page_load_details 22:32:23 INFO - PROCESS | 21289 | |i|pagename|runs| 22:32:23 INFO - PROCESS | 21289 | |0;dojo.html;3095.9535606965887;3098.4732595458768;3121.7658675599337;3135.0908236611385;3161.3835302116095;5284.365876094865;5370.677759334442;5389.946100538993;5443.727813609318;5466.480684334992;5428.809991650293;5446.564274858012;5483.533899618537;5502.862428439281;5503.889922201563;4872.512748725126;4930.827421040246;4965.702057876539;4973.502649735015;5089.51649593288;4983.900321993558;5022.0709168803705;5033.823816166435;5044.016416798726;5047.545720885119;4835.709857408549;4862.513748625144;4863.14894893394;4924.433690125646;4926.5566099051075;4900.338454308694;4912.5578697917;4920.3111564381015;4936.827211047596;5002.924956125661;4959.628027897922;4982.352294201731;4983.25251212321;4996.3504744383;5033.823816166417;5275.366955965309;5414.3232095988005;5477.370102438231;5485.862853428656;5547.611667183259;5833.912491312633;5881.941180588225;5890.263717035371;5894.852628684317;5939.970300148515;5925.555583331267;5938.287405511366;5942.851428714273;5942.940570594282;5951.434613711691;5247.291615631879;5371.919421208685;5412.83761487156;5416.187571864252;5514.420985796433;5951.256092988377;6007.879842403215;6009.188759517409;6009.609375390585;6012.699365031731;5793.507551858055;5800.361960184377;5809.8547536311935;5810.331811841608;5811.709414529257;5697.971510142423;5844.94155058444;5906.645601263938;5931.940680593225;5939.851503712355;5817.7091145442555;5845.386234445407;5849.093390524476;5850.619709718854;5866.032104702762;5808.767649293981;5842.065269556851;5851.9707401463575;5865.706714664249;5884.58807883444;4327.740335579875;4376.562343765598;4386.627136693417;4397.714148580332;4413.205622987829;3922.646961773454;3937.350337194388;3960.128771670228;3975.3043217436834;3994.960050399517;5458.181272809111;5504.2294078829;5506;5509.972450137804;5511.311086114236;5675.404082571353;5823.923932422127;5861.443162899518;5886.764529418861;5928.762849486058;5606;5613.045782217033;5650.039493286151;5657.4059723728415;5663.207150998864;5032.79868805251;5070.112730272188;5076.593872490192;5079.2381142828135;5123.154679477919;5852.824415267549;5973.701314934236;6123.8162855114415;6133.877322453526;6134.38656134383;5835.649861008353;5871.7651293947765;5887.381824908323;5934;5935.465808077293;5976.641401515923;6013.609115407485;6022.909656355246;6034.185384973148;6039.033754599243;5602.9719851402115;5734.942650573441;5764.933487304862;5777.508911742549;5779.2486976694445;5800.303963524404;5926.4666180044005;5932.911006334822;5970.014947533608;5986.281646202484;5745.626534275259;5775.104858746901;5783.508401785895;5783.768649253982;5792.362840087502;5564.944350556442;5669;5761.5102716269585;5775.162601422854;5778.27771528559;5648.802291919763;5727.169560413633;5733.082706766897;5763.423657634203;5778.566607504538;5815.854603634773;5822.126680997884;5853.2976042875125;5870.031444811559;5877.4122587740885;5376.946230537645;5477.315335583052;5655.547556195412;5664.0937450007805;5666.886662266814 22:32:23 INFO - PROCESS | 21289 | |1;ext.html;576.9307683078031;596.8239369386031;602.358488210056;611.2817439508577;628.691940948935;31734.523982140257;45741;46628.3005754914;46639.30041049387;46666.30000549994;47127.76436117823;47380.52619473804;47412.525874741244;47574.28638570424;47597.76201118998;8988.730338089868;9086.45481271126;9137.314701397421;9142.314326425512;9145.268378529701;8995.550222488882;9068.229200517964;9082.636694532212;9106;9106.590203440861;9105.544722763836;9149.085091490864;9149.54252287383;9149.862752058725;9156.816863662756;9118.544072796367;9147.588358523883;9153.359264851462;9155.954220228856;9173.31200159984;9043.457392556467;9107.544622768835;9111.863322050174;9114.453132811987;9117.31620128493;43956.340654889886;44174.779126104164;44222;44311;44484;3270.1824543864036;3316.502524621302;3444.3111377724304;3501.0022143688925;4149.771762553059;33192.336153276796;33220.50169247463;33806.309684515676;33835.66164338374;33916;4428.202923473774;4584.12901548708;4637.281221410687;4698.365720627706;4703.9294410583525;356280;360324;360651;360703;361279;60590.697046515364;61175;61223.69388153031;61251.69374153101;61281;64435;64505.67747161234;64753.35246647568;64952.15430611268;65009.72465963668;16869.24088416024;16997.745033824256;17049.573760655836;17064.48806535806;17094.743578846326;12849.229046257255;12877.678058048436;12910.67723306906;12911.160774549624;12916.935415322863;13905.235212063433;15648.765268520981;18125.63748725018;18344.449666510027;18470.168842402123;6477;6570.733097081499;6680.732770689216;6782.134912898893;6982.162140543167;975.2977855943709;1046.283295942282;1058.2750815691277;1096.5812177094438;1161.1697636190165;33351.49972750411;33404.164895877315;33747.466649404334;33812;33827.83086084554;1064.1752641702742;1096.2874131814226;1140.9714757131137;1168.4741866160261;1169.1523645357217;17128.40050598223;17387.65224695499;17435.30258789634;17512;17608.471745847644;514884;516683;519170;519382;521981;61645.38354616486;61835;61837;61883;61971;1242.937853107341;1345.5021641992525;1349.689571398584;1382.9585112446644;1383.3290853936035;32279.51580726244;34479.827600861834;34805.825970869984;35189.47215791714;35277;52558.47441525536;53098;53147.4685253158;53217.73391133019;53263.20105198347;409288;411020;411371;411887;413793;36810.8159459201;43027.56972430235;43736.56263437325;44435.55564444314;46355.53644463647;49870;50272.245916312015;50361.74819125881;50510;50686.23970640369;42529.36205956975;43071.13857722891;43529.78235108931;44830.20679172927;45029.54970450253;41709.79145104255;42166.789166053975;42427.575724242364;42874.1425171501;44140.55859441365;42395.1520969585;43238;43608.34587481254;43654.56345436505;43711;35975.460368093976;37733;38289.649951942294;38771.806140969114;38828.41757373585;28969.130926072252;28987.855060725404;28996.710032899402;29321.12036638904;29386.265343366587 22:32:23 INFO - PROCESS | 21289 | |2;jquery.html;375.8797184900831;388.27199001871486;390.60939060939063;392.13925433673063;408.38130232697466;18297;20110.9944502775;20218.09018594163;20249.088791004404;20269.290574829847;18800.905995470002;19532.90233548834;19944.401667949947;20024;20150;5647.181158731982;5754.683492407916;5876.206712093876;5886.264216972879;5967.612105213169;5821.767129314822;5839.8540036499;5885.705714714269;5904.350521442638;5913.201717768111;5663.2637757091525;5848.152017957394;5852.297724273093;5879.911801322984;5885.7351419186025;5845.707714614274;5889.175515427822;5889.79385721502;5891.587588868781;5893.8821223575505;6866.107406037183;7178;7203.963980180066;7204.567725936408;7340.082489688789;25267.747322526724;25752.87123564389;26021.609675854877;27427.725722742718;27458.5881211782;2417.16607770319;2469.0123950419975;2517.0309430869065;2783.2346104821136;2786.9303267418477;15725.685486290324;16403.753943690856;16670.749938750927;16896.493105206864;17094.487165385057;2004.288477590449;2138.689889965962;2147.4094623978376;2151.8386121040985;2181.2692747929464;98649.50675246649;100767.49616251946;101341;101366.10249013008;102426.99999999926;18020.459386218434;18383.448496545127;18695.439136825917;18974.33589824377;20221.29225477101;32781;33048;33099;33116.3376732464;33189.668103318654;9912.900870991341;9923.255755818342;10026.749331266778;10107.7473063174;10440.216983726099;8170.918290817016;8222.13667564909;8321.500709957421;8399.62201700925;8770.254528365032;8737.946582553095;9065.365424420228;9095;9268.304877134242;9286.257099432165;3092.9381412371627;3328.7667820758616;3575.4636804479537;3587.8026708531283;3642.7450078494253;874.3092956564243;970.8203982263162;978.4765150644371;980.352967041749;1059.1980640874358;19784.60430791376;20208.393748187576;20279.797202027792;20302.492437689176;20350.491237719176;991.2763682511807;1004.6584161385164;1020.8928062553473;1024.2983556263982;1024.779672370444;10617.256792024484;10858.511366988505;11023.503942322615;11096.056835169102;11119.666410007712;480410;501690.407027617;511735;528467;530123;81044.18955810367;93912.53043734875;96163;98300;98363.50818245862;1006.3659894266565;1039.60495011895;1058.1640504001907;1070.2829104500004;1079.9136069114452;17881.642367152843;18274.90862545679;18602.44192674222;18723.25106995705;18961.525961851064;42185.789071054445;43548.56451435572;44110.11779764451;44512.844914326604;45261.77369113133;58783;59830.40169598248;60315.095273570056;62083.37916620957;64779.67610161919;8334.458260213067;8436.11420800807;8452.239298463168;8498.575071246289;8512.191341822518;12594.24434533931;13152.802707959198;13207.60377188686;13479.86520134786;13849;11709;11920.403979801149;11985.760284794429;11996.16026878145;12000.63998080024;11375.943120284677;11420.94289528547;11779.587714429954;12126.24667346496;12168.75662486763;11558.364289964133;12189.512419503475;12245.510179592717;12399.690007749878;12826.807597886225;7192.640367981476;7356.374708149867;7454.6645400956;7813;8589.570521473775;9951.353162044445;10699;11272.887271127183;11306.378149201544;12146.878531214576 22:32:23 INFO - PROCESS | 21289 | |3;mootools.html;1280.1806843620082;1313.0808434096134;1410.6050305914343;1434;1834.7064469684854;8566.143385661439;8905.465672059672;8922.152395522431;9138.451692898414;9145.405548639334;9036.72889813305;9100.40847344922;9115.453072815635;9127.452352858832;9140.908590914089;2321.5472982768365;2354.564405584964;2354.9529009419803;2356.422676444265;2357.1985524921524;2294.2314324701247;2335.4978679583887;2344.15610380263;2348.7886090251873;2349.8120150388013;2312.6531020346915;2324.5815753164425;2325.186184835311;2386.737458879522;2387.0929046962215;2220.7779222077825;2245.2253972379503;2260.5026894083276;2260.626996545568;2305.227748704186;2266.8073213776847;2272.4659704969317;2278.2254033628647;2278.7721227877246;2278.8290878184202;8942.150495702897;8995.370324077317;9017.774555636162;9083.500407477586;9095.590698418588;5284.471552844685;5387.919181212285;5418.051840927822;5442.2380866678695;5458.863528411822;3481.9477707834403;3482.512448257246;3554.2003049313957;3554.3246783111385;3573.749837511375;1133.257716195893;1135.409587014756;1164.6913567904512;1168.1764356128908;1168.9181757276995;12187.684339975465;12233.632991010283;12250.264984100893;12251.69370765729;12262.693432664255;4315.28797748368;4521.977390113028;4570.040291538814;4610.216263235258;4622.306654001926;9722.27082968766;9743.366681165702;9744.805103897883;9773.902260977442;9795.06946840049;5223.817166399233;5245.26566280721;5245.868853278698;5250.579953603703;5267.815626453133;4002.599740025974;4255.340422234559;4342.1532801103485;4383.342498625232;4901.1913034349645;3693.7045036397562;3791.5829258781514;3829.5596006459054;3838.673712734393;3867.57456679765;1110.439228189759;1116.642674344202;1117.7261570915177;1127.6617014895498;1135.1089394825024;990.6582229130938;997.8403455447094;999.8050380175796;1000.0999100809184;1002.6190047781942;6055.364186760415;6162.815115546541;6257.5932564383165;6295.055741638791;6515.827151112845;975.9951200243953;987.5901500877055;1000.4097582426404;1011.5346940407347;1047.5233768635405;3966.147278335172;4113.095119073799;4137.97931010343;4223.767692776927;4265.019045619525;11186.216964812385;11623.76752464946;11627.53489860396;11656.41717914101;11675.532978680758;11397;11397.772044559062;11430.1998860079;11450.713732156597;11462.484188211383;8096.230858068474;8425.705100321582;8450.366222533334;8600;8604.182602652738;5707.800226992035;5742;5884.882302353843;5970.850728731816;6199.636080062379;9868.506574671093;10026.64906728261;10121.291509594257;10152.644657436955;10166.390016599027;9438.433693978383;10119.342242754197;10208.336458130198;10467.842982355423;10603.469826508797;8290.626921788655;8497.872531911902;8558.657653693783;8574.056853746208;8737.868931965899;8680.435771674822;8685.826283474169;8891;8897.73306800797;8928.15182557656;7712.691492340468;7841.843163136591;7904.446688731734;7966.083900351534;8080.151584083822;7579.090509138939;7685.23147685227;7727.832052169922;7821.061472623322;7868.685252589832;7142.142942846892;7147.070880785464;7213.1704853942465;7222.241664625139;7229.060222171084;5004.199328107486;5012.598992080698;5075.289459475603;5137.126688463045;5191.506806853343;5129.7691603877165;5210.739463026909;5287.206918962186;5343.465653434625;5350.063738845764 22:32:23 INFO - PROCESS | 21289 | |4;prototype.html;302.68067189115476;314.3289077818856;316.6216371436134;322.18165858718856;325.7752151015799;5315.495027972342;5360.812371566996;5395.622306438546;5399.514043736063;5418.783248670054;5041.6974981501135;5074.79700811968;5158.2004789257635;5210.062188806013;5317.627766056377;4093.89765255868;4105.609967053125;4112.527059388179;4114.650254728352;4139.3583994480905;4146.419501269825;4159.417681524589;4162.458880345551;4167.437395951538;4172.457580514529;3892.3966785148345;3960.465337179487;4102.323116685743;4112.856050038249;4142.71001029928;4077.4913282085818;4089.8752842968124;4108.445359876423;4118.4440100586335;4126.628603425691;3615.94576081359;3624.818759062063;3625.347437461256;3629.383004889175;3630.3102410541915;5032.6980381177245;5050.696958182521;5126.871828204287;5184.092783762864;5207.6354655174155;4588.632909367243;4648.256278995378;4654.627629789608;4655.464621568495;4711.693739906895;5438;5443.918341224885;5452.91820622691;5487.780488780444;5493.807716729935;3670.761400508985;3861.806909654506;3918.8236529356245;3939.5075615548058;3962.5244970603712;5616.410276920906;5659.641685995372;5670.087639528712;5694.971525142348;5696.401877802854;3934.940975885364;3946.8421263149153;3955.881323560298;3956.9010774730864;3958.921566177722;3904.6485816276104;4023.2154729827403;4026.375911733627;4034.7780872052317;4036.788963310995;4535.2765948939195;4544.909101818012;4568.565986231302;4571.26859702446;4622.838200662961;5471.4528547145765;5481.150421684645;5484.862878427992;5507.915567282334;5554.166874968787;1330.9866901331056;1353.3706826325806;1368.6313686313686;1368.6646771541036;1369.9589012329645;889.1286539191628;902.273669695888;903.70629545398;918.9540522973824;920.0845159066771;4758.262469317261;4769.448345135336;4817.723303324622;4831.444383895827;4847.466778654345;5032.446430892599;5052;5054.469280725544;5055.747212639353;5088.262201980692;4580.633549316047;4770.711907784878;4800.783964721596;4802.855914322576;4838.709677419366;2673.0109859351774;2675.518406686776;2677.799165062628;2701.1086341507257;2702.7432393922545;5176.651612645396;5191.922121168185;5208.114620514521;5222.97388513055;5238.611767881514;4918.606511479073;5102.571280041594;5121.615878809104;5136.2552429898205;5159.25190847325;5062.594992400599;5067.695938243717;5093.770780314969;5162.767675454614;5185.792568297225;4705.929411058768;4969.47820478859;5011.84964451067;5025.522575355335;5067.670601411044;4937.703737775601;4961.677490963076;4968.776405061709;5000.64995450315;5040.4455509894615;5122.462141475092;5160.845174644766;5163.432022477449;5186.611004174627;5230.790768369223;3704.4258139988347;3709.258148370283;3709.3879509880567;3725.8323375448713;3732.384156614237;5244.7902083916215;5412.107002344569;5529.4470552945995;5638.872225554824;5658.85852853682;5540.529055030368;5541.5843811715085;5543.1984605004345;5551.417101204315;5563.944360556343;5507.972460137674;5539.695316757617;5539.811774349029;5560.749766260609;5597.80407685729;5150.871228219175;5409.864753381197;5484.8354549363585;5509.283793106871;5513.807016754395;5563.219769673666;5607.934247670406;5613.354464236665;5618.325800903917;5636;5230.63385563007;5451.3458384994055;5519.613627046068;5520.365158006881;5544.306961629796 22:32:23 INFO - PROCESS | 21289 | |5;yui.html;734.8089496730848;747.5290566942826;752.5447104501776;775.1395950494946;784.0434669702961;15358.155301458415;16238;16686.582835429104;16783;16829;15651.686966260697;15754.606134846634;15838.84161158388;15841;15852.524424267262;807.6648191000743;825.5500752090126;825.694493037577;826.1078035721406;826.1696994520498;673.8315421144714;809.2797410304834;832.305025303869;833.1585099049943;838.5681374092347;871.6556960000796;872.8123453457505;874.5758307220992;874.868769684546;875.1860769484377;867.4361664917853;872.4111224923151;872.6901949807822;872.9258013068896;873.7946582553095;508.8829569199105;527.4093015822303;528.7541293298597;611.1108337369117;624.881272558217;14995.32521036556;15606.687866242612;15611.921940390226;15617.297221625055;15624.531264062096;14174.220417877013;14362.425502979868;14783.408663653441;14904.329305181185;15043.548693539211;12949.417276222499;13046.934765326112;13153.21080735159;13364.799528007088;13545.796813047813;4587.977060114678;4593.517680643517;4728.692634978715;4825.276208568707;4877.292792545097;74136.62931685307;74737.25262747357;79767;81480.59259703723;81587.59206203932;11586.362750048827;11655.533778648929;11811.468483918245;12060.396980151132;12086.939565302117;23322.533549328917;23478.64782028271;23661.526769464515;23676.171334003226;23691.88154059253;8809.559522023874;8849.60176792046;8956.417832840845;9117.042710515432;9158.221551168226;4826.903461930812;4828.251620998681;4847.224444088929;4906.705597664151;5085.0338435697095;3681.4293784463453;3787.3372159872465;3819.923601527954;3820.541535015816;3825.2540754552597;646.179352222684;648.3451713769033;650.5316172355896;654.312971380049;675.5845155229498;626.996865015672;656.1568384625733;688.7795905310353;694.4201591671011;717.1716667249335;9757.658481953098;10007.299489035697;10197.74505637365;10303.3817970922;10367.896321036844;549.8652830056661;626.9374453541079;656.3272645538361;698.2354322017484;705.3052743048094;4406.49325327585;4419.64642828573;4427.8228870844805;4464.374987501752;4507.346434766941;70301;70517.6474117626;99588.50205748926;103824.48087759664;106033.46983265188;100820.49589752004;101086;102118;102408.48795756123;103892.48053759683;87919.56040219885;88080;89192.5540372294;90157.94289256999;90414;9927.805033704315;10168.237382196217;10268.58925642966;10295.53670084863;10380.221483388927;48121;51906;53795.46204537905;54095.45904540904;55472.167917482075;51092;51689.741551292005;69447.65702891327;70070;70733;13866.098703584235;13964.79052814229;14038.649033773829;14189.29053547298;14244.857551424353;14714.264286785832;15250.466233681767;15357.23213839326;16083.597910052342;17001.06494142834;14125.858741412454;14893.404263829325;15175.924120379328;15274.694506110038;15789.447369342019;14185.219812909976;14231.50189743354;14576.781348279572;14800.259987000822;14828.62928426798;14198.432062717375;14220.146791192561;14331.396073931888;14489.058211216237;14809.037412568148;10910.672679819618;11168.21822472419;11211.271267367594;11366.943165284121;11690.18168728215;12208.572699955459;12249.877501224873;12376.81434778497;12613.369331533204;12648.304343261208 22:32:23 INFO - PROCESS | 21289 | __end_tp_report 22:32:23 INFO - PROCESS | 21289 | __start_cc_report 22:32:23 INFO - PROCESS | 21289 | _x_x_mozilla_cycle_collect,1008 22:32:23 INFO - PROCESS | 21289 | __end_cc_report 22:32:23 INFO - PROCESS | 21289 | __startTimestamp1469511143826__endTimestamp 22:32:23 INFO - PROCESS | 21289 | 22:32:23 INFO - PROCESS | 21289 | ------- Summary: start ------- 22:32:23 INFO - PROCESS | 21289 | Number of tests: 6 22:32:23 INFO - PROCESS | 21289 | 22:32:23 INFO - PROCESS | 21289 | [#0] dojo.html Cycles:165 Average:5437.56 Median:5666.89 stddev:638.50 (11.3%) stddev-sans-first:613.47 22:32:23 INFO - PROCESS | 21289 | Values: 3096.0 3098.5 3121.8 3135.1 3161.4 5284.4 5370.7 5389.9 5443.7 5466.5 5428.8 5446.6 5483.5 5502.9 5503.9 4872.5 4930.8 4965.7 4973.5 5089.5 4983.9 5022.1 5033.8 5044.0 5047.5 4835.7 4862.5 4863.1 4924.4 4926.6 4900.3 4912.6 4920.3 4936.8 5002.9 4959.6 4982.4 4983.3 4996.4 5033.8 5275.4 5414.3 5477.4 5485.9 5547.6 5833.9 5881.9 5890.3 5894.9 5940.0 5925.6 5938.3 5942.9 5942.9 5951.4 5247.3 5371.9 5412.8 5416.2 5514.4 5951.3 6007.9 6009.2 6009.6 6012.7 5793.5 5800.4 5809.9 5810.3 5811.7 5698.0 5844.9 5906.6 5931.9 5939.9 5817.7 5845.4 5849.1 5850.6 5866.0 5808.8 5842.1 5852.0 5865.7 5884.6 4327.7 4376.6 4386.6 4397.7 4413.2 3922.6 3937.4 3960.1 3975.3 3995.0 5458.2 5504.2 5506.0 5510.0 5511.3 5675.4 5823.9 5861.4 5886.8 5928.8 5606.0 5613.0 5650.0 5657.4 5663.2 5032.8 5070.1 5076.6 5079.2 5123.2 5852.8 5973.7 6123.8 6133.9 6134.4 5835.6 5871.8 5887.4 5934.0 5935.5 5976.6 6013.6 6022.9 6034.2 6039.0 5603.0 5734.9 5764.9 5777.5 5779.2 5800.3 5926.5 5932.9 5970.0 5986.3 5745.6 5775.1 5783.5 5783.8 5792.4 5564.9 5669.0 5761.5 5775.2 5778.3 5648.8 5727.2 5733.1 5763.4 5778.6 5815.9 5822.1 5853.3 5870.0 5877.4 5376.9 5477.3 5655.5 5664.1 5666.9 22:32:23 INFO - PROCESS | 21289 | 22:32:23 INFO - PROCESS | 21289 | [#1] ext.html Cycles:180 Average:60723.40 Median:44048.45 stddev:114920.09 (260.9%) stddev-sans-first:115153.74 22:32:23 INFO - PROCESS | 21289 | Values: 576.9 596.8 602.4 611.3 628.7 31734.5 45741.0 46628.3 46639.3 46666.3 47127.8 47380.5 47412.5 47574.3 47597.8 8988.7 9086.5 9137.3 9142.3 9145.3 8995.6 9068.2 9082.6 9106.0 9106.6 9105.5 9149.1 9149.5 9149.9 9156.8 9118.5 9147.6 9153.4 9156.0 9173.3 9043.5 9107.5 9111.9 9114.5 9117.3 43956.3 44174.8 44222.0 44311.0 44484.0 3270.2 3316.5 3444.3 3501.0 4149.8 33192.3 33220.5 33806.3 33835.7 33916.0 4428.2 4584.1 4637.3 4698.4 4703.9 356280.0 360324.0 360651.0 360703.0 361279.0 60590.7 61175.0 61223.7 61251.7 61281.0 64435.0 64505.7 64753.4 64952.2 65009.7 16869.2 16997.7 17049.6 17064.5 17094.7 12849.2 12877.7 12910.7 12911.2 12916.9 13905.2 15648.8 18125.6 18344.4 18470.2 6477.0 6570.7 6680.7 6782.1 6982.2 975.3 1046.3 1058.3 1096.6 1161.2 33351.5 33404.2 33747.5 33812.0 33827.8 1064.2 1096.3 1141.0 1168.5 1169.2 17128.4 17387.7 17435.3 17512.0 17608.5 514884.0 516683.0 519170.0 519382.0 521981.0 61645.4 61835.0 61837.0 61883.0 61971.0 1242.9 1345.5 1349.7 1383.0 1383.3 32279.5 34479.8 34805.8 35189.5 35277.0 52558.5 53098.0 53147.5 53217.7 53263.2 409288.0 411020.0 411371.0 411887.0 413793.0 36810.8 43027.6 43736.6 44435.6 46355.5 49870.0 50272.2 50361.7 50510.0 50686.2 42529.4 43071.1 43529.8 44830.2 45029.5 41709.8 42166.8 42427.6 42874.1 44140.6 42395.2 43238.0 43608.3 43654.6 43711.0 35975.5 37733.0 38289.6 38771.8 38828.4 28969.1 28987.9 28996.7 29321.1 29386.3 22:32:23 INFO - PROCESS | 21289 | 22:32:23 INFO - PROCESS | 21289 | [#2] jquery.html Cycles:180 Average:31670.24 Median:2939.93 stddev:84377.49 (2870.0%) stddev-sans-first:84581.47 22:32:23 INFO - PROCESS | 21289 | Values: 375.9 388.3 390.6 392.1 408.4 18297.0 20111.0 20218.1 20249.1 20269.3 18800.9 19532.9 19944.4 20024.0 20150.0 5647.2 5754.7 5876.2 5886.3 5967.6 5821.8 5839.9 5885.7 5904.4 5913.2 5663.3 5848.2 5852.3 5879.9 5885.7 5845.7 5889.2 5889.8 5891.6 5893.9 6866.1 7178.0 7204.0 7204.6 7340.1 25267.7 25752.9 26021.6 27427.7 27458.6 2417.2 2469.0 2517.0 2783.2 2786.9 15725.7 16403.8 16670.7 16896.5 17094.5 2004.3 2138.7 2147.4 2151.8 2181.3 98649.5 100767.5 101341.0 101366.1 102427.0 18020.5 18383.4 18695.4 18974.3 20221.3 32781.0 33048.0 33099.0 33116.3 33189.7 9912.9 9923.3 10026.7 10107.7 10440.2 8170.9 8222.1 8321.5 8399.6 8770.3 8737.9 9065.4 9095.0 9268.3 9286.3 3092.9 3328.8 3575.5 3587.8 3642.7 874.3 970.8 978.5 980.4 1059.2 19784.6 20208.4 20279.8 20302.5 20350.5 991.3 1004.7 1020.9 1024.3 1024.8 10617.3 10858.5 11023.5 11096.1 11119.7 480410.0 501690.4 511735.0 528467.0 530123.0 81044.2 93912.5 96163.0 98300.0 98363.5 1006.4 1039.6 1058.2 1070.3 1079.9 17881.6 18274.9 18602.4 18723.3 18961.5 42185.8 43548.6 44110.1 44512.8 45261.8 58783.0 59830.4 60315.1 62083.4 64779.7 8334.5 8436.1 8452.2 8498.6 8512.2 12594.2 13152.8 13207.6 13479.9 13849.0 11709.0 11920.4 11985.8 11996.2 12000.6 11375.9 11420.9 11779.6 12126.2 12168.8 11558.4 12189.5 12245.5 12399.7 12826.8 7192.6 7356.4 7454.7 7813.0 8589.6 9951.4 10699.0 11272.9 11306.4 12146.9 22:32:23 INFO - PROCESS | 21289 | 22:32:23 INFO - PROCESS | 21289 | [#3] mootools.html Cycles:180 Average:5881.01 Median:4616.26 stddev:3386.22 (73.4%) stddev-sans-first:3378.06 22:32:23 INFO - PROCESS | 21289 | Values: 1280.2 1313.1 1410.6 1434.0 1834.7 8566.1 8905.5 8922.2 9138.5 9145.4 9036.7 9100.4 9115.5 9127.5 9140.9 2321.5 2354.6 2355.0 2356.4 2357.2 2294.2 2335.5 2344.2 2348.8 2349.8 2312.7 2324.6 2325.2 2386.7 2387.1 2220.8 2245.2 2260.5 2260.6 2305.2 2266.8 2272.5 2278.2 2278.8 2278.8 8942.2 8995.4 9017.8 9083.5 9095.6 5284.5 5387.9 5418.1 5442.2 5458.9 3481.9 3482.5 3554.2 3554.3 3573.7 1133.3 1135.4 1164.7 1168.2 1168.9 12187.7 12233.6 12250.3 12251.7 12262.7 4315.3 4522.0 4570.0 4610.2 4622.3 9722.3 9743.4 9744.8 9773.9 9795.1 5223.8 5245.3 5245.9 5250.6 5267.8 4002.6 4255.3 4342.2 4383.3 4901.2 3693.7 3791.6 3829.6 3838.7 3867.6 1110.4 1116.6 1117.7 1127.7 1135.1 990.7 997.8 999.8 1000.1 1002.6 6055.4 6162.8 6257.6 6295.1 6515.8 976.0 987.6 1000.4 1011.5 1047.5 3966.1 4113.1 4138.0 4223.8 4265.0 11186.2 11623.8 11627.5 11656.4 11675.5 11397.0 11397.8 11430.2 11450.7 11462.5 8096.2 8425.7 8450.4 8600.0 8604.2 5707.8 5742.0 5884.9 5970.9 6199.6 9868.5 10026.6 10121.3 10152.6 10166.4 9438.4 10119.3 10208.3 10467.8 10603.5 8290.6 8497.9 8558.7 8574.1 8737.9 8680.4 8685.8 8891.0 8897.7 8928.2 7712.7 7841.8 7904.4 7966.1 8080.2 7579.1 7685.2 7727.8 7821.1 7868.7 7142.1 7147.1 7213.2 7222.2 7229.1 5004.2 5012.6 5075.3 5137.1 5191.5 5129.8 5210.7 5287.2 5343.5 5350.1 22:32:23 INFO - PROCESS | 21289 | 22:32:23 INFO - PROCESS | 21289 | [#4] prototype.html Cycles:180 Average:4460.45 Median:5036.57 stddev:1297.55 (25.8%) stddev-sans-first:1263.10 22:32:23 INFO - PROCESS | 21289 | Values: 302.7 314.3 316.6 322.2 325.8 5315.5 5360.8 5395.6 5399.5 5418.8 5041.7 5074.8 5158.2 5210.1 5317.6 4093.9 4105.6 4112.5 4114.7 4139.4 4146.4 4159.4 4162.5 4167.4 4172.5 3892.4 3960.5 4102.3 4112.9 4142.7 4077.5 4089.9 4108.4 4118.4 4126.6 3615.9 3624.8 3625.3 3629.4 3630.3 5032.7 5050.7 5126.9 5184.1 5207.6 4588.6 4648.3 4654.6 4655.5 4711.7 5438.0 5443.9 5452.9 5487.8 5493.8 3670.8 3861.8 3918.8 3939.5 3962.5 5616.4 5659.6 5670.1 5695.0 5696.4 3934.9 3946.8 3955.9 3956.9 3958.9 3904.6 4023.2 4026.4 4034.8 4036.8 4535.3 4544.9 4568.6 4571.3 4622.8 5471.5 5481.2 5484.9 5507.9 5554.2 1331.0 1353.4 1368.6 1368.7 1370.0 889.1 902.3 903.7 919.0 920.1 4758.3 4769.4 4817.7 4831.4 4847.5 5032.4 5052.0 5054.5 5055.7 5088.3 4580.6 4770.7 4800.8 4802.9 4838.7 2673.0 2675.5 2677.8 2701.1 2702.7 5176.7 5191.9 5208.1 5223.0 5238.6 4918.6 5102.6 5121.6 5136.3 5159.3 5062.6 5067.7 5093.8 5162.8 5185.8 4705.9 4969.5 5011.8 5025.5 5067.7 4937.7 4961.7 4968.8 5000.6 5040.4 5122.5 5160.8 5163.4 5186.6 5230.8 3704.4 3709.3 3709.4 3725.8 3732.4 5244.8 5412.1 5529.4 5638.9 5658.9 5540.5 5541.6 5543.2 5551.4 5563.9 5508.0 5539.7 5539.8 5560.7 5597.8 5150.9 5409.9 5484.8 5509.3 5513.8 5563.2 5607.9 5613.4 5618.3 5636.0 5230.6 5451.3 5519.6 5520.4 5544.3 22:32:23 INFO - PROCESS | 21289 | 22:32:23 INFO - PROCESS | 21289 | [#5] yui.html Cycles:180 Average:20474.27 Median:4413.07 stddev:28050.22 (635.6%) stddev-sans-first:28089.75 22:32:23 INFO - PROCESS | 21289 | Values: 734.8 747.5 752.5 775.1 784.0 15358.2 16238.0 16686.6 16783.0 16829.0 15651.7 15754.6 15838.8 15841.0 15852.5 807.7 825.6 825.7 826.1 826.2 673.8 809.3 832.3 833.2 838.6 871.7 872.8 874.6 874.9 875.2 867.4 872.4 872.7 872.9 873.8 508.9 527.4 528.8 611.1 624.9 14995.3 15606.7 15611.9 15617.3 15624.5 14174.2 14362.4 14783.4 14904.3 15043.5 12949.4 13046.9 13153.2 13364.8 13545.8 4588.0 4593.5 4728.7 4825.3 4877.3 74136.6 74737.3 79767.0 81480.6 81587.6 11586.4 11655.5 11811.5 12060.4 12086.9 23322.5 23478.6 23661.5 23676.2 23691.9 8809.6 8849.6 8956.4 9117.0 9158.2 4826.9 4828.3 4847.2 4906.7 5085.0 3681.4 3787.3 3819.9 3820.5 3825.3 646.2 648.3 650.5 654.3 675.6 627.0 656.2 688.8 694.4 717.2 9757.7 10007.3 10197.7 10303.4 10367.9 549.9 626.9 656.3 698.2 705.3 4406.5 4419.6 4427.8 4464.4 4507.3 70301.0 70517.6 99588.5 103824.5 106033.5 100820.5 101086.0 102118.0 102408.5 103892.5 87919.6 88080.0 89192.6 90157.9 90414.0 9927.8 10168.2 10268.6 10295.5 10380.2 48121.0 51906.0 53795.5 54095.5 55472.2 51092.0 51689.7 69447.7 70070.0 70733.0 13866.1 13964.8 14038.6 14189.3 14244.9 14714.3 15250.5 15357.2 16083.6 17001.1 14125.9 14893.4 15175.9 15274.7 15789.4 14185.2 14231.5 14576.8 14800.3 14828.6 14198.4 14220.1 14331.4 14489.1 14809.0 10910.7 11168.2 11211.3 11366.9 11690.2 12208.6 12249.9 12376.8 12613.4 12648.3 22:32:23 INFO - PROCESS | 21289 | -------- Summary: end -------- 22:32:23 INFO - PROCESS | 21289 | 22:32:24 INFO - TEST-INFO | 21289: exit 0 22:32:24 INFO - TEST-OK | dromaeo_css | took 1114304ms 22:32:24 INFO - TEST-START | kraken 22:32:24 INFO - operating with platform_type : linux_ 22:32:24 INFO - Initialising browser for kraken test... 22:32:24 INFO - TEST-INFO | started process 21482 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0sN76P/profile http://localhost:54928/getInfo.html) 22:32:28 INFO - PROCESS | 21482 | JavaScript error: , line 0: uncaught exception: undefined 22:32:29 INFO - PROCESS | 21482 | __metrics Screen width/height:1600/1200 22:32:29 INFO - PROCESS | 21482 | colorDepth:24 22:32:29 INFO - PROCESS | 21482 | Browser inner width/height: 1024/697 22:32:29 INFO - PROCESS | 21482 | __metrics 22:32:30 INFO - PROCESS | 21482 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:32:30 INFO - PROCESS | 21482 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:32:30 INFO - PROCESS | 21482 | console.error: PushService: 22:32:30 INFO - PROCESS | 21482 | stateChangeProcessEnqueue: Error transitioning state 22:32:30 INFO - PROCESS | 21482 | UnknownError 22:32:31 INFO - TEST-INFO | 21482: exit 0 22:32:31 INFO - Browser initialized. 22:32:31 INFO - Running cycle 1/1 for kraken test... 22:32:31 INFO - TEST-INFO | started process 21565 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0sN76P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:32:42 INFO - PROCESS | 21565 | RSS: Main: 157782016 22:32:42 INFO - PROCESS | 21565 | 22:32:45 INFO - PROCESS | 21565 | Cycle 1(1): loaded http://localhost:54928/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54928/tests/kraken/driver.html?testName=audio-beat-detection) 22:32:46 INFO - PROCESS | 21565 | RSS: Main: 213725184 22:32:46 INFO - PROCESS | 21565 | 22:32:48 INFO - PROCESS | 21565 | Cycle 1(1): loaded http://localhost:54928/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54928/tests/kraken/driver.html?testName=audio-dft) 22:32:49 INFO - PROCESS | 21565 | RSS: Main: 188391424 22:32:49 INFO - PROCESS | 21565 | 22:32:52 INFO - PROCESS | 21565 | Cycle 1(1): loaded http://localhost:54928/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54928/tests/kraken/driver.html?testName=audio-fft) 22:32:53 INFO - PROCESS | 21565 | RSS: Main: 271740928 22:32:53 INFO - PROCESS | 21565 | 22:32:55 INFO - PROCESS | 21565 | Cycle 1(1): loaded http://localhost:54928/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54928/tests/kraken/driver.html?testName=audio-oscillator) 22:32:55 INFO - PROCESS | 21565 | RSS: Main: 181096448 22:32:55 INFO - PROCESS | 21565 | 22:32:58 INFO - PROCESS | 21565 | Cycle 1(1): loaded http://localhost:54928/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54928/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:32:58 INFO - PROCESS | 21565 | RSS: Main: 185757696 22:32:58 INFO - PROCESS | 21565 | 22:33:01 INFO - PROCESS | 21565 | Cycle 1(1): loaded http://localhost:54928/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54928/tests/kraken/driver.html?testName=imaging-darkroom) 22:33:02 INFO - PROCESS | 21565 | RSS: Main: 220020736 22:33:02 INFO - PROCESS | 21565 | 22:33:05 INFO - PROCESS | 21565 | Cycle 1(1): loaded http://localhost:54928/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54928/tests/kraken/driver.html?testName=imaging-desaturate) 22:33:06 INFO - PROCESS | 21565 | RSS: Main: 220385280 22:33:06 INFO - PROCESS | 21565 | 22:33:08 INFO - PROCESS | 21565 | Cycle 1(1): loaded http://localhost:54928/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54928/tests/kraken/driver.html?testName=json-parse-financial) 22:33:09 INFO - PROCESS | 21565 | RSS: Main: 221241344 22:33:09 INFO - PROCESS | 21565 | 22:33:11 INFO - PROCESS | 21565 | Cycle 1(1): loaded http://localhost:54928/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54928/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:33:11 INFO - PROCESS | 21565 | RSS: Main: 185753600 22:33:11 INFO - PROCESS | 21565 | 22:33:13 INFO - PROCESS | 21565 | Cycle 1(1): loaded http://localhost:54928/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54928/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:33:14 INFO - PROCESS | 21565 | RSS: Main: 190042112 22:33:14 INFO - PROCESS | 21565 | 22:33:15 INFO - PROCESS | 21565 | Cycle 1(1): loaded http://localhost:54928/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54928/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:33:16 INFO - PROCESS | 21565 | RSS: Main: 195600384 22:33:16 INFO - PROCESS | 21565 | 22:33:19 INFO - PROCESS | 21565 | Cycle 1(1): loaded http://localhost:54928/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54928/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:33:19 INFO - PROCESS | 21565 | RSS: Main: 191275008 22:33:19 INFO - PROCESS | 21565 | 22:33:22 INFO - PROCESS | 21565 | Cycle 1(1): loaded http://localhost:54928/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54928/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:33:23 INFO - PROCESS | 21565 | RSS: Main: 193953792 22:33:23 INFO - PROCESS | 21565 | 22:33:24 INFO - PROCESS | 21565 | Cycle 1(1): loaded http://localhost:54928/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54928/tests/kraken/driver.html?testName=ai-astar) 22:33:24 INFO - PROCESS | 21565 | RSS: Main: 245223424 22:33:24 INFO - PROCESS | 21565 | 22:33:25 INFO - PROCESS | 21565 | __start_tp_report 22:33:25 INFO - PROCESS | 21565 | _x_x_mozilla_page_load 22:33:25 INFO - PROCESS | 21565 | _x_x_mozilla_page_load_details 22:33:25 INFO - PROCESS | 21565 | |i|pagename|runs| 22:33:25 INFO - PROCESS | 21565 | |0;ai-astar;105;111;113;111;111;111;112;111;110;112 22:33:25 INFO - PROCESS | 21565 | |1;audio-beat-detection;146;123;122;181;120;119;149;117;180;118 22:33:25 INFO - PROCESS | 21565 | |2;audio-dft;160;160;161;159;159;160;160;160;160;162 22:33:25 INFO - PROCESS | 21565 | |3;audio-fft;76;75;75;125;75;76;74;73;97;76 22:33:25 INFO - PROCESS | 21565 | |4;audio-oscillator;84;85;119;84;84;84;119;85;85;120 22:33:25 INFO - PROCESS | 21565 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;117;117;117 22:33:25 INFO - PROCESS | 21565 | |6;imaging-darkroom;146;146;145;146;146;146;145;146;146;145 22:33:25 INFO - PROCESS | 21565 | |7;imaging-desaturate;95;96;95;94;95;94;95;95;95;94 22:33:25 INFO - PROCESS | 21565 | |8;json-parse-financial;54;52;51;53;54;52;53;52;51;52 22:33:25 INFO - PROCESS | 21565 | |9;json-stringify-tinderbox;61;62;71;62;63;62;62;62;63;62 22:33:25 INFO - PROCESS | 21565 | |10;stanford-crypto-aes;76;77;76;76;77;86;75;76;75;80 22:33:25 INFO - PROCESS | 21565 | |11;stanford-crypto-ccm;123;120;131;120;124;119;120;120;165;131 22:33:25 INFO - PROCESS | 21565 | |12;stanford-crypto-pbkdf2;155;157;155;161;156;154;156;155;156;158 22:33:25 INFO - PROCESS | 21565 | |13;stanford-crypto-sha256-iterative;63;62;64;63;63;68;62;63;63;61 22:33:25 INFO - PROCESS | 21565 | __end_tp_report 22:33:25 INFO - PROCESS | 21565 | __start_cc_report 22:33:25 INFO - PROCESS | 21565 | _x_x_mozilla_cycle_collect,1714 22:33:25 INFO - PROCESS | 21565 | __end_cc_report 22:33:25 INFO - PROCESS | 21565 | __startTimestamp1469511204998__endTimestamp 22:33:25 INFO - PROCESS | 21565 | 22:33:25 INFO - PROCESS | 21565 | ------- Summary: start ------- 22:33:25 INFO - PROCESS | 21565 | Number of tests: 14 22:33:25 INFO - PROCESS | 21565 | 22:33:25 INFO - PROCESS | 21565 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.00 stddev:2.16 (1.9%) stddev-sans-first:0.87 22:33:25 INFO - PROCESS | 21565 | Values: 105.0 111.0 113.0 111.0 111.0 111.0 112.0 111.0 110.0 112.0 22:33:25 INFO - PROCESS | 21565 | 22:33:25 INFO - PROCESS | 21565 | [#1] audio-beat-detection Cycles:10 Average:137.50 Median:134.50 stddev:25.39 (18.9%) stddev-sans-first:26.74 22:33:25 INFO - PROCESS | 21565 | Values: 146.0 123.0 122.0 181.0 120.0 119.0 149.0 117.0 180.0 118.0 22:33:25 INFO - PROCESS | 21565 | 22:33:25 INFO - PROCESS | 21565 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.00 stddev:0.88 (0.5%) stddev-sans-first:0.93 22:33:25 INFO - PROCESS | 21565 | Values: 160.0 160.0 161.0 159.0 159.0 160.0 160.0 160.0 160.0 162.0 22:33:25 INFO - PROCESS | 21565 | 22:33:25 INFO - PROCESS | 21565 | [#3] audio-fft Cycles:10 Average:82.20 Median:75.50 stddev:16.58 (22.0%) stddev-sans-first:17.43 22:33:25 INFO - PROCESS | 21565 | Values: 76.0 75.0 75.0 125.0 75.0 76.0 74.0 73.0 97.0 76.0 22:33:25 INFO - PROCESS | 21565 | 22:33:25 INFO - PROCESS | 21565 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:84.00 stddev:16.87 (20.1%) stddev-sans-first:17.42 22:33:25 INFO - PROCESS | 21565 | Values: 84.0 85.0 119.0 84.0 84.0 84.0 119.0 85.0 85.0 120.0 22:33:25 INFO - PROCESS | 21565 | 22:33:25 INFO - PROCESS | 21565 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 22:33:25 INFO - PROCESS | 21565 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 22:33:25 INFO - PROCESS | 21565 | 22:33:25 INFO - PROCESS | 21565 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 22:33:25 INFO - PROCESS | 21565 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 145.0 146.0 146.0 145.0 22:33:25 INFO - PROCESS | 21565 | 22:33:25 INFO - PROCESS | 21565 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 22:33:25 INFO - PROCESS | 21565 | Values: 95.0 96.0 95.0 94.0 95.0 94.0 95.0 95.0 95.0 94.0 22:33:25 INFO - PROCESS | 21565 | 22:33:25 INFO - PROCESS | 21565 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:52.50 stddev:1.07 (2.0%) stddev-sans-first:0.97 22:33:25 INFO - PROCESS | 21565 | Values: 54.0 52.0 51.0 53.0 54.0 52.0 53.0 52.0 51.0 52.0 22:33:25 INFO - PROCESS | 21565 | 22:33:25 INFO - PROCESS | 21565 | [#9] json-stringify-tinderbox Cycles:10 Average:63.00 Median:62.00 stddev:2.87 (4.6%) stddev-sans-first:2.95 22:33:25 INFO - PROCESS | 21565 | Values: 61.0 62.0 71.0 62.0 63.0 62.0 62.0 62.0 63.0 62.0 22:33:25 INFO - PROCESS | 21565 | 22:33:25 INFO - PROCESS | 21565 | [#10] stanford-crypto-aes Cycles:10 Average:77.40 Median:76.50 stddev:3.34 (4.4%) stddev-sans-first:3.50 22:33:25 INFO - PROCESS | 21565 | Values: 76.0 77.0 76.0 76.0 77.0 86.0 75.0 76.0 75.0 80.0 22:33:25 INFO - PROCESS | 21565 | 22:33:25 INFO - PROCESS | 21565 | [#11] stanford-crypto-ccm Cycles:10 Average:127.30 Median:123.50 stddev:13.98 (11.3%) stddev-sans-first:14.75 22:33:25 INFO - PROCESS | 21565 | Values: 123.0 120.0 131.0 120.0 124.0 119.0 120.0 120.0 165.0 131.0 22:33:25 INFO - PROCESS | 21565 | 22:33:25 INFO - PROCESS | 21565 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.30 Median:156.00 stddev:2.00 (1.3%) stddev-sans-first:2.07 22:33:25 INFO - PROCESS | 21565 | Values: 155.0 157.0 155.0 161.0 156.0 154.0 156.0 155.0 156.0 158.0 22:33:25 INFO - PROCESS | 21565 | 22:33:25 INFO - PROCESS | 21565 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.00 stddev:1.87 (3.0%) stddev-sans-first:1.99 22:33:25 INFO - PROCESS | 21565 | Values: 63.0 62.0 64.0 63.0 63.0 68.0 62.0 63.0 63.0 61.0 22:33:25 INFO - PROCESS | 21565 | -------- Summary: end -------- 22:33:25 INFO - PROCESS | 21565 | 22:33:26 INFO - TEST-INFO | 21565: exit 0 22:33:26 INFO - TEST-OK | kraken | took 61425ms 22:33:26 INFO - SUITE-END | took 1175s 22:33:26 INFO - Completed test suite (00:19:35) 22:33:26 INFO - javascript benchmark 22:33:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3095.9535606965887, 3098.4732595458768, 3121.7658675599337, 3135.0908236611385, 3161.3835302116095, 5284.365876094865, 5370.677759334442, 5389.946100538993, 5443.727813609318, 5466.480684334992, 5428.809991650293, 5446.564274858012, 5483.533899618537, 5502.862428439281, 5503.889922201563, 4872.512748725126, 4930.827421040246, 4965.702057876539, 4973.502649735015, 5089.51649593288, 4983.900321993558, 5022.0709168803705, 5033.823816166435, 5044.016416798726, 5047.545720885119, 4835.709857408549, 4862.513748625144, 4863.14894893394, 4924.433690125646, 4926.5566099051075, 4900.338454308694, 4912.5578697917, 4920.3111564381015, 4936.827211047596, 5002.924956125661, 4959.628027897922, 4982.352294201731, 4983.25251212321, 4996.3504744383, 5033.823816166417, 5275.366955965309, 5414.3232095988005, 5477.370102438231, 5485.862853428656, 5547.611667183259, 5833.912491312633, 5881.941180588225, 5890.263717035371, 5894.852628684317, 5939.970300148515, 5925.555583331267, 5938.287405511366, 5942.851428714273, 5942.940570594282, 5951.434613711691, 5247.291615631879, 5371.919421208685, 5412.83761487156, 5416.187571864252, 5514.420985796433, 5951.256092988377, 6007.879842403215, 6009.188759517409, 6009.609375390585, 6012.699365031731, 5793.507551858055, 5800.361960184377, 5809.8547536311935, 5810.331811841608, 5811.709414529257, 5697.971510142423, 5844.94155058444, 5906.645601263938, 5931.940680593225, 5939.851503712355, 5817.7091145442555, 5845.386234445407, 5849.093390524476, 5850.619709718854, 5866.032104702762, 5808.767649293981, 5842.065269556851, 5851.9707401463575, 5865.706714664249, 5884.58807883444, 4327.740335579875, 4376.562343765598, 4386.627136693417, 4397.714148580332, 4413.205622987829, 3922.646961773454, 3937.350337194388, 3960.128771670228, 3975.3043217436834, 3994.960050399517, 5458.181272809111, 5504.2294078829, 5506.0, 5509.972450137804, 5511.311086114236, 5675.404082571353, 5823.923932422127, 5861.443162899518, 5886.764529418861, 5928.762849486058, 5606.0, 5613.045782217033, 5650.039493286151, 5657.4059723728415, 5663.207150998864, 5032.79868805251, 5070.112730272188, 5076.593872490192, 5079.2381142828135, 5123.154679477919, 5852.824415267549, 5973.701314934236, 6123.8162855114415, 6133.877322453526, 6134.38656134383, 5835.649861008353, 5871.7651293947765, 5887.381824908323, 5934.0, 5935.465808077293, 5976.641401515923, 6013.609115407485, 6022.909656355246, 6034.185384973148, 6039.033754599243, 5602.9719851402115, 5734.942650573441, 5764.933487304862, 5777.508911742549, 5779.2486976694445, 5800.303963524404, 5926.4666180044005, 5932.911006334822, 5970.014947533608, 5986.281646202484, 5745.626534275259, 5775.104858746901, 5783.508401785895, 5783.768649253982, 5792.362840087502, 5564.944350556442, 5669.0, 5761.5102716269585, 5775.162601422854, 5778.27771528559, 5648.802291919763, 5727.169560413633, 5733.082706766897, 5763.423657634203, 5778.566607504538, 5815.854603634773, 5822.126680997884, 5853.2976042875125, 5870.031444811559, 5877.4122587740885, 5376.946230537645, 5477.315335583052, 5655.547556195412, 5664.0937450007805, 5666.886662266814], "value": 5392.857922498147, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.9307683078031, 596.8239369386031, 602.358488210056, 611.2817439508577, 628.691940948935, 31734.523982140257, 45741.0, 46628.3005754914, 46639.30041049387, 46666.30000549994, 47127.76436117823, 47380.52619473804, 47412.525874741244, 47574.28638570424, 47597.76201118998, 8988.730338089868, 9086.45481271126, 9137.314701397421, 9142.314326425512, 9145.268378529701, 8995.550222488882, 9068.229200517964, 9082.636694532212, 9106.0, 9106.590203440861, 9105.544722763836, 9149.085091490864, 9149.54252287383, 9149.862752058725, 9156.816863662756, 9118.544072796367, 9147.588358523883, 9153.359264851462, 9155.954220228856, 9173.31200159984, 9043.457392556467, 9107.544622768835, 9111.863322050174, 9114.453132811987, 9117.31620128493, 43956.340654889886, 44174.779126104164, 44222.0, 44311.0, 44484.0, 3270.1824543864036, 3316.502524621302, 3444.3111377724304, 3501.0022143688925, 4149.771762553059, 33192.336153276796, 33220.50169247463, 33806.309684515676, 33835.66164338374, 33916.0, 4428.202923473774, 4584.12901548708, 4637.281221410687, 4698.365720627706, 4703.9294410583525, 356280.0, 360324.0, 360651.0, 360703.0, 361279.0, 60590.697046515364, 61175.0, 61223.69388153031, 61251.69374153101, 61281.0, 64435.0, 64505.67747161234, 64753.35246647568, 64952.15430611268, 65009.72465963668, 16869.24088416024, 16997.745033824256, 17049.573760655836, 17064.48806535806, 17094.743578846326, 12849.229046257255, 12877.678058048436, 12910.67723306906, 12911.160774549624, 12916.935415322863, 13905.235212063433, 15648.765268520981, 18125.63748725018, 18344.449666510027, 18470.168842402123, 6477.0, 6570.733097081499, 6680.732770689216, 6782.134912898893, 6982.162140543167, 975.2977855943709, 1046.283295942282, 1058.2750815691277, 1096.5812177094438, 1161.1697636190165, 33351.49972750411, 33404.164895877315, 33747.466649404334, 33812.0, 33827.83086084554, 1064.1752641702742, 1096.2874131814226, 1140.9714757131137, 1168.4741866160261, 1169.1523645357217, 17128.40050598223, 17387.65224695499, 17435.30258789634, 17512.0, 17608.471745847644, 514884.0, 516683.0, 519170.0, 519382.0, 521981.0, 61645.38354616486, 61835.0, 61837.0, 61883.0, 61971.0, 1242.937853107341, 1345.5021641992525, 1349.689571398584, 1382.9585112446644, 1383.3290853936035, 32279.51580726244, 34479.827600861834, 34805.825970869984, 35189.47215791714, 35277.0, 52558.47441525536, 53098.0, 53147.4685253158, 53217.73391133019, 53263.20105198347, 409288.0, 411020.0, 411371.0, 411887.0, 413793.0, 36810.8159459201, 43027.56972430235, 43736.56263437325, 44435.55564444314, 46355.53644463647, 49870.0, 50272.245916312015, 50361.74819125881, 50510.0, 50686.23970640369, 42529.36205956975, 43071.13857722891, 43529.78235108931, 44830.20679172927, 45029.54970450253, 41709.79145104255, 42166.789166053975, 42427.575724242364, 42874.1425171501, 44140.55859441365, 42395.1520969585, 43238.0, 43608.34587481254, 43654.56345436505, 43711.0, 35975.460368093976, 37733.0, 38289.649951942294, 38771.806140969114, 38828.41757373585, 28969.130926072252, 28987.855060725404, 28996.710032899402, 29321.12036638904, 29386.265343366587], "value": 20826.541085038152, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.8797184900831, 388.27199001871486, 390.60939060939063, 392.13925433673063, 408.38130232697466, 18297.0, 20110.9944502775, 20218.09018594163, 20249.088791004404, 20269.290574829847, 18800.905995470002, 19532.90233548834, 19944.401667949947, 20024.0, 20150.0, 5647.181158731982, 5754.683492407916, 5876.206712093876, 5886.264216972879, 5967.612105213169, 5821.767129314822, 5839.8540036499, 5885.705714714269, 5904.350521442638, 5913.201717768111, 5663.2637757091525, 5848.152017957394, 5852.297724273093, 5879.911801322984, 5885.7351419186025, 5845.707714614274, 5889.175515427822, 5889.79385721502, 5891.587588868781, 5893.8821223575505, 6866.107406037183, 7178.0, 7203.963980180066, 7204.567725936408, 7340.082489688789, 25267.747322526724, 25752.87123564389, 26021.609675854877, 27427.725722742718, 27458.5881211782, 2417.16607770319, 2469.0123950419975, 2517.0309430869065, 2783.2346104821136, 2786.9303267418477, 15725.685486290324, 16403.753943690856, 16670.749938750927, 16896.493105206864, 17094.487165385057, 2004.288477590449, 2138.689889965962, 2147.4094623978376, 2151.8386121040985, 2181.2692747929464, 98649.50675246649, 100767.49616251946, 101341.0, 101366.10249013008, 102426.99999999926, 18020.459386218434, 18383.448496545127, 18695.439136825917, 18974.33589824377, 20221.29225477101, 32781.0, 33048.0, 33099.0, 33116.3376732464, 33189.668103318654, 9912.900870991341, 9923.255755818342, 10026.749331266778, 10107.7473063174, 10440.216983726099, 8170.918290817016, 8222.13667564909, 8321.500709957421, 8399.62201700925, 8770.254528365032, 8737.946582553095, 9065.365424420228, 9095.0, 9268.304877134242, 9286.257099432165, 3092.9381412371627, 3328.7667820758616, 3575.4636804479537, 3587.8026708531283, 3642.7450078494253, 874.3092956564243, 970.8203982263162, 978.4765150644371, 980.352967041749, 1059.1980640874358, 19784.60430791376, 20208.393748187576, 20279.797202027792, 20302.492437689176, 20350.491237719176, 991.2763682511807, 1004.6584161385164, 1020.8928062553473, 1024.2983556263982, 1024.779672370444, 10617.256792024484, 10858.511366988505, 11023.503942322615, 11096.056835169102, 11119.666410007712, 480410.0, 501690.407027617, 511735.0, 528467.0, 530123.0, 81044.18955810367, 93912.53043734875, 96163.0, 98300.0, 98363.50818245862, 1006.3659894266565, 1039.60495011895, 1058.1640504001907, 1070.2829104500004, 1079.9136069114452, 17881.642367152843, 18274.90862545679, 18602.44192674222, 18723.25106995705, 18961.525961851064, 42185.789071054445, 43548.56451435572, 44110.11779764451, 44512.844914326604, 45261.77369113133, 58783.0, 59830.40169598248, 60315.095273570056, 62083.37916620957, 64779.67610161919, 8334.458260213067, 8436.11420800807, 8452.239298463168, 8498.575071246289, 8512.191341822518, 12594.24434533931, 13152.802707959198, 13207.60377188686, 13479.86520134786, 13849.0, 11709.0, 11920.403979801149, 11985.760284794429, 11996.16026878145, 12000.63998080024, 11375.943120284677, 11420.94289528547, 11779.587714429954, 12126.24667346496, 12168.75662486763, 11558.364289964133, 12189.512419503475, 12245.510179592717, 12399.690007749878, 12826.807597886225, 7192.640367981476, 7356.374708149867, 7454.6645400956, 7813.0, 8589.570521473775, 9951.353162044445, 10699.0, 11272.887271127183, 11306.378149201544, 12146.878531214576], "value": 10432.257752382659, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1280.1806843620082, 1313.0808434096134, 1410.6050305914343, 1434.0, 1834.7064469684854, 8566.143385661439, 8905.465672059672, 8922.152395522431, 9138.451692898414, 9145.405548639334, 9036.72889813305, 9100.40847344922, 9115.453072815635, 9127.452352858832, 9140.908590914089, 2321.5472982768365, 2354.564405584964, 2354.9529009419803, 2356.422676444265, 2357.1985524921524, 2294.2314324701247, 2335.4978679583887, 2344.15610380263, 2348.7886090251873, 2349.8120150388013, 2312.6531020346915, 2324.5815753164425, 2325.186184835311, 2386.737458879522, 2387.0929046962215, 2220.7779222077825, 2245.2253972379503, 2260.5026894083276, 2260.626996545568, 2305.227748704186, 2266.8073213776847, 2272.4659704969317, 2278.2254033628647, 2278.7721227877246, 2278.8290878184202, 8942.150495702897, 8995.370324077317, 9017.774555636162, 9083.500407477586, 9095.590698418588, 5284.471552844685, 5387.919181212285, 5418.051840927822, 5442.2380866678695, 5458.863528411822, 3481.9477707834403, 3482.512448257246, 3554.2003049313957, 3554.3246783111385, 3573.749837511375, 1133.257716195893, 1135.409587014756, 1164.6913567904512, 1168.1764356128908, 1168.9181757276995, 12187.684339975465, 12233.632991010283, 12250.264984100893, 12251.69370765729, 12262.693432664255, 4315.28797748368, 4521.977390113028, 4570.040291538814, 4610.216263235258, 4622.306654001926, 9722.27082968766, 9743.366681165702, 9744.805103897883, 9773.902260977442, 9795.06946840049, 5223.817166399233, 5245.26566280721, 5245.868853278698, 5250.579953603703, 5267.815626453133, 4002.599740025974, 4255.340422234559, 4342.1532801103485, 4383.342498625232, 4901.1913034349645, 3693.7045036397562, 3791.5829258781514, 3829.5596006459054, 3838.673712734393, 3867.57456679765, 1110.439228189759, 1116.642674344202, 1117.7261570915177, 1127.6617014895498, 1135.1089394825024, 990.6582229130938, 997.8403455447094, 999.8050380175796, 1000.0999100809184, 1002.6190047781942, 6055.364186760415, 6162.815115546541, 6257.5932564383165, 6295.055741638791, 6515.827151112845, 975.9951200243953, 987.5901500877055, 1000.4097582426404, 1011.5346940407347, 1047.5233768635405, 3966.147278335172, 4113.095119073799, 4137.97931010343, 4223.767692776927, 4265.019045619525, 11186.216964812385, 11623.76752464946, 11627.53489860396, 11656.41717914101, 11675.532978680758, 11397.0, 11397.772044559062, 11430.1998860079, 11450.713732156597, 11462.484188211383, 8096.230858068474, 8425.705100321582, 8450.366222533334, 8600.0, 8604.182602652738, 5707.800226992035, 5742.0, 5884.882302353843, 5970.850728731816, 6199.636080062379, 9868.506574671093, 10026.64906728261, 10121.291509594257, 10152.644657436955, 10166.390016599027, 9438.433693978383, 10119.342242754197, 10208.336458130198, 10467.842982355423, 10603.469826508797, 8290.626921788655, 8497.872531911902, 8558.657653693783, 8574.056853746208, 8737.868931965899, 8680.435771674822, 8685.826283474169, 8891.0, 8897.73306800797, 8928.15182557656, 7712.691492340468, 7841.843163136591, 7904.446688731734, 7966.083900351534, 8080.151584083822, 7579.090509138939, 7685.23147685227, 7727.832052169922, 7821.061472623322, 7868.685252589832, 7142.142942846892, 7147.070880785464, 7213.1704853942465, 7222.241664625139, 7229.060222171084, 5004.199328107486, 5012.598992080698, 5075.289459475603, 5137.126688463045, 5191.506806853343, 5129.7691603877165, 5210.739463026909, 5287.206918962186, 5343.465653434625, 5350.063738845764], "value": 4673.137668985856, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.68067189115476, 314.3289077818856, 316.6216371436134, 322.18165858718856, 325.7752151015799, 5315.495027972342, 5360.812371566996, 5395.622306438546, 5399.514043736063, 5418.783248670054, 5041.6974981501135, 5074.79700811968, 5158.2004789257635, 5210.062188806013, 5317.627766056377, 4093.89765255868, 4105.609967053125, 4112.527059388179, 4114.650254728352, 4139.3583994480905, 4146.419501269825, 4159.417681524589, 4162.458880345551, 4167.437395951538, 4172.457580514529, 3892.3966785148345, 3960.465337179487, 4102.323116685743, 4112.856050038249, 4142.71001029928, 4077.4913282085818, 4089.8752842968124, 4108.445359876423, 4118.4440100586335, 4126.628603425691, 3615.94576081359, 3624.818759062063, 3625.347437461256, 3629.383004889175, 3630.3102410541915, 5032.6980381177245, 5050.696958182521, 5126.871828204287, 5184.092783762864, 5207.6354655174155, 4588.632909367243, 4648.256278995378, 4654.627629789608, 4655.464621568495, 4711.693739906895, 5438.0, 5443.918341224885, 5452.91820622691, 5487.780488780444, 5493.807716729935, 3670.761400508985, 3861.806909654506, 3918.8236529356245, 3939.5075615548058, 3962.5244970603712, 5616.410276920906, 5659.641685995372, 5670.087639528712, 5694.971525142348, 5696.401877802854, 3934.940975885364, 3946.8421263149153, 3955.881323560298, 3956.9010774730864, 3958.921566177722, 3904.6485816276104, 4023.2154729827403, 4026.375911733627, 4034.7780872052317, 4036.788963310995, 4535.2765948939195, 4544.909101818012, 4568.565986231302, 4571.26859702446, 4622.838200662961, 5471.4528547145765, 5481.150421684645, 5484.862878427992, 5507.915567282334, 5554.166874968787, 1330.9866901331056, 1353.3706826325806, 1368.6313686313686, 1368.6646771541036, 1369.9589012329645, 889.1286539191628, 902.273669695888, 903.70629545398, 918.9540522973824, 920.0845159066771, 4758.262469317261, 4769.448345135336, 4817.723303324622, 4831.444383895827, 4847.466778654345, 5032.446430892599, 5052.0, 5054.469280725544, 5055.747212639353, 5088.262201980692, 4580.633549316047, 4770.711907784878, 4800.783964721596, 4802.855914322576, 4838.709677419366, 2673.0109859351774, 2675.518406686776, 2677.799165062628, 2701.1086341507257, 2702.7432393922545, 5176.651612645396, 5191.922121168185, 5208.114620514521, 5222.97388513055, 5238.611767881514, 4918.606511479073, 5102.571280041594, 5121.615878809104, 5136.2552429898205, 5159.25190847325, 5062.594992400599, 5067.695938243717, 5093.770780314969, 5162.767675454614, 5185.792568297225, 4705.929411058768, 4969.47820478859, 5011.84964451067, 5025.522575355335, 5067.670601411044, 4937.703737775601, 4961.677490963076, 4968.776405061709, 5000.64995450315, 5040.4455509894615, 5122.462141475092, 5160.845174644766, 5163.432022477449, 5186.611004174627, 5230.790768369223, 3704.4258139988347, 3709.258148370283, 3709.3879509880567, 3725.8323375448713, 3732.384156614237, 5244.7902083916215, 5412.107002344569, 5529.4470552945995, 5638.872225554824, 5658.85852853682, 5540.529055030368, 5541.5843811715085, 5543.1984605004345, 5551.417101204315, 5563.944360556343, 5507.972460137674, 5539.695316757617, 5539.811774349029, 5560.749766260609, 5597.80407685729, 5150.871228219175, 5409.864753381197, 5484.8354549363585, 5509.283793106871, 5513.807016754395, 5563.219769673666, 5607.934247670406, 5613.354464236665, 5618.325800903917, 5636.0, 5230.63385563007, 5451.3458384994055, 5519.613627046068, 5520.365158006881, 5544.306961629796], "value": 4045.4600487825196, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.8089496730848, 747.5290566942826, 752.5447104501776, 775.1395950494946, 784.0434669702961, 15358.155301458415, 16238.0, 16686.582835429104, 16783.0, 16829.0, 15651.686966260697, 15754.606134846634, 15838.84161158388, 15841.0, 15852.524424267262, 807.6648191000743, 825.5500752090126, 825.694493037577, 826.1078035721406, 826.1696994520498, 673.8315421144714, 809.2797410304834, 832.305025303869, 833.1585099049943, 838.5681374092347, 871.6556960000796, 872.8123453457505, 874.5758307220992, 874.868769684546, 875.1860769484377, 867.4361664917853, 872.4111224923151, 872.6901949807822, 872.9258013068896, 873.7946582553095, 508.8829569199105, 527.4093015822303, 528.7541293298597, 611.1108337369117, 624.881272558217, 14995.32521036556, 15606.687866242612, 15611.921940390226, 15617.297221625055, 15624.531264062096, 14174.220417877013, 14362.425502979868, 14783.408663653441, 14904.329305181185, 15043.548693539211, 12949.417276222499, 13046.934765326112, 13153.21080735159, 13364.799528007088, 13545.796813047813, 4587.977060114678, 4593.517680643517, 4728.692634978715, 4825.276208568707, 4877.292792545097, 74136.62931685307, 74737.25262747357, 79767.0, 81480.59259703723, 81587.59206203932, 11586.362750048827, 11655.533778648929, 11811.468483918245, 12060.396980151132, 12086.939565302117, 23322.533549328917, 23478.64782028271, 23661.526769464515, 23676.171334003226, 23691.88154059253, 8809.559522023874, 8849.60176792046, 8956.417832840845, 9117.042710515432, 9158.221551168226, 4826.903461930812, 4828.251620998681, 4847.224444088929, 4906.705597664151, 5085.0338435697095, 3681.4293784463453, 3787.3372159872465, 3819.923601527954, 3820.541535015816, 3825.2540754552597, 646.179352222684, 648.3451713769033, 650.5316172355896, 654.312971380049, 675.5845155229498, 626.996865015672, 656.1568384625733, 688.7795905310353, 694.4201591671011, 717.1716667249335, 9757.658481953098, 10007.299489035697, 10197.74505637365, 10303.3817970922, 10367.896321036844, 549.8652830056661, 626.9374453541079, 656.3272645538361, 698.2354322017484, 705.3052743048094, 4406.49325327585, 4419.64642828573, 4427.8228870844805, 4464.374987501752, 4507.346434766941, 70301.0, 70517.6474117626, 99588.50205748926, 103824.48087759664, 106033.46983265188, 100820.49589752004, 101086.0, 102118.0, 102408.48795756123, 103892.48053759683, 87919.56040219885, 88080.0, 89192.5540372294, 90157.94289256999, 90414.0, 9927.805033704315, 10168.237382196217, 10268.58925642966, 10295.53670084863, 10380.221483388927, 48121.0, 51906.0, 53795.46204537905, 54095.45904540904, 55472.167917482075, 51092.0, 51689.741551292005, 69447.65702891327, 70070.0, 70733.0, 13866.098703584235, 13964.79052814229, 14038.649033773829, 14189.29053547298, 14244.857551424353, 14714.264286785832, 15250.466233681767, 15357.23213839326, 16083.597910052342, 17001.06494142834, 14125.858741412454, 14893.404263829325, 15175.924120379328, 15274.694506110038, 15789.447369342019, 14185.219812909976, 14231.50189743354, 14576.781348279572, 14800.259987000822, 14828.62928426798, 14198.432062717375, 14220.146791192561, 14331.396073931888, 14489.058211216237, 14809.037412568148, 10910.672679819618, 11168.21822472419, 11211.271267367594, 11366.943165284121, 11690.18168728215, 12208.572699955459, 12249.877501224873, 12376.81434778497, 12613.369331533204, 12648.304343261208], "value": 7740.621482689013, "unit": "score"}], "name": "dromaeo_css", "value": 7453.657385569783, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 111.0, 113.0, 111.0, 111.0, 111.0, 112.0, 111.0, 110.0, 112.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 123.0, 122.0, 181.0, 120.0, 119.0, 149.0, 117.0, 180.0, 118.0], "value": 137.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 159.0, 159.0, 160.0, 160.0, 160.0, 160.0, 162.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 125.0, 75.0, 76.0, 74.0, 73.0, 97.0, 76.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 119.0, 84.0, 84.0, 84.0, 119.0, 85.0, 85.0, 120.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 51.0, 53.0, 54.0, 52.0, 53.0, 52.0, 51.0, 52.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 71.0, 62.0, 63.0, 62.0, 62.0, 62.0, 63.0, 62.0], "value": 63.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 76.0, 76.0, 77.0, 86.0, 75.0, 76.0, 75.0, 80.0], "value": 77.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 120.0, 131.0, 120.0, 124.0, 119.0, 120.0, 120.0, 165.0, 131.0], "value": 127.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 157.0, 155.0, 161.0, 156.0, 154.0, 156.0, 155.0, 156.0, 158.0], "value": 156.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 64.0, 63.0, 63.0, 68.0, 62.0, 63.0, 63.0, 61.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1482.3999999999999, "alertThreshold": 2.0}]} 22:33:26 INFO - Return code: 0 22:33:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:33:26 INFO - # TBPL SUCCESS # 22:33:26 INFO - Running post-action listener: _resource_record_post_action 22:33:26 INFO - [mozharness: 2016-07-26 05:33:26.318790Z] Finished run-tests step (success) 22:33:26 INFO - Running post-run listener: _resource_record_post_run 22:33:26 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 8192; Write bytes: 565432320; Read time: 324; Write time: 1315228 22:33:26 INFO - TinderboxPrint: CPU usage
13.2% 22:33:26 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 324 22:33:26 INFO - TinderboxPrint: I/O write bytes / time
565,432,320 / 1,315,228 22:33:26 INFO - TinderboxPrint: CPU idle
8,282.4 (86.8%) 22:33:26 INFO - TinderboxPrint: CPU user
1,198.1 (12.6%) 22:33:26 INFO - TinderboxPrint: Swap in / out
0 / 0 22:33:26 INFO - install - Wall time: 19s; CPU: 18.0%; Read bytes: 4096; Write bytes: 271097856; Read time: 244; Write time: 872028 22:33:26 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 294334464; Read time: 80; Write time: 443200 22:33:26 INFO - Running post-run listener: _upload_blobber_files 22:33:26 INFO - Blob upload gear active. 22:33:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:33:26 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 22:33:26 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:33:26 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:33:27 INFO - (blobuploader) - INFO - Open directory for files ... 22:33:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:33:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:33:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:33:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:33:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:33:35 INFO - (blobuploader) - INFO - Done attempting. 22:33:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:33:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:33:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:33:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:33:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:33:36 INFO - (blobuploader) - INFO - Done attempting. 22:33:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:33:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:33:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:33:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:33:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:33:37 INFO - (blobuploader) - INFO - Done attempting. 22:33:37 INFO - (blobuploader) - INFO - Iteration through files over. 22:33:37 INFO - Return code: 0 22:33:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:33:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:33:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a6e52357e48168fbdfaa906fc7604549cea9f5b3fcaa9a09565849d4c33e515efe3a9799b22c8c435e4d1b5e8bd84fd255a8a0d21d51d42a41584a107cc54968", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9ff486a15a3e1a883353840b2ac838c4ffb92a614aa435b35d34a0751e02c901b806ebf44c834d0327f47c375e11659966a38eea9aaf27d088954837a0bc64a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2c38be900dd91150ca87d2a0e3b048439a75e81f3cc2703c45aee5f23036c5ccf984897f8666bbc082e9fb562ecbfc05fd417e9531c2ea0899597337d393b447"} 22:33:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:33:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:33:37 INFO - Contents: 22:33:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a6e52357e48168fbdfaa906fc7604549cea9f5b3fcaa9a09565849d4c33e515efe3a9799b22c8c435e4d1b5e8bd84fd255a8a0d21d51d42a41584a107cc54968", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9ff486a15a3e1a883353840b2ac838c4ffb92a614aa435b35d34a0751e02c901b806ebf44c834d0327f47c375e11659966a38eea9aaf27d088954837a0bc64a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2c38be900dd91150ca87d2a0e3b048439a75e81f3cc2703c45aee5f23036c5ccf984897f8666bbc082e9fb562ecbfc05fd417e9531c2ea0899597337d393b447"} 22:33:37 INFO - Running post-run listener: copy_logs_to_upload_dir 22:33:37 INFO - Copying logs to upload dir... 22:33:37 INFO - mkdir: /builds/slave/test/build/upload/logs 22:33:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.127950 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-25 22:33:36.919837) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 22:33:36.923708) ========= 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=745a4373a2a16df560f216e000000264-1469509733.423385-447909566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a6e52357e48168fbdfaa906fc7604549cea9f5b3fcaa9a09565849d4c33e515efe3a9799b22c8c435e4d1b5e8bd84fd255a8a0d21d51d42a41584a107cc54968", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9ff486a15a3e1a883353840b2ac838c4ffb92a614aa435b35d34a0751e02c901b806ebf44c834d0327f47c375e11659966a38eea9aaf27d088954837a0bc64a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2c38be900dd91150ca87d2a0e3b048439a75e81f3cc2703c45aee5f23036c5ccf984897f8666bbc082e9fb562ecbfc05fd417e9531c2ea0899597337d393b447"} build_url:https://queue.taskcluster.net/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011221 build_url: 'https://queue.taskcluster.net/v1/task/ENKMMFkITMut56AH1Rjlwg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a6e52357e48168fbdfaa906fc7604549cea9f5b3fcaa9a09565849d4c33e515efe3a9799b22c8c435e4d1b5e8bd84fd255a8a0d21d51d42a41584a107cc54968", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9ff486a15a3e1a883353840b2ac838c4ffb92a614aa435b35d34a0751e02c901b806ebf44c834d0327f47c375e11659966a38eea9aaf27d088954837a0bc64a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2c38be900dd91150ca87d2a0e3b048439a75e81f3cc2703c45aee5f23036c5ccf984897f8666bbc082e9fb562ecbfc05fd417e9531c2ea0899597337d393b447"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 22:33:36.977268) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 22:33:36.977639) ========= 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=745a4373a2a16df560f216e000000264-1469509733.423385-447909566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004440 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 22:33:37.027196) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 22:33:37.027518) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 22:33:37.027800) ========= ========= Total master_lag: 0.22 =========