builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-069 starttime: 1469684838.01 results: success (0) buildid: 20160727221426 builduid: f29a0c13c29c4946b73b94e4663900a9 revision: 90b49d2b01334dd107bcbea8f0790dc518344fe2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 22:47:18.009066) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 22:47:18.010076) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 22:47:18.010791) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 22:47:18.246244) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:47:18.246551) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-27 22:47:18-- 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% 95.8M=0s 2016-07-27 22:47:18 (95.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.130024 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:47:18.396319) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:47:18.397083) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014982 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:47:18.448771) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:47:18.449139) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 90b49d2b01334dd107bcbea8f0790dc518344fe2 --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 90b49d2b01334dd107bcbea8f0790dc518344fe2 --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=05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-27 22:47:18,611 truncating revision to first 12 chars 2016-07-27 22:47:18,611 Setting DEBUG logging. 2016-07-27 22:47:18,611 attempt 1/10 2016-07-27 22:47:18,611 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/90b49d2b0133?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-27 22:47:18,919 unpacking tar archive at: fx-team-90b49d2b0133/testing/mozharness/ program finished with exit code 0 elapsedTime=0.695809 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:47:19.160785) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 22:47:19.161172) ========= script_repo_revision: 90b49d2b01334dd107bcbea8f0790dc518344fe2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 22:47:19.161636) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 22:47:19.161963) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 22:47:19.172687) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-07-27 22:47:19.173011) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:47:19 INFO - MultiFileLogger online at 20160727 22:47:19 in /builds/slave/test 22:47:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 22:47:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:47:19 INFO - {'append_to_log': False, 22:47:19 INFO - 'base_work_dir': '/builds/slave/test', 22:47:19 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 22:47:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:47:19 INFO - 'branch': 'Fx-Team-Non-PGO', 22:47:19 INFO - 'buildbot_json_path': 'buildprops.json', 22:47:19 INFO - 'config_files': ('talos/linux_config.py',), 22:47:19 INFO - 'default_actions': ('clobber', 22:47:19 INFO - 'read-buildbot-config', 22:47:19 INFO - 'download-and-extract', 22:47:19 INFO - 'populate-webroot', 22:47:19 INFO - 'create-virtualenv', 22:47:19 INFO - 'install', 22:47:19 INFO - 'run-tests'), 22:47:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:47:19 INFO - 'download_minidump_stackwalk': True, 22:47:19 INFO - 'download_symbols': 'ondemand', 22:47:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:47:19 INFO - 'tooltool.py': '/tools/tooltool.py', 22:47:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:47:19 INFO - '/tools/misc-python/virtualenv.py')}, 22:47:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:47:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:47:19 INFO - 'installer_path': 'installer.exe', 22:47:19 INFO - 'log_level': 'info', 22:47:19 INFO - 'log_name': 'talos', 22:47:19 INFO - 'log_to_console': True, 22:47:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:47:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:47:19 INFO - 'opt_config_files': (), 22:47:19 INFO - 'pip_index': False, 22:47:19 INFO - 'sps_profile': False, 22:47:19 INFO - 'sps_profile_interval': 0, 22:47:19 INFO - 'suite': 'dromaeojs', 22:47:19 INFO - 'system_bits': '32', 22:47:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:47:19 INFO - 'title': 'talos-linux64-ix-069', 22:47:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:47:19 INFO - 'use_talos_json': True, 22:47:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:47:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:47:19 INFO - 'work_dir': 'build'} 22:47:19 INFO - [mozharness: 2016-07-28 05:47:19.429765Z] Running clobber step. 22:47:19 INFO - Running pre-action listener: _resource_record_pre_action 22:47:19 INFO - Running main action method: clobber 22:47:19 INFO - rmtree: /builds/slave/test/build 22:47:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:47:19 INFO - Running post-action listener: _resource_record_post_action 22:47:19 INFO - [mozharness: 2016-07-28 05:47:19.900580Z] Finished clobber step (success) 22:47:19 INFO - [mozharness: 2016-07-28 05:47:19.900681Z] Running read-buildbot-config step. 22:47:19 INFO - Running pre-action listener: _resource_record_pre_action 22:47:19 INFO - Running main action method: read_buildbot_config 22:47:19 INFO - Using buildbot properties: 22:47:19 INFO - { 22:47:19 INFO - "project": "", 22:47:19 INFO - "product": "firefox", 22:47:19 INFO - "script_repo_revision": "production", 22:47:19 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 22:47:19 INFO - "repository": "", 22:47:19 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 22:47:19 INFO - "buildid": "20160727221426", 22:47:19 INFO - "pgo_build": "False", 22:47:19 INFO - "basedir": "/builds/slave/test", 22:47:19 INFO - "buildnumber": 1833, 22:47:19 INFO - "slavename": "talos-linux64-ix-069", 22:47:19 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:47:19 INFO - "platform": "ubuntu64_hw", 22:47:19 INFO - "scheduler": "tests-fx-team-linux64-talos", 22:47:19 INFO - "branch": "fx-team", 22:47:19 INFO - "repo_path": "integration/fx-team", 22:47:19 INFO - "revision": "90b49d2b01334dd107bcbea8f0790dc518344fe2", 22:47:19 INFO - "stage_platform": "linux64", 22:47:19 INFO - "builduid": "f29a0c13c29c4946b73b94e4663900a9", 22:47:19 INFO - "slavebuilddir": "test" 22:47:19 INFO - } 22:47:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 22:47:19 INFO - Running post-action listener: _resource_record_post_action 22:47:19 INFO - [mozharness: 2016-07-28 05:47:19.903400Z] Finished read-buildbot-config step (success) 22:47:19 INFO - [mozharness: 2016-07-28 05:47:19.903489Z] Running download-and-extract step. 22:47:19 INFO - Running pre-action listener: _resource_record_pre_action 22:47:19 INFO - Running main action method: download_and_extract 22:47:19 INFO - mkdir: /builds/slave/test/build/tests 22:47: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:47:19 INFO - https://queue.taskcluster.net/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:47:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:47:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:47:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/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:47:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/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:47:23 INFO - Downloaded 1361 bytes. 22:47:23 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:47:23 INFO - Using the following test package requirements: 22:47:23 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:47:23 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:47:23 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:47:23 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:23 INFO - u'jsshell-linux-x86_64.zip'], 22:47:23 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:47:23 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:47:23 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:47:23 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:47:23 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:47:23 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:47:23 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:47: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:47:23 INFO - https://queue.taskcluster.net/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:47:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:47:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:47:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/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:47:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/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:47:29 INFO - Downloaded 22746624 bytes. 22:47:29 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:47:29 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:47:30 INFO - Return code: 0 22:47:30 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:47: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:47:30 INFO - https://queue.taskcluster.net/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:47:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:47:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:47:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/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:47:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/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:47:32 INFO - Downloaded 22746624 bytes. 22:47:32 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:47:32 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:47:34 INFO - Return code: 0 22:47:34 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:47:34 INFO - https://queue.taskcluster.net/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:47:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:47:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:47:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/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:47:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/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:47:35 INFO - Downloaded 14413725 bytes. 22:47:35 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:47:35 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:47:35 INFO - Return code: 0 22:47:35 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:47:35 INFO - https://queue.taskcluster.net/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:47:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:47:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:47:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/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:47:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/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:47:37 INFO - Downloaded 57163030 bytes. 22:47:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:47:37 INFO - mkdir: /builds/slave/test/properties 22:47:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:47:37 INFO - Writing to file /builds/slave/test/properties/build_url 22:47:37 INFO - Contents: 22:47:37 INFO - build_url:https://queue.taskcluster.net/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:47:37 INFO - Running post-action listener: _resource_record_post_action 22:47:37 INFO - Running post-action listener: set_extra_try_arguments 22:47:37 INFO - [mozharness: 2016-07-28 05:47:37.761796Z] Finished download-and-extract step (success) 22:47:37 INFO - [mozharness: 2016-07-28 05:47:37.762030Z] Running populate-webroot step. 22:47:37 INFO - Running pre-action listener: _resource_record_pre_action 22:47:37 INFO - Running main action method: populate_webroot 22:47:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:47:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:47:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:47:37 INFO - '--tppagecycles', 22:47:37 INFO - '7'], 22:47:37 INFO - 'tests': ['tsvgm']}}, 22:47:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:47:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:47:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:47:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:47:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:37 INFO - 'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:47:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:47:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:37 INFO - 'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['damp', 'tps']}, 22:47:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:37 INFO - 'tests': ['damp', 'tps']}, 22:47:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['dromaeo_dom']}, 22:47:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:47:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['basic_compositor_video']}, 22:47:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:47:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['a11yr', 22:47:37 INFO - 'ts_paint', 22:47:37 INFO - 'tpaint', 22:47:37 INFO - 'sessionrestore', 22:47:37 INFO - 'sessionrestore_no_auto_restore', 22:47:37 INFO - 'tabpaint']}, 22:47:37 INFO - 'other-e10s': {'tests': ['a11yr', 22:47:37 INFO - 'ts_paint', 22:47:37 INFO - 'tpaint', 22:47:37 INFO - 'sessionrestore', 22:47:37 INFO - 'sessionrestore_no_auto_restore', 22:47:37 INFO - 'tabpaint']}, 22:47:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:47:37 INFO - 'ts_paint', 22:47:37 INFO - 'tpaint', 22:47:37 INFO - 'sessionrestore', 22:47:37 INFO - 'sessionrestore_no_auto_restore', 22:47:37 INFO - 'tabpaint']}, 22:47:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:47:37 INFO - 'ts_paint', 22:47:37 INFO - 'tpaint', 22:47:37 INFO - 'sessionrestore', 22:47:37 INFO - 'sessionrestore_no_auto_restore', 22:47:37 INFO - 'tabpaint']}, 22:47:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['a11yr', 22:47:37 INFO - 'ts_paint', 22:47:37 INFO - 'tpaint', 22:47:37 INFO - 'sessionrestore', 22:47:37 INFO - 'sessionrestore_no_auto_restore', 22:47:37 INFO - 'tabpaint']}, 22:47:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['a11yr', 22:47:37 INFO - 'ts_paint', 22:47:37 INFO - 'tpaint', 22:47:37 INFO - 'sessionrestore', 22:47:37 INFO - 'sessionrestore_no_auto_restore', 22:47:37 INFO - 'tabpaint']}, 22:47:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['tsvgx', 22:47:37 INFO - 'tsvgr_opacity', 22:47:37 INFO - 'tart', 22:47:37 INFO - 'tscrollx', 22:47:37 INFO - 'cart']}, 22:47:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:47:37 INFO - 'tsvgr_opacity', 22:47:37 INFO - 'tart', 22:47:37 INFO - 'tscrollx', 22:47:37 INFO - 'cart']}, 22:47:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:37 INFO - 'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['tp5o']}, 22:47:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:37 INFO - 'tests': ['tp5o']}, 22:47:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:37 INFO - 'talos_options': ['--disable-e10s', 22:47:37 INFO - '--xperf_path', 22:47:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:47:37 INFO - 'tests': ['tp5n']}, 22:47:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:37 INFO - 'talos_options': ['--xperf_path', 22:47:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:47:37 INFO - 'tests': ['tp5n']}}, 22:47:37 INFO - 'talos.zip': {'path': '', 22:47:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:47:37 INFO - Running post-action listener: _resource_record_post_action 22:47:37 INFO - [mozharness: 2016-07-28 05:47:37.777600Z] Finished populate-webroot step (success) 22:47:37 INFO - [mozharness: 2016-07-28 05:47:37.777690Z] Running create-virtualenv step. 22:47:37 INFO - Running pre-action listener: _resource_record_pre_action 22:47:37 INFO - Running main action method: create_virtualenv 22:47:37 INFO - Creating virtualenv /builds/slave/test/build/venv 22:47:37 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:47:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:47:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:47:37 INFO - Using real prefix '/usr' 22:47:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:47:38 INFO - Installing distribute.............................................................................................................................................................................................done. 22:47:40 INFO - Installing pip.................done. 22:47:40 INFO - Return code: 0 22:47:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:47:40 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:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:40 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:47:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:40 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 0xad8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd99180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd973b0>, '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 0xd982b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', '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:47:40 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:47:40 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:47:40 INFO - Using env: {'DISPLAY': ':0', 22:47:40 INFO - 'HOME': '/home/cltbld', 22:47:40 INFO - 'LANG': 'en_US.UTF-8', 22:47:40 INFO - 'LANGUAGE': 'en_US:en', 22:47:40 INFO - 'LOGNAME': 'cltbld', 22:47:40 INFO - 'MAIL': '/var/mail/cltbld', 22:47:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:40 INFO - 'MOZ_NO_REMOTE': '1', 22:47:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:40 INFO - 'NO_EM_RESTART': '1', 22:47:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:40 INFO - 'PWD': '/builds/slave/test', 22:47:40 INFO - 'SHELL': '/bin/bash', 22:47:40 INFO - 'SHLVL': '1', 22:47:40 INFO - 'TERM': 'linux', 22:47:40 INFO - 'TMOUT': '86400', 22:47:40 INFO - 'USER': 'cltbld', 22:47:40 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', 22:47:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:40 INFO - '_': '/tools/buildbot/bin/python'} 22:47:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:40 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:47:40 INFO - Cleaning up... 22:47:40 INFO - Return code: 0 22:47:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:47:40 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:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:40 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:47:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:40 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 0xad8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd99180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd973b0>, '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 0xd982b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', '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:47:40 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:47:40 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:47:40 INFO - Using env: {'DISPLAY': ':0', 22:47:40 INFO - 'HOME': '/home/cltbld', 22:47:40 INFO - 'LANG': 'en_US.UTF-8', 22:47:40 INFO - 'LANGUAGE': 'en_US:en', 22:47:40 INFO - 'LOGNAME': 'cltbld', 22:47:40 INFO - 'MAIL': '/var/mail/cltbld', 22:47:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:40 INFO - 'MOZ_NO_REMOTE': '1', 22:47:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:40 INFO - 'NO_EM_RESTART': '1', 22:47:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:40 INFO - 'PWD': '/builds/slave/test', 22:47:40 INFO - 'SHELL': '/bin/bash', 22:47:40 INFO - 'SHLVL': '1', 22:47:40 INFO - 'TERM': 'linux', 22:47:40 INFO - 'TMOUT': '86400', 22:47:40 INFO - 'USER': 'cltbld', 22:47:40 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', 22:47:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:40 INFO - '_': '/tools/buildbot/bin/python'} 22:47:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:40 INFO - Downloading/unpacking psutil>=3.1.1 22:47:40 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:47:40 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:47:40 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:47:40 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:47:40 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:47:40 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:47:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:47:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:47:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:47:42 INFO - Installing collected packages: psutil 22:47:42 INFO - Running setup.py install for psutil 22:47:42 INFO - building 'psutil._psutil_linux' extension 22:47:42 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:47:43 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:47:43 INFO - building 'psutil._psutil_posix' extension 22:47:43 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:47:43 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:47:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:47:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:47:43 INFO - Successfully installed psutil 22:47:43 INFO - Cleaning up... 22:47:43 INFO - Return code: 0 22:47:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:47:43 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:47:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:43 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:47:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:43 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 0xad8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd99180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd973b0>, '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 0xd982b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', '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:47:43 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:47:43 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:47:43 INFO - Using env: {'DISPLAY': ':0', 22:47:43 INFO - 'HOME': '/home/cltbld', 22:47:43 INFO - 'LANG': 'en_US.UTF-8', 22:47:43 INFO - 'LANGUAGE': 'en_US:en', 22:47:43 INFO - 'LOGNAME': 'cltbld', 22:47:43 INFO - 'MAIL': '/var/mail/cltbld', 22:47:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:43 INFO - 'MOZ_NO_REMOTE': '1', 22:47:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:43 INFO - 'NO_EM_RESTART': '1', 22:47:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:43 INFO - 'PWD': '/builds/slave/test', 22:47:43 INFO - 'SHELL': '/bin/bash', 22:47:43 INFO - 'SHLVL': '1', 22:47:43 INFO - 'TERM': 'linux', 22:47:43 INFO - 'TMOUT': '86400', 22:47:43 INFO - 'USER': 'cltbld', 22:47:43 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', 22:47:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:43 INFO - '_': '/tools/buildbot/bin/python'} 22:47:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:47:43 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:47:43 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:47:43 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:47:43 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:47:43 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:47:43 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:47:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:47:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:47:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:47:45 INFO - Installing collected packages: mozsystemmonitor 22:47:45 INFO - Running setup.py install for mozsystemmonitor 22:47:45 INFO - Successfully installed mozsystemmonitor 22:47:45 INFO - Cleaning up... 22:47:45 INFO - Return code: 0 22:47:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:47:45 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:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:45 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:47:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:45 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 0xad8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd99180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd973b0>, '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 0xd982b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', '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:47:45 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:47:45 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:47:45 INFO - Using env: {'DISPLAY': ':0', 22:47:45 INFO - 'HOME': '/home/cltbld', 22:47:45 INFO - 'LANG': 'en_US.UTF-8', 22:47:45 INFO - 'LANGUAGE': 'en_US:en', 22:47:45 INFO - 'LOGNAME': 'cltbld', 22:47:45 INFO - 'MAIL': '/var/mail/cltbld', 22:47:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:45 INFO - 'MOZ_NO_REMOTE': '1', 22:47:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:45 INFO - 'NO_EM_RESTART': '1', 22:47:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:45 INFO - 'PWD': '/builds/slave/test', 22:47:45 INFO - 'SHELL': '/bin/bash', 22:47:45 INFO - 'SHLVL': '1', 22:47:45 INFO - 'TERM': 'linux', 22:47:45 INFO - 'TMOUT': '86400', 22:47:45 INFO - 'USER': 'cltbld', 22:47:45 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', 22:47:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:45 INFO - '_': '/tools/buildbot/bin/python'} 22:47:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:45 INFO - Downloading/unpacking jsonschema==2.5.1 22:47:45 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:47:45 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:47:45 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:47:45 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:47:45 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:47:45 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:47:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:47:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:47:47 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:47:47 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:47:47 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:47:47 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:47:47 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:47:47 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:47:47 INFO - Downloading functools32-3.2.3-2.tar.gz 22:47:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:47:48 INFO - warning: no files found matching '*.txt' 22:47:48 INFO - no previously-included directories found matching 'build' 22:47:48 INFO - no previously-included directories found matching 'dist' 22:47:48 INFO - no previously-included directories found matching '.git*' 22:47:48 INFO - Installing collected packages: jsonschema, functools32 22:47:48 INFO - Running setup.py install for functools32 22:47:48 INFO - warning: no files found matching '*.txt' 22:47:48 INFO - no previously-included directories found matching 'build' 22:47:48 INFO - no previously-included directories found matching 'dist' 22:47:48 INFO - no previously-included directories found matching '.git*' 22:47:48 INFO - Successfully installed jsonschema functools32 22:47:48 INFO - Cleaning up... 22:47:48 INFO - Return code: 0 22:47:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:47:48 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:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:48 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:47:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:48 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 0xad8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd99180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd973b0>, '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 0xd982b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', '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:47:48 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:47:48 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:47:48 INFO - Using env: {'DISPLAY': ':0', 22:47:48 INFO - 'HOME': '/home/cltbld', 22:47:48 INFO - 'LANG': 'en_US.UTF-8', 22:47:48 INFO - 'LANGUAGE': 'en_US:en', 22:47:48 INFO - 'LOGNAME': 'cltbld', 22:47:48 INFO - 'MAIL': '/var/mail/cltbld', 22:47:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:48 INFO - 'MOZ_NO_REMOTE': '1', 22:47:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:48 INFO - 'NO_EM_RESTART': '1', 22:47:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:48 INFO - 'PWD': '/builds/slave/test', 22:47:48 INFO - 'SHELL': '/bin/bash', 22:47:48 INFO - 'SHLVL': '1', 22:47:48 INFO - 'TERM': 'linux', 22:47:48 INFO - 'TMOUT': '86400', 22:47:48 INFO - 'USER': 'cltbld', 22:47:48 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', 22:47:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:48 INFO - '_': '/tools/buildbot/bin/python'} 22:47:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:47:48 INFO - Cleaning up... 22:47:48 INFO - Return code: 0 22:47:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:47:48 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:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:48 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:47:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:48 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 0xad8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd99180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd973b0>, '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 0xd982b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', '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:47:48 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:47:48 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:47:48 INFO - Using env: {'DISPLAY': ':0', 22:47:48 INFO - 'HOME': '/home/cltbld', 22:47:48 INFO - 'LANG': 'en_US.UTF-8', 22:47:48 INFO - 'LANGUAGE': 'en_US:en', 22:47:48 INFO - 'LOGNAME': 'cltbld', 22:47:48 INFO - 'MAIL': '/var/mail/cltbld', 22:47:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:48 INFO - 'MOZ_NO_REMOTE': '1', 22:47:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:48 INFO - 'NO_EM_RESTART': '1', 22:47:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:48 INFO - 'PWD': '/builds/slave/test', 22:47:48 INFO - 'SHELL': '/bin/bash', 22:47:48 INFO - 'SHLVL': '1', 22:47:48 INFO - 'TERM': 'linux', 22:47:48 INFO - 'TMOUT': '86400', 22:47:48 INFO - 'USER': 'cltbld', 22:47:48 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', 22:47:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:48 INFO - '_': '/tools/buildbot/bin/python'} 22:47:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:48 INFO - Downloading/unpacking blobuploader==1.2.4 22:47:48 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:47:48 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:47:48 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:47:48 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:47:48 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:47:48 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:47:50 INFO - Downloading blobuploader-1.2.4.tar.gz 22:47:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:47:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:47:50 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:47:50 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:47:50 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:47:50 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:47:50 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:47:50 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:47:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:47:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:47:51 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:47:51 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:47:51 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:47:51 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:47:51 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:47:51 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:47:51 INFO - Downloading docopt-0.6.1.tar.gz 22:47:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:47:51 INFO - Installing collected packages: blobuploader, requests, docopt 22:47:51 INFO - Running setup.py install for blobuploader 22:47:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:47:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:47:51 INFO - Running setup.py install for requests 22:47:51 INFO - Running setup.py install for docopt 22:47:51 INFO - Successfully installed blobuploader requests docopt 22:47:51 INFO - Cleaning up... 22:47:51 INFO - Return code: 0 22:47:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:47:51 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:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:51 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:47:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:51 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 0xad8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd99180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd973b0>, '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 0xd982b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', '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:47:51 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:47:51 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:47:51 INFO - Using env: {'DISPLAY': ':0', 22:47:51 INFO - 'HOME': '/home/cltbld', 22:47:51 INFO - 'LANG': 'en_US.UTF-8', 22:47:51 INFO - 'LANGUAGE': 'en_US:en', 22:47:51 INFO - 'LOGNAME': 'cltbld', 22:47:51 INFO - 'MAIL': '/var/mail/cltbld', 22:47:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:51 INFO - 'MOZ_NO_REMOTE': '1', 22:47:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:51 INFO - 'NO_EM_RESTART': '1', 22:47:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:51 INFO - 'PWD': '/builds/slave/test', 22:47:51 INFO - 'SHELL': '/bin/bash', 22:47:51 INFO - 'SHLVL': '1', 22:47:51 INFO - 'TERM': 'linux', 22:47:51 INFO - 'TMOUT': '86400', 22:47:51 INFO - 'USER': 'cltbld', 22:47:51 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', 22:47:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:51 INFO - '_': '/tools/buildbot/bin/python'} 22:47:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:47:52 INFO - Running setup.py (path:/tmp/pip-uTFyTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:47:52 INFO - Running setup.py (path:/tmp/pip-SOVWpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:47:52 INFO - Running setup.py (path:/tmp/pip-picR73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:47:52 INFO - Running setup.py (path:/tmp/pip-LeEtKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:47:52 INFO - Running setup.py (path:/tmp/pip-eehbYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:47:52 INFO - Running setup.py (path:/tmp/pip-VPd2t0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:47:52 INFO - Running setup.py (path:/tmp/pip-8Fd5qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:47:52 INFO - Running setup.py (path:/tmp/pip-Ap5W9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:47:52 INFO - Running setup.py (path:/tmp/pip-YU9qgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:47:52 INFO - Running setup.py (path:/tmp/pip-I2SMyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:47:52 INFO - Running setup.py (path:/tmp/pip-lp6azm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:47:52 INFO - Running setup.py (path:/tmp/pip-Y0lXTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:47:52 INFO - Running setup.py (path:/tmp/pip-tuHE5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:47:53 INFO - Running setup.py (path:/tmp/pip-Et0gOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:47:53 INFO - Running setup.py (path:/tmp/pip-VPoozX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:47:53 INFO - Running setup.py (path:/tmp/pip-ZERdaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:47:53 INFO - Running setup.py (path:/tmp/pip-UzIl06-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:47:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:47:53 INFO - Running setup.py install for manifestparser 22:47:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:47:53 INFO - Running setup.py install for mozcrash 22:47:53 INFO - Running setup.py install for mozdebug 22:47:53 INFO - Running setup.py install for mozdevice 22:47:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:47:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:47:53 INFO - Running setup.py install for mozfile 22:47:53 INFO - Running setup.py install for mozhttpd 22:47:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:47:53 INFO - Running setup.py install for mozinfo 22:47:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:47:54 INFO - Running setup.py install for mozInstall 22:47:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:47:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:47:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:47:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:47:54 INFO - Running setup.py install for mozleak 22:47:54 INFO - Running setup.py install for mozlog 22:47:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:47:54 INFO - Running setup.py install for moznetwork 22:47:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:47:54 INFO - Running setup.py install for mozprocess 22:47:54 INFO - Running setup.py install for mozprofile 22:47:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:47:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:47:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:47:54 INFO - Running setup.py install for mozrunner 22:47:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:47:54 INFO - Running setup.py install for mozscreenshot 22:47:54 INFO - Running setup.py install for moztest 22:47:55 INFO - Running setup.py install for mozversion 22:47:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:47:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:47:55 INFO - Cleaning up... 22:47:55 INFO - Return code: 0 22:47:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:47:55 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:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:55 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:47:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:55 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 0xad8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd99180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd973b0>, '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 0xd982b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', '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:47:55 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:47:55 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:47:55 INFO - Using env: {'DISPLAY': ':0', 22:47:55 INFO - 'HOME': '/home/cltbld', 22:47:55 INFO - 'LANG': 'en_US.UTF-8', 22:47:55 INFO - 'LANGUAGE': 'en_US:en', 22:47:55 INFO - 'LOGNAME': 'cltbld', 22:47:55 INFO - 'MAIL': '/var/mail/cltbld', 22:47:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:55 INFO - 'MOZ_NO_REMOTE': '1', 22:47:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:55 INFO - 'NO_EM_RESTART': '1', 22:47:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:55 INFO - 'PWD': '/builds/slave/test', 22:47:55 INFO - 'SHELL': '/bin/bash', 22:47:55 INFO - 'SHLVL': '1', 22:47:55 INFO - 'TERM': 'linux', 22:47:55 INFO - 'TMOUT': '86400', 22:47:55 INFO - 'USER': 'cltbld', 22:47:55 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', 22:47:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:55 INFO - '_': '/tools/buildbot/bin/python'} 22:47:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:47:55 INFO - Running setup.py (path:/tmp/pip-rnKdSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:47:55 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:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:47:55 INFO - Running setup.py (path:/tmp/pip-IHFNLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:47:55 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:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:47:55 INFO - Running setup.py (path:/tmp/pip-B3b4ON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:47:55 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:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:47:55 INFO - Running setup.py (path:/tmp/pip-wnk5vL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:47:55 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:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:47:55 INFO - Running setup.py (path:/tmp/pip-6ZtCaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:47:55 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:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:47:55 INFO - Running setup.py (path:/tmp/pip-swIkFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:47:55 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:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:47:55 INFO - Running setup.py (path:/tmp/pip-ELpS58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:47:55 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:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:47:55 INFO - Running setup.py (path:/tmp/pip-egIRv8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:47:55 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:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:47:55 INFO - Running setup.py (path:/tmp/pip-0zKuFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:47:55 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:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:47:55 INFO - Running setup.py (path:/tmp/pip-4AkV7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:47:56 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:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:47:56 INFO - Running setup.py (path:/tmp/pip-UoD2yD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:47:56 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:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:47:56 INFO - Running setup.py (path:/tmp/pip-bu4bqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:47:56 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:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:47:56 INFO - Running setup.py (path:/tmp/pip-HzgLHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:47:56 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:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:47:56 INFO - Running setup.py (path:/tmp/pip-EuagcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:47:56 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:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:47:56 INFO - Running setup.py (path:/tmp/pip-6AibM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:47:56 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:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:47:56 INFO - Running setup.py (path:/tmp/pip-mUw2pB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:47:56 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:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:47:56 INFO - Running setup.py (path:/tmp/pip-1bslTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:47:56 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:47:56 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:47:56 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:47:56 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:47:56 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:47:56 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:47:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:47:56 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:47:56 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:47:56 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:47:56 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:47:56 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:47:56 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:47:58 INFO - Downloading blessings-1.6.tar.gz 22:47:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:47:58 INFO - Installing collected packages: blessings 22:47:58 INFO - Running setup.py install for blessings 22:47:58 INFO - Successfully installed blessings 22:47:58 INFO - Cleaning up... 22:47:58 INFO - Return code: 0 22:47:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:47:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:47:58 INFO - Reading from file tmpfile_stdout 22:47:58 INFO - Current package versions: 22:47:58 INFO - argparse == 1.2.1 22:47:58 INFO - blessings == 1.6 22:47:58 INFO - blobuploader == 1.2.4 22:47:58 INFO - docopt == 0.6.1 22:47:58 INFO - functools32 == 3.2.3-2 22:47:58 INFO - jsonschema == 2.5.1 22:47:58 INFO - manifestparser == 1.1 22:47:58 INFO - mozInstall == 1.12 22:47:58 INFO - mozcrash == 0.17 22:47:58 INFO - mozdebug == 0.1 22:47:58 INFO - mozdevice == 0.48 22:47:58 INFO - mozfile == 1.2 22:47:58 INFO - mozhttpd == 0.7 22:47:58 INFO - mozinfo == 0.9 22:47:58 INFO - mozleak == 0.1 22:47:58 INFO - mozlog == 3.3 22:47:58 INFO - moznetwork == 0.27 22:47:58 INFO - mozprocess == 0.23 22:47:58 INFO - mozprofile == 0.28 22:47:58 INFO - mozrunner == 6.12 22:47:58 INFO - mozscreenshot == 0.1 22:47:58 INFO - mozsystemmonitor == 0.3 22:47:58 INFO - moztest == 0.7 22:47:58 INFO - mozversion == 1.4 22:47:58 INFO - psutil == 3.1.1 22:47:58 INFO - requests == 1.2.3 22:47:58 INFO - wsgiref == 0.1.2 22:47:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:47: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:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47: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:47:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:58 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 0xad8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd99180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd973b0>, '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 0xd982b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', '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:47:58 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:47:58 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:47:58 INFO - Using env: {'DISPLAY': ':0', 22:47:58 INFO - 'HOME': '/home/cltbld', 22:47:58 INFO - 'LANG': 'en_US.UTF-8', 22:47:58 INFO - 'LANGUAGE': 'en_US:en', 22:47:58 INFO - 'LOGNAME': 'cltbld', 22:47:58 INFO - 'MAIL': '/var/mail/cltbld', 22:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:58 INFO - 'MOZ_NO_REMOTE': '1', 22:47:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:58 INFO - 'NO_EM_RESTART': '1', 22:47:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:58 INFO - 'PWD': '/builds/slave/test', 22:47:58 INFO - 'SHELL': '/bin/bash', 22:47:58 INFO - 'SHLVL': '1', 22:47:58 INFO - 'TERM': 'linux', 22:47:58 INFO - 'TMOUT': '86400', 22:47:58 INFO - 'USER': 'cltbld', 22:47:58 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', 22:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:58 INFO - '_': '/tools/buildbot/bin/python'} 22:47:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:59 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:47:59 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:47:59 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:47:59 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:47:59 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:47:59 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:47:59 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:47:59 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:47:59 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:47:59 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:47:59 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:47:59 INFO - Cleaning up... 22:47:59 INFO - Return code: 0 22:47:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:47: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:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47: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:47:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0xad8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd99180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd973b0>, '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 0xd982b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', '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:47:59 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:47:59 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:47:59 INFO - Using env: {'DISPLAY': ':0', 22:47:59 INFO - 'HOME': '/home/cltbld', 22:47:59 INFO - 'LANG': 'en_US.UTF-8', 22:47:59 INFO - 'LANGUAGE': 'en_US:en', 22:47:59 INFO - 'LOGNAME': 'cltbld', 22:47:59 INFO - 'MAIL': '/var/mail/cltbld', 22:47:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:59 INFO - 'MOZ_NO_REMOTE': '1', 22:47:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:59 INFO - 'NO_EM_RESTART': '1', 22:47:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:59 INFO - 'PWD': '/builds/slave/test', 22:47:59 INFO - 'SHELL': '/bin/bash', 22:47:59 INFO - 'SHLVL': '1', 22:47:59 INFO - 'TERM': 'linux', 22:47:59 INFO - 'TMOUT': '86400', 22:47:59 INFO - 'USER': 'cltbld', 22:47:59 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', 22:47:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:59 INFO - '_': '/tools/buildbot/bin/python'} 22:47:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:47:59 INFO - Cleaning up... 22:47:59 INFO - Return code: 0 22:47:59 INFO - Running post-action listener: _resource_record_post_action 22:47:59 INFO - Running post-action listener: _start_resource_monitoring 22:47:59 INFO - Starting resource monitoring. 22:47:59 INFO - [mozharness: 2016-07-28 05:47:59.428984Z] Finished create-virtualenv step (success) 22:47:59 INFO - [mozharness: 2016-07-28 05:47:59.429421Z] Running install step. 22:47:59 INFO - Running pre-action listener: _resource_record_pre_action 22:47:59 INFO - Running main action method: install 22:47:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:47:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:47:59 INFO - Reading from file tmpfile_stdout 22:47:59 INFO - Detecting whether we're running mozinstall >=1.0... 22:47:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:47:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:47:59 INFO - Reading from file tmpfile_stdout 22:47:59 INFO - Output received: 22:47:59 INFO - Usage: mozinstall [options] installer 22:47:59 INFO - Options: 22:47:59 INFO - -h, --help show this help message and exit 22:47:59 INFO - -d DEST, --destination=DEST 22:47:59 INFO - Directory to install application into. [default: 22:47:59 INFO - "/builds/slave/test"] 22:47:59 INFO - --app=APP Application being installed. [default: firefox] 22:47:59 INFO - mkdir: /builds/slave/test/build/application 22:47:59 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:47:59 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:48:16 INFO - Reading from file tmpfile_stdout 22:48:16 INFO - Output received: 22:48:16 INFO - /builds/slave/test/build/application/firefox/firefox 22:48:16 INFO - Running post-action listener: _resource_record_post_action 22:48:16 INFO - [mozharness: 2016-07-28 05:48:16.128628Z] Finished install step (success) 22:48:16 INFO - [mozharness: 2016-07-28 05:48:16.128906Z] Running run-tests step. 22:48:16 INFO - Running pre-action listener: _resource_record_pre_action 22:48:16 INFO - Running main action method: run_tests 22:48:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:48:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:48:16 INFO - Python 2.7.3 22:48:16 INFO - Return code: 0 22:48:16 INFO - grabbing minidump binary from tooltool 22:48:16 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:48:16 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 0xd973b0>, '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 0xd982b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:48:16 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:48:16 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:48:16 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:48:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:48:16 INFO - Return code: 0 22:48:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:48:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:48:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:48:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:48:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:48:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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:48:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --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:48:16 INFO - Using env: {'DISPLAY': ':0', 22:48:16 INFO - 'HOME': '/home/cltbld', 22:48:16 INFO - 'LANG': 'en_US.UTF-8', 22:48:16 INFO - 'LANGUAGE': 'en_US:en', 22:48:16 INFO - 'LOGNAME': 'cltbld', 22:48:16 INFO - 'MAIL': '/var/mail/cltbld', 22:48:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:48:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:48:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:16 INFO - 'MOZ_NO_REMOTE': '1', 22:48:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:48:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:48:16 INFO - 'NO_EM_RESTART': '1', 22:48:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:48:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:16 INFO - 'PWD': '/builds/slave/test', 22:48:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:48:16 INFO - 'SHELL': '/bin/bash', 22:48:16 INFO - 'SHLVL': '1', 22:48:16 INFO - 'TERM': 'linux', 22:48:16 INFO - 'TMOUT': '86400', 22:48:16 INFO - 'USER': 'cltbld', 22:48:16 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775', 22:48:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:16 INFO - '_': '/tools/buildbot/bin/python'} 22:48:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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:48:16 INFO - mozversion application_buildid: 20160727221426 22:48:16 INFO - mozversion application_changeset: 90b49d2b01334dd107bcbea8f0790dc518344fe2 22:48:16 INFO - mozversion application_display_name: Nightly 22:48:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:48:16 INFO - mozversion application_name: Firefox 22:48:16 INFO - mozversion application_remotingname: firefox 22:48:16 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:48:16 INFO - mozversion application_vendor: Mozilla 22:48:16 INFO - mozversion application_version: 50.0a1 22:48:16 INFO - mozversion platform_buildid: 20160727221426 22:48:16 INFO - mozversion platform_changeset: 90b49d2b01334dd107bcbea8f0790dc518344fe2 22:48:16 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:48:16 INFO - mozversion platform_version: 50.0a1 22:48:16 INFO - using testdate: 1469684896 22:48:16 INFO - actual date: 1469684896 22:48:16 INFO - starting webserver on 'localhost:59196' 22:48:16 INFO - SUITE-START | Running 2 tests 22:48:16 INFO - TEST-START | dromaeo_css 22:48:16 INFO - operating with platform_type : linux_ 22:48:16 INFO - Initialising browser for dromaeo_css test... 22:48:16 INFO - TEST-INFO | started process 23692 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2avT9o/profile http://localhost:59196/getInfo.html) 22:48:19 INFO - PROCESS | 23692 | JavaScript error: , line 0: uncaught exception: undefined 22:48:20 INFO - PROCESS | 23692 | __metrics Screen width/height:1600/1200 22:48:20 INFO - PROCESS | 23692 | colorDepth:24 22:48:20 INFO - PROCESS | 23692 | Browser inner width/height: 1024/697 22:48:20 INFO - PROCESS | 23692 | __metrics 22:48:21 INFO - PROCESS | 23692 | 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:48:21 INFO - PROCESS | 23692 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:48:22 INFO - TEST-INFO | 23692: exit 0 22:48:22 INFO - Browser initialized. 22:48:22 INFO - Running cycle 1/1 for dromaeo_css test... 22:48:22 INFO - TEST-INFO | started process 23772 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2avT9o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:48:34 INFO - PROCESS | 23772 | RSS: Main: 158826496 22:48:34 INFO - PROCESS | 23772 | 22:49:26 INFO - PROCESS | 23772 | 1469684966293 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:51:38 INFO - PROCESS | 23772 | Cycle 1(1): loaded http://localhost:59196/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59196/tests/dromaeo/cssquery-ext.html) 22:51:39 INFO - PROCESS | 23772 | RSS: Main: 240627712 22:51:39 INFO - PROCESS | 23772 | 22:54:42 INFO - PROCESS | 23772 | Cycle 1(1): loaded http://localhost:59196/tests/dromaeo/cssquery-ext.html (next: http://localhost:59196/tests/dromaeo/cssquery-jquery.html) 22:54:42 INFO - PROCESS | 23772 | RSS: Main: 250490880 22:54:42 INFO - PROCESS | 23772 | 22:57:45 INFO - PROCESS | 23772 | Cycle 1(1): loaded http://localhost:59196/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59196/tests/dromaeo/cssquery-mootools.html) 22:57:45 INFO - PROCESS | 23772 | RSS: Main: 188563456 22:57:45 INFO - PROCESS | 23772 | 23:00:47 INFO - PROCESS | 23772 | Cycle 1(1): loaded http://localhost:59196/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59196/tests/dromaeo/cssquery-prototype.html) 23:00:48 INFO - PROCESS | 23772 | RSS: Main: 223305728 23:00:48 INFO - PROCESS | 23772 | 23:03:51 INFO - PROCESS | 23772 | Cycle 1(1): loaded http://localhost:59196/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59196/tests/dromaeo/cssquery-yui.html) 23:03:52 INFO - PROCESS | 23772 | RSS: Main: 191234048 23:03:52 INFO - PROCESS | 23772 | 23:06:54 INFO - PROCESS | 23772 | Cycle 1(1): loaded http://localhost:59196/tests/dromaeo/cssquery-yui.html (next: http://localhost:59196/tests/dromaeo/cssquery-dojo.html) 23:06:55 INFO - PROCESS | 23772 | RSS: Main: 284119040 23:06:55 INFO - PROCESS | 23772 | 23:06:55 INFO - PROCESS | 23772 | __start_tp_report 23:06:55 INFO - PROCESS | 23772 | _x_x_mozilla_page_load 23:06:55 INFO - PROCESS | 23772 | _x_x_mozilla_page_load_details 23:06:55 INFO - PROCESS | 23772 | |i|pagename|runs| 23:06:55 INFO - PROCESS | 23772 | |0;dojo.html;2633.525965326241;2649.589313656384;2673.104509989155;2674.3848914749615;2678.892844286229;4669.41632295963;4759.90480190396;4802.567768900798;4953.207486802113;4963.205887058071;4770.618350531958;4805.0389922015565;4864.781084851182;4876.512348765131;4959.082569724604;4396.6043056124945;4414.249577571804;4421.0494743630115;4428.6014258716705;4562.680612357136;4518.1415531049;4520.706154099989;4522.977385113069;4524.095180963804;4544.772761361935;4311.267084595626;4353.238183317907;4362.890927726832;4412.426384569985;4426.778661066966;4361.716488428242;4397.362382454558;4415.359772832953;4438.467383913919;4497.685162038659;4312.374705667692;4320.049589090395;4323.308270676708;4363.2364336241035;4400.273954797454;4692.718436893763;4778.307145463923;4812.085703716282;4813.927791083137;4818.204996175627;4967.602591792648;5069.290299358093;5123.538881500683;5126.282320475137;5150.175971844487;5146.614003949681;5179.974100129513;5205.453427390107;5207.739613019372;5215;4835.3230547723;4864.902701945941;4867.123917694778;4869.074875773592;4875.19559272716;5144.845654630367;5171;5204.4014938281825;5235.188545775411;5238.292830467914;5055.469175736568;5065.518775716301;5086.847394578169;5108.744562771921;5115.769790359443;5146.382434107931;5191.065608190562;5191.091558977239;5193.221016847503;5196.4803519648485;4921.458639549646;5068.265101560253;5081.390233172043;5110.105731496973;5116.565091967224;5104.2088476286235;5110.565601923804;5117.667351622133;5121.564667003272;5154.9484505155215;4060.309747342959;4064.146529228895;4070.6539944105084;4093.508778946486;4100.979495102505;3503.474478828196;3519.665631764978;3537.2218112015316;3575.070481674731;3580.5882323532605;4425.690201685916;4439.711418757835;4483.417155769729;4488.169688607618;4491.416115904977;5199.454057324002;5202.583793296527;5206.427292997767;5207.739613019409;5254.369475662945;4301.956980430218;4342.435483387203;4378.934315985262;4443.800028998703;4493.483249426293;4611.400517932647;4649.674522783373;4655.883602909955;4669.7431641260055;4695.3426520287185;4834.709917404967;4844.515548445127;4850.151223535797;4864.051509955525;4887.975560122248;5010.048090862724;5108;5110.054639891632;5175.948240517547;5222.3733152022005;4825.758712064453;4826.469088400203;4845.103655823684;4903.705777653352;4931.605471562195;5021.748912554431;5024.246363045572;5048.495150484922;5051.06555287273;5097.770600323069;4949.752512374439;5072.137736584716;5106.821261255838;5118.616103792305;5168.328117344721;4986.80052797884;5027;5029;5054.29239906406;5075.847724568269;5001.524855138756;5014.222795466709;5017.0216807722145;5018.899622007612;5063.620228482805;4761.952380476151;4860.854174374775;4949.232868905326;4960.429550601727;4999.200127979651;4980.8754781130765;5023.2716256143385;5031.974840125776;5051.595872330131;5062.519060689228;4927.926081108858;4932.087563800708;4934.605231581537;4941.75291235444;4985.626078044089 23:06:55 INFO - PROCESS | 23772 | |1;ext.html;567.9606320433606;574.4026212738753;589.9911151930199;599.9460048595625;603.5051257968465;38452.80773596135;40019.199616007696;40032.79983600081;44032.559674403245;44061.559384406144;40973.180536389256;41383.17233655325;41422;41458.79270603643;41518.58481415185;7310.195878453366;7794.844103117948;7928.445008849383;7933.127355990837;7933.16701746317;7512.511686740371;7600.467967242295;7619.085709714815;9762.804743905117;10066.09405153536;10305.742356441075;10371.11845493134;10371.585136594527;10376.273660843744;10396.636117735881;10150.492475376239;10208.48957552127;10212.234082443847;10215.489225538768;10216.79566408675;10061.698149055466;10114.797704045952;10115.089641932262;10121.746956326151;10160.4919754012;39131.60868391308;39443.21113577741;39503.80248098741;39504.20991580181;39506.20987580261;2888.9133326000256;2974.8363839988792;3003.039027511197;3253.7722359434847;3315.900522984314;29980.8500957496;30296;30497.695023049706;30628.8468557658;31105.8444707775;3261.5596894419423;3446.207372304359;3475.2701932594186;3572.517710109154;3628.3468975584647;254582;255612;256180;256743.99999999814;256834;32208.677913220567;32874.67125328764;33236.1690957728;54383.72808136013;54945.1758223627;34062.82968585191;34064;34315.313693725984;34387.31225375478;34391.82804085963;13146.605601831769;13257.469701211843;13277.203367797963;13279.800802987962;13291.534796282278;10839.512221950032;10925.289856159325;10932.781344373068;10953.945230273797;10964.232503724663;7352.742654007084;7676.654550545239;7903.920960790433;7942.960285198537;8065.677372905018;3088.876444942222;3103.6275646922513;3105.7981231219896;3121.5785868907406;3163.9050828475183;824.3240542755003;894.821035792844;965.6813251627086;992.9623543397167;1003.6637726361605;29002.7099729;29040.41919161647;29062;29106.27234319159;29485.410291794044;960.4477425480237;991.1080027974737;998.3161534348849;1015.4009134610615;1033.540074666766;14730.55808325752;15378.923105384401;15529;15759.842401576067;15811.288492017888;404313;409346;409531;409890;410765;50652.493475065516;51042.48957510452;51163;51470.485295147315;51578;1064.3454275620593;1153.7807816515003;1180.533689192764;1189.7798907202196;1209.3469526455792;27825.304367390978;29249.122526324245;29261;29574.260643484085;30113.849430752707;42767.5723242776;43313.7834310839;43345.78327108344;43347.133057339306;43382.698519044476;311331;313233;314062;314471;314809;22711.772882271627;23139;23196.884015579813;24371;27214.72785272201;41657.79171104125;42316.576834231266;42342.36486452644;42554;42568;35028.124296891765;35099.64900351066;35392.29215415729;36091.63908360988;36165.457518136725;35982.8200858994;36168.81915590405;36550;36832.81583592065;36879.8156009229;35997;36682.44976325411;36700.81649591735;37175.81412092922;37233.06917327089;31056.844715776275;32205.677943220267;33053;33127;33218.5017224737;25099;25551.361215969006;25657.358566035997;26002.86998564995;26049 23:06:55 INFO - PROCESS | 23772 | |2;jquery.html;340.3516301445747;358.381791409818;358.58404251068765;363.5746176972941;383.18764219853915;12871.806922896165;16402.83597164028;16886.32454701812;17512.474625761217;17718.29126834925;16726.163691815393;17010.319587216498;17181.484555463325;17289.39487117948;17437.7384339235;4938.234573641092;4966.354373931384;5121.2318152277085;5126.538611524962;5134.152864777326;3699.741018128732;5018.498150184971;5106.259592359106;5652.717364131798;5961.254843144607;5976.731047102869;6005.87988240235;6007.18902948103;6013.398660133974;6018.759249630009;5062.367204099506;5288.603354748409;5314.123169676998;5378.596605254622;5998.460138587527;5210.687358758487;5219.164933610642;5225.634205605609;5226.55574276187;5229.267902493654;12179;17212.70904682154;19007.524811879677;21054.684179737316;21214.89392553043;1723.4312676816621;1744.1628018551166;1850.2229063793238;1870.2892900697784;1872.1201035513286;8781.560921953876;8833.955830220872;8843.955780221122;9355.766105847408;9364.43813371193;1648.8763342749342;1650.31511922552;1668.7663727078218;1682.4700219430963;1727.1105380728934;67120.6643966777;69756;71470.2852971474;71650.64174679146;72717.6364118176;9552.14030737237;9588.808223835624;9685.17675997534;9800.803983920425;9890.505474726235;12970.546030888874;12995.805062923875;13002.219866808022;13002.479900803863;13010.739785204243;6957.478189135835;7123.572585644878;7125.501214914906;7129.89305160423;7230.530015548972;6781.62701051437;6812.080369150101;7034.964825175842;7039.753608623673;7072.787816365517;4064.7357921734992;4083.7958102094776;4087;4135.958640413557;4184.4351012613515;1713.387084695849;1767.372582733137;1783.848372888319;1792.6145878636155;1812.6012277298973;697.2957313113792;809.340387584117;851.6678495386805;854.6495936665937;904.4618452021037;15424.46014389491;16059.357625694842;16162.111083890499;16405.261763220682;16737.91631041837;805.960311198559;847.7668641123616;848.8599381102049;900.8738776571286;907.5507623726297;8693.782655433664;9002.684906028258;9177.816443671089;9927.106560409598;9943.552540135712;390375;396325;396691;406500;406618;81640.59179704063;82695.58652206701;84130;84462;86730.56634716786;897.5512243878061;902.472053848506;939.2345238630494;939.6100618243353;942.2320808541017;15846.776605714804;15974.121423321829;16238.350465981228;16835.15824208809;16894.915525422293;39049.414258785575;39050.804745976086;39305.82082537414;40408.19183616252;40938.1812363757;53031;55124.243787809646;55293.1706024418;55858.246741261784;56096;7258.600776957318;7301.817454563466;7442.516236444614;7646.732364367221;7806.648700808364;9547.618095276111;11555.42222888869;11577.131715121232;11606.24559403636;11801.29192248468;6357.268914074755;7734;9105.817883642423;9922.801543968935;9926.15627671627;9236.122568355995;9800.362976406795;9922.156616687374;10012.098911098035;10254.436006019441;9962.252831037556;10080.899191007997;10192.796144077225;10201.897981020391;10329.483525823829;7281.745138920112;7355.411567074728;7412.221716719667;7476.159822751942;7648.311651951351;8988.86516702263;10018.448985305879;10037.849432258668;10863.619773308212;11046.558137674403 23:06:55 INFO - PROCESS | 23772 | |3;mootools.html;1320.7688654485466;1396.4344440501598;1440.7694768836982;1669.8998060116387;1701.7107091794387;7301.926980730191;7809.882851757228;7810.765677029684;7818.491798033124;7823.92176078239;6522.086907832895;6721.025451309557;6727.22636896757;6919.723211071551;7678.308952194301;1874.9812501874942;2074.7406574178226;2139.5186083131325;2143.185589475997;2175.1408193763455;2162.9351119466364;2176.586448574774;2179.1610230061383;2181.836362272828;2182.6507758758603;2089.404519711875;2161.5244646177816;2163.4266919266406;2163.826893848496;2164.0261882153013;2105.368389483149;2108.652072408051;2117.714108595343;2145.8819764912923;2148.1729534129317;2103.5792841431776;2123.161351266241;2155.795199456065;2157.9460513487134;2158.3740715192575;7759.534427934285;7852.803679907991;7872.448928575002;7874.5668988205625;7876.251756083163;4407.1846708358735;4534.274516077445;4536.296873984537;4544.931826022612;4548.840790572347;2624.265205742376;2736.1107640017076;2777.7361150690654;2790.316372488733;2791.9441611167663;820.4584973917187;827.664795757719;834.1533343656193;1712.9571760705958;1778.2975724588714;10274.075333220046;10355.79288414235;10367.481625918674;10387.169026477864;10432.113270372027;3783.754055986352;3897.4738410314812;3933.996830808183;3964.8810535683974;3991.1618560102124;8248.340132789363;8249.422540422112;8251.463654862415;8254.42219044661;8256.958715206385;4416.514183439884;4429.357743127293;4431.180231657154;4431.667624928144;4441.333799929972;4026.0337518995816;4083.714140010171;4092.4270602115726;4094.160697057096;4096.897577560525;3072.8924487642826;3133.592632957701;3242.4487837067754;3362.0922350965593;3386.102682789063;988.9406635601887;991.0040409388519;1001.5943542865151;1004.0862814838462;1008.3647302199568;743.6913680822506;767.4742801181209;769.0540635018846;773.9342155916697;795.4829360915336;4832.940592831453;4909.0672772173175;4947.90104197914;5041;5096.566791822736;826.8883700700328;857.3484152044366;858.1118542308681;866.2290561400358;868.1362044765499;3445.0698311456267;3471.6181220065773;3516.349475347069;3545.308664810337;3636.1455058061333;8416.747497575083;8649.913500865037;8698.652053917773;9001.63993440255;9506.904930950603;8596.140385961355;8949.194572488508;8970.41692289999;8974.28205743539;9014.18872301483;6634.170728658918;6761.29006454325;6794.184697836291;6895.379415852497;7065.611391373421;5163.638545301793;5183.81856635016;5206.765695543634;5408.188771684278;5444.646098003617;8361;8550.486970781772;8563;8565.657373704982;8565.871511927451;8704.60829262672;8951.104889510996;8952.597133128895;9103.681371151977;9167.77080572991;6976.162860456778;7126.251743566851;7275.236100209613;7285.4900156990625;7287.489875708649;7147.892781608176;7344.48588598793;7375.741849035258;7384.630768461663;7387.372073373824;6633.633229043578;6720.327967203436;6720.966395167993;6783.559068660521;6790.354916282945;5814.331351894586;5903.822885313448;6332.145160403286;6468.2561505427475;6520.608763474207;5779.335376431596;5822.446867547576;5877.353491115889;5881.382454842351;5889.499392551681;4401.40581021558;4426.070525189746;4473.150101480708;4498.122866041091;4547.613452856544;4338.544452832533;4352.93470597935;4400.757958312323;4421.275702475973;4433.423654924839 23:06:55 INFO - PROCESS | 23772 | |4;prototype.html;265.4611139387045;274.11051139053774;283.7545523122497;296.1426669790955;305.2216847040048;4892.5596696297325;4936.802527898889;4938.135826230413;4946.332245146904;4950.504949505048;4572.291294849296;4635.490096089427;4763.809047738066;4795.25673520604;4876.731779752121;3808.9619103808886;3819.159784847336;3821.7006217885914;3831.3678243089857;3832.482614847002;3826.827792749319;3827.7751119641725;3839.4432807242933;3845.38473844185;3848.692104631623;3613.235874555967;3709.350863598873;3774.75464094833;3788.4696142540065;3822.8853134406013;3798.3542797724454;3800.9429858552007;3806.885793426202;3812.1232116613332;3814;3383.1711230748624;3385.830708464567;3386.4412371958842;3387.762856600039;3393.9321213575836;4640.976795116003;4698.765061746899;4735.371913084334;4758.547937945889;4795.136875362434;4318.848840290606;4330.675199360029;4346.261135606955;4373.890652733675;4387.517373088957;5046.697198168122;5046.772895219724;5056.519630635084;5066.164082926312;5132.794688212467;3690.2435000825035;3693.836441520912;3698.5746639136573;3713.9814300928324;3722.571904230994;5183.455737147583;5276;5300.496452836975;5317.680939143625;5322.840314790563;3771.2080463102507;3774.245150969817;3775.112848480605;3786.659200671953;3800.3349413852466;3830.5061026199805;3842.1739326045035;3847.076701591654;3872.7869967151514;3876.8643097491454;4261.552536983572;4342.131573685212;4388.670849686287;4396.758178300161;4401.625861801719;5061.392632884003;5069;5087.812240736519;5099.158638824552;5101.974490127525;1253.7429826885466;1306.8823805857328;1324.2253281830044;1326.6020193941972;1336.7727486327349;903.3315346643568;924.542351535994;943.5046600534774;944.2068662323682;945.9337846350827;4466.062126953376;4478.283474644041;4503.626393949877;4505.5719706627815;4508.368828364031;5178.870528236825;5194.662401919151;5204.73976301191;5206.349206349207;5212.4266330703595;4282.8715138545895;4428;4451.931536431298;4475.507694153641;4485;2750.2161883863005;2758.613794068832;2769.819961702483;2777.375090604638;2777.6666799983727;4869.904271538875;4894.632902532324;4901.877453063631;4904.166291730415;4917.803287868445;5164.741762911839;5271.182966640177;5295.4439783822145;5295.4969277918535;5297.761600727977;4861.9027619447415;4868.48880867518;4870.586000190024;4873.417088942866;4873.561379475864;5142.97428512855;5151.922721159261;5164.664296820694;5175.741212939413;5184.688918664892;4965.304857319906;5227.503387178212;5228.320318358741;5232.581393488588;5247.816326428557;4980.128477516492;5004.024215277986;5013.623978201723;5018.924716129334;5041.445441001413;3725.5529336479794;3759.0602349412648;3763.8494460222378;3767.981160094182;3768.811559421963;5182.522980950411;5252.763625636779;5317.787288508416;5325.547328477122;5330.466953304638;5164.509371609691;5167.741612919263;5170.767315470737;5177.870553236048;5185.637005409736;4955.388536175514;5107.438181800073;5147.897042059213;5150.253213284128;5169.844904652867;4850.393700787402;4938.209886418156;5057.69653820772;5075.923861142012;5087.389513258433;5123.692578445305;5149.708379770966;5168;5173.430922598586;5174.844754657366;5068.82259120929;5073.619478539051;5078.866875912052;5083.2883396323805;5083.890185573094 23:06:55 INFO - PROCESS | 23772 | |5;yui.html;597.8624916269258;656.9047488114221;677.4005005570069;691.3259571917381;700.7582384077491;11657.708557286072;12575.371231438427;12627.24236545808;12795.104342696015;12841.614751557456;12579.245245285289;12765.425555849986;12806;12823.294718790463;12829.230246185212;633.762339122829;766.5247546521165;783.902012248469;784.2784638132903;785.332467402706;742.3281929853491;752.0373921380615;759.8366351234483;790.0795573157264;791.0309870408761;813.0649752784316;815.9510429374227;822.1572887790003;824.3528829868554;825.0553116681397;650.265200323636;813.2883726739103;813.8535063688533;814.3444527155638;814.763718521628;563.357772139761;578.1356871477169;582.2488989203922;590.5777369181056;591.8461200087966;12463.875361246362;12479.064070194772;12509.499620015189;12511.061670374667;12538.310392928386;11442.141839361999;11506.942465287704;11783.646490605295;11966.162368634199;12045.217060891102;11607.419629018515;11749.412529373498;11784.528618855236;11785.882141178565;11897.821532677017;3868.78721670308;3908.159745654698;3919.1573811630637;3965.781881996489;3966.920661586781;60001.39998600002;61026;61352.386476135114;62497;62585.37414625841;9044.773880652905;9286.767830804149;9510.95244523773;9532.952335238419;9533.427994320364;17998;18002.279908803765;18011.189496472427;18067.45797626073;18125.36561220351;6605.174353205849;6676.732930682815;6679.398854103154;6683.2314283857;6692;3487.3897068013503;3508.08789714676;3520.559930008749;3579.552555930509;3634.7455678102806;2632.5129850977632;2710.4985577668194;2728.877200525981;2729.672439307256;2735.7401046900513;483.05803682818646;484.394506866417;487.73174753885223;490.6687985609699;526.978920843162;511.562613965062;567.3418834152446;587.1398401341969;599.7960693364278;610.6397225636896;7739.34215591681;7776.183500732454;7982.800429989296;8049.798755031171;8128.878066829002;572.6134858970179;576.1070340971478;593.8871614393252;594.9048152295612;595.484905556699;3577.5349204603235;3681.2269423420844;3709.870154544632;3775.679067279257;3781.1681430085337;73979.63010185023;76015;76759.23240767633;91009;106748;88016.55991720001;88212;88513.34459966212;88722;90122.54938725264;73870.26129738742;74198.25801741914;74999.62500187464;81169.59415202886;81624;7805.375569954276;8182.427230093836;8469.280111190372;8515.74452766418;8681.175288347597;43341.3498797512;45960.080798384515;47173.52826471828;47910.76044619754;48088.7595562034;38351.23297533978;47304.05391892074;54362;54658;55474;10897.618583349546;10933.343999360064;11123.165762567678;11203;11460.885391145981;12048.698782530508;12226.14416990802;12733.236005839679;12997.350132493526;13450.730985380042;11365.602203922823;11593.536258549564;11760.76478470443;11849.644510664693;11981.820272695742;10355.585776569154;10870.728231794268;11881.168318217642;12046.277223366626;12144.210626309261;11178.496967636313;11395.886041139482;12082.577109801467;12084.274943503418;12090.939545302217;9675.129238368581;9768.316217864965;9775.364601300893;9909.685889912225;10003.199744020316;9171.63313467454;9335.439873607605;10157.847632285373;10187.592496300065;10204.948975255076 23:06:55 INFO - PROCESS | 23772 | __end_tp_report 23:06:55 INFO - PROCESS | 23772 | __start_cc_report 23:06:55 INFO - PROCESS | 23772 | _x_x_mozilla_cycle_collect,1024 23:06:55 INFO - PROCESS | 23772 | __end_cc_report 23:06:55 INFO - PROCESS | 23772 | __startTimestamp1469686015190__endTimestamp 23:06:55 INFO - PROCESS | 23772 | 23:06:55 INFO - PROCESS | 23772 | ------- Summary: start ------- 23:06:55 INFO - PROCESS | 23772 | Number of tests: 6 23:06:55 INFO - PROCESS | 23772 | 23:06:55 INFO - PROCESS | 23772 | [#0] dojo.html Cycles:165 Average:4736.32 Median:4903.71 stddev:525.66 (10.7%) stddev-sans-first:500.72 23:06:55 INFO - PROCESS | 23772 | Values: 2633.5 2649.6 2673.1 2674.4 2678.9 4669.4 4759.9 4802.6 4953.2 4963.2 4770.6 4805.0 4864.8 4876.5 4959.1 4396.6 4414.2 4421.0 4428.6 4562.7 4518.1 4520.7 4523.0 4524.1 4544.8 4311.3 4353.2 4362.9 4412.4 4426.8 4361.7 4397.4 4415.4 4438.5 4497.7 4312.4 4320.0 4323.3 4363.2 4400.3 4692.7 4778.3 4812.1 4813.9 4818.2 4967.6 5069.3 5123.5 5126.3 5150.2 5146.6 5180.0 5205.5 5207.7 5215.0 4835.3 4864.9 4867.1 4869.1 4875.2 5144.8 5171.0 5204.4 5235.2 5238.3 5055.5 5065.5 5086.8 5108.7 5115.8 5146.4 5191.1 5191.1 5193.2 5196.5 4921.5 5068.3 5081.4 5110.1 5116.6 5104.2 5110.6 5117.7 5121.6 5154.9 4060.3 4064.1 4070.7 4093.5 4101.0 3503.5 3519.7 3537.2 3575.1 3580.6 4425.7 4439.7 4483.4 4488.2 4491.4 5199.5 5202.6 5206.4 5207.7 5254.4 4302.0 4342.4 4378.9 4443.8 4493.5 4611.4 4649.7 4655.9 4669.7 4695.3 4834.7 4844.5 4850.2 4864.1 4888.0 5010.0 5108.0 5110.1 5175.9 5222.4 4825.8 4826.5 4845.1 4903.7 4931.6 5021.7 5024.2 5048.5 5051.1 5097.8 4949.8 5072.1 5106.8 5118.6 5168.3 4986.8 5027.0 5029.0 5054.3 5075.8 5001.5 5014.2 5017.0 5018.9 5063.6 4762.0 4860.9 4949.2 4960.4 4999.2 4980.9 5023.3 5032.0 5051.6 5062.5 4927.9 4932.1 4934.6 4941.8 4985.6 23:06:55 INFO - PROCESS | 23772 | 23:06:55 INFO - PROCESS | 23772 | [#1] ext.html Cycles:180 Average:47239.33 Median:18267.20 stddev:87621.27 (479.7%) stddev-sans-first:87797.00 23:06:55 INFO - PROCESS | 23772 | Values: 568.0 574.4 590.0 599.9 603.5 38452.8 40019.2 40032.8 44032.6 44061.6 40973.2 41383.2 41422.0 41458.8 41518.6 7310.2 7794.8 7928.4 7933.1 7933.2 7512.5 7600.5 7619.1 9762.8 10066.1 10305.7 10371.1 10371.6 10376.3 10396.6 10150.5 10208.5 10212.2 10215.5 10216.8 10061.7 10114.8 10115.1 10121.7 10160.5 39131.6 39443.2 39503.8 39504.2 39506.2 2888.9 2974.8 3003.0 3253.8 3315.9 29980.9 30296.0 30497.7 30628.8 31105.8 3261.6 3446.2 3475.3 3572.5 3628.3 254582.0 255612.0 256180.0 256744.0 256834.0 32208.7 32874.7 33236.2 54383.7 54945.2 34062.8 34064.0 34315.3 34387.3 34391.8 13146.6 13257.5 13277.2 13279.8 13291.5 10839.5 10925.3 10932.8 10953.9 10964.2 7352.7 7676.7 7903.9 7943.0 8065.7 3088.9 3103.6 3105.8 3121.6 3163.9 824.3 894.8 965.7 993.0 1003.7 29002.7 29040.4 29062.0 29106.3 29485.4 960.4 991.1 998.3 1015.4 1033.5 14730.6 15378.9 15529.0 15759.8 15811.3 404313.0 409346.0 409531.0 409890.0 410765.0 50652.5 51042.5 51163.0 51470.5 51578.0 1064.3 1153.8 1180.5 1189.8 1209.3 27825.3 29249.1 29261.0 29574.3 30113.8 42767.6 43313.8 43345.8 43347.1 43382.7 311331.0 313233.0 314062.0 314471.0 314809.0 22711.8 23139.0 23196.9 24371.0 27214.7 41657.8 42316.6 42342.4 42554.0 42568.0 35028.1 35099.6 35392.3 36091.6 36165.5 35982.8 36168.8 36550.0 36832.8 36879.8 35997.0 36682.4 36700.8 37175.8 37233.1 31056.8 32205.7 33053.0 33127.0 33218.5 25099.0 25551.4 25657.4 26002.9 26049.0 23:06:55 INFO - PROCESS | 23772 | 23:06:55 INFO - PROCESS | 23772 | [#2] jquery.html Cycles:180 Average:24774.35 Median:5226.09 stddev:66177.24 (1266.3%) stddev-sans-first:66337.46 23:06:55 INFO - PROCESS | 23772 | Values: 340.4 358.4 358.6 363.6 383.2 12871.8 16402.8 16886.3 17512.5 17718.3 16726.2 17010.3 17181.5 17289.4 17437.7 4938.2 4966.4 5121.2 5126.5 5134.2 3699.7 5018.5 5106.3 5652.7 5961.3 5976.7 6005.9 6007.2 6013.4 6018.8 5062.4 5288.6 5314.1 5378.6 5998.5 5210.7 5219.2 5225.6 5226.6 5229.3 12179.0 17212.7 19007.5 21054.7 21214.9 1723.4 1744.2 1850.2 1870.3 1872.1 8781.6 8834.0 8844.0 9355.8 9364.4 1648.9 1650.3 1668.8 1682.5 1727.1 67120.7 69756.0 71470.3 71650.6 72717.6 9552.1 9588.8 9685.2 9800.8 9890.5 12970.5 12995.8 13002.2 13002.5 13010.7 6957.5 7123.6 7125.5 7129.9 7230.5 6781.6 6812.1 7035.0 7039.8 7072.8 4064.7 4083.8 4087.0 4136.0 4184.4 1713.4 1767.4 1783.8 1792.6 1812.6 697.3 809.3 851.7 854.6 904.5 15424.5 16059.4 16162.1 16405.3 16737.9 806.0 847.8 848.9 900.9 907.6 8693.8 9002.7 9177.8 9927.1 9943.6 390375.0 396325.0 396691.0 406500.0 406618.0 81640.6 82695.6 84130.0 84462.0 86730.6 897.6 902.5 939.2 939.6 942.2 15846.8 15974.1 16238.4 16835.2 16894.9 39049.4 39050.8 39305.8 40408.2 40938.2 53031.0 55124.2 55293.2 55858.2 56096.0 7258.6 7301.8 7442.5 7646.7 7806.6 9547.6 11555.4 11577.1 11606.2 11801.3 6357.3 7734.0 9105.8 9922.8 9926.2 9236.1 9800.4 9922.2 10012.1 10254.4 9962.3 10080.9 10192.8 10201.9 10329.5 7281.7 7355.4 7412.2 7476.2 7648.3 8988.9 10018.4 10037.8 10863.6 11046.6 23:06:55 INFO - PROCESS | 23772 | 23:06:55 INFO - PROCESS | 23772 | [#3] mootools.html Cycles:180 Average:4945.09 Median:5173.73 stddev:2739.91 (53.0%) stddev-sans-first:2734.06 23:06:55 INFO - PROCESS | 23772 | Values: 1320.8 1396.4 1440.8 1669.9 1701.7 7301.9 7809.9 7810.8 7818.5 7823.9 6522.1 6721.0 6727.2 6919.7 7678.3 1875.0 2074.7 2139.5 2143.2 2175.1 2162.9 2176.6 2179.2 2181.8 2182.7 2089.4 2161.5 2163.4 2163.8 2164.0 2105.4 2108.7 2117.7 2145.9 2148.2 2103.6 2123.2 2155.8 2157.9 2158.4 7759.5 7852.8 7872.4 7874.6 7876.3 4407.2 4534.3 4536.3 4544.9 4548.8 2624.3 2736.1 2777.7 2790.3 2791.9 820.5 827.7 834.2 1713.0 1778.3 10274.1 10355.8 10367.5 10387.2 10432.1 3783.8 3897.5 3934.0 3964.9 3991.2 8248.3 8249.4 8251.5 8254.4 8257.0 4416.5 4429.4 4431.2 4431.7 4441.3 4026.0 4083.7 4092.4 4094.2 4096.9 3072.9 3133.6 3242.4 3362.1 3386.1 988.9 991.0 1001.6 1004.1 1008.4 743.7 767.5 769.1 773.9 795.5 4832.9 4909.1 4947.9 5041.0 5096.6 826.9 857.3 858.1 866.2 868.1 3445.1 3471.6 3516.3 3545.3 3636.1 8416.7 8649.9 8698.7 9001.6 9506.9 8596.1 8949.2 8970.4 8974.3 9014.2 6634.2 6761.3 6794.2 6895.4 7065.6 5163.6 5183.8 5206.8 5408.2 5444.6 8361.0 8550.5 8563.0 8565.7 8565.9 8704.6 8951.1 8952.6 9103.7 9167.8 6976.2 7126.3 7275.2 7285.5 7287.5 7147.9 7344.5 7375.7 7384.6 7387.4 6633.6 6720.3 6721.0 6783.6 6790.4 5814.3 5903.8 6332.1 6468.3 6520.6 5779.3 5822.4 5877.4 5881.4 5889.5 4401.4 4426.1 4473.2 4498.1 4547.6 4338.5 4352.9 4400.8 4421.3 4433.4 23:06:55 INFO - PROCESS | 23772 | 23:06:55 INFO - PROCESS | 23772 | [#4] prototype.html Cycles:180 Average:4245.00 Median:4865.20 stddev:1220.35 (25.1%) stddev-sans-first:1186.66 23:06:55 INFO - PROCESS | 23772 | Values: 265.5 274.1 283.8 296.1 305.2 4892.6 4936.8 4938.1 4946.3 4950.5 4572.3 4635.5 4763.8 4795.3 4876.7 3809.0 3819.2 3821.7 3831.4 3832.5 3826.8 3827.8 3839.4 3845.4 3848.7 3613.2 3709.4 3774.8 3788.5 3822.9 3798.4 3800.9 3806.9 3812.1 3814.0 3383.2 3385.8 3386.4 3387.8 3393.9 4641.0 4698.8 4735.4 4758.5 4795.1 4318.8 4330.7 4346.3 4373.9 4387.5 5046.7 5046.8 5056.5 5066.2 5132.8 3690.2 3693.8 3698.6 3714.0 3722.6 5183.5 5276.0 5300.5 5317.7 5322.8 3771.2 3774.2 3775.1 3786.7 3800.3 3830.5 3842.2 3847.1 3872.8 3876.9 4261.6 4342.1 4388.7 4396.8 4401.6 5061.4 5069.0 5087.8 5099.2 5102.0 1253.7 1306.9 1324.2 1326.6 1336.8 903.3 924.5 943.5 944.2 945.9 4466.1 4478.3 4503.6 4505.6 4508.4 5178.9 5194.7 5204.7 5206.3 5212.4 4282.9 4428.0 4451.9 4475.5 4485.0 2750.2 2758.6 2769.8 2777.4 2777.7 4869.9 4894.6 4901.9 4904.2 4917.8 5164.7 5271.2 5295.4 5295.5 5297.8 4861.9 4868.5 4870.6 4873.4 4873.6 5143.0 5151.9 5164.7 5175.7 5184.7 4965.3 5227.5 5228.3 5232.6 5247.8 4980.1 5004.0 5013.6 5018.9 5041.4 3725.6 3759.1 3763.8 3768.0 3768.8 5182.5 5252.8 5317.8 5325.5 5330.5 5164.5 5167.7 5170.8 5177.9 5185.6 4955.4 5107.4 5147.9 5150.3 5169.8 4850.4 4938.2 5057.7 5075.9 5087.4 5123.7 5149.7 5168.0 5173.4 5174.8 5068.8 5073.6 5078.9 5083.3 5083.9 23:06:55 INFO - PROCESS | 23772 | 23:06:55 INFO - PROCESS | 23772 | [#5] yui.html Cycles:180 Average:17187.07 Median:501.12 stddev:24416.76 (4872.5%) stddev-sans-first:24453.48 23:06:55 INFO - PROCESS | 23772 | Values: 597.9 656.9 677.4 691.3 700.8 11657.7 12575.4 12627.2 12795.1 12841.6 12579.2 12765.4 12806.0 12823.3 12829.2 633.8 766.5 783.9 784.3 785.3 742.3 752.0 759.8 790.1 791.0 813.1 816.0 822.2 824.4 825.1 650.3 813.3 813.9 814.3 814.8 563.4 578.1 582.2 590.6 591.8 12463.9 12479.1 12509.5 12511.1 12538.3 11442.1 11506.9 11783.6 11966.2 12045.2 11607.4 11749.4 11784.5 11785.9 11897.8 3868.8 3908.2 3919.2 3965.8 3966.9 60001.4 61026.0 61352.4 62497.0 62585.4 9044.8 9286.8 9511.0 9533.0 9533.4 17998.0 18002.3 18011.2 18067.5 18125.4 6605.2 6676.7 6679.4 6683.2 6692.0 3487.4 3508.1 3520.6 3579.6 3634.7 2632.5 2710.5 2728.9 2729.7 2735.7 483.1 484.4 487.7 490.7 527.0 511.6 567.3 587.1 599.8 610.6 7739.3 7776.2 7982.8 8049.8 8128.9 572.6 576.1 593.9 594.9 595.5 3577.5 3681.2 3709.9 3775.7 3781.2 73979.6 76015.0 76759.2 91009.0 106748.0 88016.6 88212.0 88513.3 88722.0 90122.5 73870.3 74198.3 74999.6 81169.6 81624.0 7805.4 8182.4 8469.3 8515.7 8681.2 43341.3 45960.1 47173.5 47910.8 48088.8 38351.2 47304.1 54362.0 54658.0 55474.0 10897.6 10933.3 11123.2 11203.0 11460.9 12048.7 12226.1 12733.2 12997.4 13450.7 11365.6 11593.5 11760.8 11849.6 11981.8 10355.6 10870.7 11881.2 12046.3 12144.2 11178.5 11395.9 12082.6 12084.3 12090.9 9675.1 9768.3 9775.4 9909.7 10003.2 9171.6 9335.4 10157.8 10187.6 10204.9 23:06:55 INFO - PROCESS | 23772 | -------- Summary: end -------- 23:06:55 INFO - PROCESS | 23772 | 23:06:56 INFO - TEST-INFO | 23772: exit 0 23:06:56 INFO - TEST-OK | dromaeo_css | took 1119721ms 23:06:56 INFO - TEST-START | kraken 23:06:56 INFO - operating with platform_type : linux_ 23:06:56 INFO - Initialising browser for kraken test... 23:06:56 INFO - TEST-INFO | started process 23954 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNB1YhC/profile http://localhost:59196/getInfo.html) 23:06:59 INFO - PROCESS | 23954 | JavaScript error: , line 0: uncaught exception: undefined 23:07:00 INFO - PROCESS | 23954 | __metrics Screen width/height:1600/1200 23:07:00 INFO - PROCESS | 23954 | colorDepth:24 23:07:00 INFO - PROCESS | 23954 | Browser inner width/height: 1024/697 23:07:00 INFO - PROCESS | 23954 | __metrics 23:07:01 INFO - PROCESS | 23954 | 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" 23:07:01 INFO - PROCESS | 23954 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:07:01 INFO - PROCESS | 23954 | console.error: PushService: 23:07:01 INFO - PROCESS | 23954 | stateChangeProcessEnqueue: Error transitioning state 23:07:01 INFO - PROCESS | 23954 | UnknownError 23:07:02 INFO - TEST-INFO | 23954: exit 0 23:07:02 INFO - Browser initialized. 23:07:02 INFO - Running cycle 1/1 for kraken test... 23:07:02 INFO - TEST-INFO | started process 24035 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNB1YhC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:07:14 INFO - PROCESS | 24035 | RSS: Main: 158531584 23:07:14 INFO - PROCESS | 24035 | 23:07:16 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:59196/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59196/tests/kraken/driver.html?testName=audio-beat-detection) 23:07:17 INFO - PROCESS | 24035 | RSS: Main: 214974464 23:07:17 INFO - PROCESS | 24035 | 23:07:20 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:59196/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59196/tests/kraken/driver.html?testName=audio-dft) 23:07:21 INFO - PROCESS | 24035 | RSS: Main: 192712704 23:07:21 INFO - PROCESS | 24035 | 23:07:24 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:59196/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59196/tests/kraken/driver.html?testName=audio-fft) 23:07:25 INFO - PROCESS | 24035 | RSS: Main: 272728064 23:07:25 INFO - PROCESS | 24035 | 23:07:27 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:59196/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59196/tests/kraken/driver.html?testName=audio-oscillator) 23:07:27 INFO - PROCESS | 24035 | RSS: Main: 181960704 23:07:27 INFO - PROCESS | 24035 | 23:07:30 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:59196/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59196/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:07:30 INFO - PROCESS | 24035 | RSS: Main: 186753024 23:07:30 INFO - PROCESS | 24035 | 23:07:33 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:59196/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59196/tests/kraken/driver.html?testName=imaging-darkroom) 23:07:34 INFO - PROCESS | 24035 | RSS: Main: 222584832 23:07:34 INFO - PROCESS | 24035 | 23:07:37 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:59196/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59196/tests/kraken/driver.html?testName=imaging-desaturate) 23:07:38 INFO - PROCESS | 24035 | RSS: Main: 216346624 23:07:38 INFO - PROCESS | 24035 | 23:07:41 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:59196/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59196/tests/kraken/driver.html?testName=json-parse-financial) 23:07:42 INFO - PROCESS | 24035 | RSS: Main: 220258304 23:07:42 INFO - PROCESS | 24035 | 23:07:43 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:59196/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59196/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:07:44 INFO - PROCESS | 24035 | RSS: Main: 185643008 23:07:44 INFO - PROCESS | 24035 | 23:07:46 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:59196/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59196/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:07:46 INFO - PROCESS | 24035 | RSS: Main: 191234048 23:07:46 INFO - PROCESS | 24035 | 23:07:49 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:59196/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59196/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:07:49 INFO - PROCESS | 24035 | RSS: Main: 194805760 23:07:49 INFO - PROCESS | 24035 | 23:07:52 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:59196/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59196/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:07:53 INFO - PROCESS | 24035 | RSS: Main: 190210048 23:07:53 INFO - PROCESS | 24035 | 23:07:56 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:59196/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59196/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:07:56 INFO - PROCESS | 24035 | RSS: Main: 193974272 23:07:56 INFO - PROCESS | 24035 | 23:07:58 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:59196/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59196/tests/kraken/driver.html?testName=ai-astar) 23:07:58 INFO - PROCESS | 24035 | RSS: Main: 244310016 23:07:58 INFO - PROCESS | 24035 | 23:07:58 INFO - PROCESS | 24035 | __start_tp_report 23:07:58 INFO - PROCESS | 24035 | _x_x_mozilla_page_load 23:07:58 INFO - PROCESS | 24035 | _x_x_mozilla_page_load_details 23:07:58 INFO - PROCESS | 24035 | |i|pagename|runs| 23:07:58 INFO - PROCESS | 24035 | |0;ai-astar;106;114;115;113;113;113;112;114;113;112 23:07:58 INFO - PROCESS | 24035 | |1;audio-beat-detection;154;123;130;182;126;124;158;123;155;123 23:07:58 INFO - PROCESS | 24035 | |2;audio-dft;161;160;160;160;160;161;162;161;162;160 23:07:58 INFO - PROCESS | 24035 | |3;audio-fft;76;75;77;135;76;77;74;76;104;75 23:07:58 INFO - PROCESS | 24035 | |4;audio-oscillator;85;85;113;85;86;86;123;84;85;125 23:07:58 INFO - PROCESS | 24035 | |5;imaging-gaussian-blur;117;117;116;117;116;117;117;116;116;117 23:07:58 INFO - PROCESS | 24035 | |6;imaging-darkroom;146;147;145;147;146;146;146;147;145;146 23:07:58 INFO - PROCESS | 24035 | |7;imaging-desaturate;95;94;95;94;95;95;95;96;95;95 23:07:58 INFO - PROCESS | 24035 | |8;json-parse-financial;63;62;63;63;62;63;62;64;62;61 23:07:58 INFO - PROCESS | 24035 | |9;json-stringify-tinderbox;65;66;65;66;67;72;66;66;65;65 23:07:58 INFO - PROCESS | 24035 | |10;stanford-crypto-aes;79;83;86;81;85;79;80;80;80;83 23:07:58 INFO - PROCESS | 24035 | |11;stanford-crypto-ccm;140;138;129;130;127;129;126;136;169;130 23:07:58 INFO - PROCESS | 24035 | |12;stanford-crypto-pbkdf2;169;167;164;167;163;165;162;164;165;167 23:07:58 INFO - PROCESS | 24035 | |13;stanford-crypto-sha256-iterative;67;66;67;66;71;67;69;66;66;64 23:07:58 INFO - PROCESS | 24035 | __end_tp_report 23:07:58 INFO - PROCESS | 24035 | __start_cc_report 23:07:58 INFO - PROCESS | 24035 | _x_x_mozilla_cycle_collect,1973 23:07:58 INFO - PROCESS | 24035 | __end_cc_report 23:07:58 INFO - PROCESS | 24035 | __startTimestamp1469686078630__endTimestamp 23:07:58 INFO - PROCESS | 24035 | 23:07:58 INFO - PROCESS | 24035 | ------- Summary: start ------- 23:07:58 INFO - PROCESS | 24035 | Number of tests: 14 23:07:58 INFO - PROCESS | 24035 | 23:07:58 INFO - PROCESS | 24035 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.00 stddev:2.46 (2.2%) stddev-sans-first:0.97 23:07:58 INFO - PROCESS | 24035 | Values: 106.0 114.0 115.0 113.0 113.0 113.0 112.0 114.0 113.0 112.0 23:07:58 INFO - PROCESS | 24035 | 23:07:58 INFO - PROCESS | 24035 | [#1] audio-beat-detection Cycles:10 Average:139.80 Median:142.00 stddev:20.89 (14.7%) stddev-sans-first:21.52 23:07:58 INFO - PROCESS | 24035 | Values: 154.0 123.0 130.0 182.0 126.0 124.0 158.0 123.0 155.0 123.0 23:07:58 INFO - PROCESS | 24035 | 23:07:58 INFO - PROCESS | 24035 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 23:07:58 INFO - PROCESS | 24035 | Values: 161.0 160.0 160.0 160.0 160.0 161.0 162.0 161.0 162.0 160.0 23:07:58 INFO - PROCESS | 24035 | 23:07:58 INFO - PROCESS | 24035 | [#3] audio-fft Cycles:10 Average:84.50 Median:76.00 stddev:19.86 (26.1%) stddev-sans-first:20.83 23:07:58 INFO - PROCESS | 24035 | Values: 76.0 75.0 77.0 135.0 76.0 77.0 74.0 76.0 104.0 75.0 23:07:58 INFO - PROCESS | 24035 | 23:07:58 INFO - PROCESS | 24035 | [#4] audio-oscillator Cycles:10 Average:95.70 Median:85.00 stddev:17.28 (20.3%) stddev-sans-first:17.88 23:07:58 INFO - PROCESS | 24035 | Values: 85.0 85.0 113.0 85.0 86.0 86.0 123.0 84.0 85.0 125.0 23:07:58 INFO - PROCESS | 24035 | 23:07:58 INFO - PROCESS | 24035 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 23:07:58 INFO - PROCESS | 24035 | Values: 117.0 117.0 116.0 117.0 116.0 117.0 117.0 116.0 116.0 117.0 23:07:58 INFO - PROCESS | 24035 | 23:07:58 INFO - PROCESS | 24035 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 23:07:58 INFO - PROCESS | 24035 | Values: 146.0 147.0 145.0 147.0 146.0 146.0 146.0 147.0 145.0 146.0 23:07:58 INFO - PROCESS | 24035 | 23:07:58 INFO - PROCESS | 24035 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 23:07:58 INFO - PROCESS | 24035 | Values: 95.0 94.0 95.0 94.0 95.0 95.0 95.0 96.0 95.0 95.0 23:07:58 INFO - PROCESS | 24035 | 23:07:58 INFO - PROCESS | 24035 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:63.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 23:07:58 INFO - PROCESS | 24035 | Values: 63.0 62.0 63.0 63.0 62.0 63.0 62.0 64.0 62.0 61.0 23:07:58 INFO - PROCESS | 24035 | 23:07:58 INFO - PROCESS | 24035 | [#9] json-stringify-tinderbox Cycles:10 Average:66.30 Median:66.00 stddev:2.11 (3.2%) stddev-sans-first:2.19 23:07:58 INFO - PROCESS | 24035 | Values: 65.0 66.0 65.0 66.0 67.0 72.0 66.0 66.0 65.0 65.0 23:07:58 INFO - PROCESS | 24035 | 23:07:58 INFO - PROCESS | 24035 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:82.00 stddev:2.50 (3.1%) stddev-sans-first:2.47 23:07:58 INFO - PROCESS | 24035 | Values: 79.0 83.0 86.0 81.0 85.0 79.0 80.0 80.0 80.0 83.0 23:07:58 INFO - PROCESS | 24035 | 23:07:58 INFO - PROCESS | 24035 | [#11] stanford-crypto-ccm Cycles:10 Average:135.40 Median:133.00 stddev:12.72 (9.6%) stddev-sans-first:13.38 23:07:58 INFO - PROCESS | 24035 | Values: 140.0 138.0 129.0 130.0 127.0 129.0 126.0 136.0 169.0 130.0 23:07:58 INFO - PROCESS | 24035 | 23:07:58 INFO - PROCESS | 24035 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.30 Median:166.00 stddev:2.16 (1.3%) stddev-sans-first:1.83 23:07:58 INFO - PROCESS | 24035 | Values: 169.0 167.0 164.0 167.0 163.0 165.0 162.0 164.0 165.0 167.0 23:07:58 INFO - PROCESS | 24035 | 23:07:58 INFO - PROCESS | 24035 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:1.91 (2.9%) stddev-sans-first:2.03 23:07:58 INFO - PROCESS | 24035 | Values: 67.0 66.0 67.0 66.0 71.0 67.0 69.0 66.0 66.0 64.0 23:07:58 INFO - PROCESS | 24035 | -------- Summary: end -------- 23:07:58 INFO - PROCESS | 24035 | 23:07:59 INFO - TEST-INFO | 24035: exit 0 23:07:59 INFO - TEST-OK | kraken | took 63631ms 23:07:59 INFO - SUITE-END | took 1183s 23:07:59 INFO - Completed test suite (00:19:43) 23:07:59 INFO - javascript benchmark 23:07:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2633.525965326241, 2649.589313656384, 2673.104509989155, 2674.3848914749615, 2678.892844286229, 4669.41632295963, 4759.90480190396, 4802.567768900798, 4953.207486802113, 4963.205887058071, 4770.618350531958, 4805.0389922015565, 4864.781084851182, 4876.512348765131, 4959.082569724604, 4396.6043056124945, 4414.249577571804, 4421.0494743630115, 4428.6014258716705, 4562.680612357136, 4518.1415531049, 4520.706154099989, 4522.977385113069, 4524.095180963804, 4544.772761361935, 4311.267084595626, 4353.238183317907, 4362.890927726832, 4412.426384569985, 4426.778661066966, 4361.716488428242, 4397.362382454558, 4415.359772832953, 4438.467383913919, 4497.685162038659, 4312.374705667692, 4320.049589090395, 4323.308270676708, 4363.2364336241035, 4400.273954797454, 4692.718436893763, 4778.307145463923, 4812.085703716282, 4813.927791083137, 4818.204996175627, 4967.602591792648, 5069.290299358093, 5123.538881500683, 5126.282320475137, 5150.175971844487, 5146.614003949681, 5179.974100129513, 5205.453427390107, 5207.739613019372, 5215.0, 4835.3230547723, 4864.902701945941, 4867.123917694778, 4869.074875773592, 4875.19559272716, 5144.845654630367, 5171.0, 5204.4014938281825, 5235.188545775411, 5238.292830467914, 5055.469175736568, 5065.518775716301, 5086.847394578169, 5108.744562771921, 5115.769790359443, 5146.382434107931, 5191.065608190562, 5191.091558977239, 5193.221016847503, 5196.4803519648485, 4921.458639549646, 5068.265101560253, 5081.390233172043, 5110.105731496973, 5116.565091967224, 5104.2088476286235, 5110.565601923804, 5117.667351622133, 5121.564667003272, 5154.9484505155215, 4060.309747342959, 4064.146529228895, 4070.6539944105084, 4093.508778946486, 4100.979495102505, 3503.474478828196, 3519.665631764978, 3537.2218112015316, 3575.070481674731, 3580.5882323532605, 4425.690201685916, 4439.711418757835, 4483.417155769729, 4488.169688607618, 4491.416115904977, 5199.454057324002, 5202.583793296527, 5206.427292997767, 5207.739613019409, 5254.369475662945, 4301.956980430218, 4342.435483387203, 4378.934315985262, 4443.800028998703, 4493.483249426293, 4611.400517932647, 4649.674522783373, 4655.883602909955, 4669.7431641260055, 4695.3426520287185, 4834.709917404967, 4844.515548445127, 4850.151223535797, 4864.051509955525, 4887.975560122248, 5010.048090862724, 5108.0, 5110.054639891632, 5175.948240517547, 5222.3733152022005, 4825.758712064453, 4826.469088400203, 4845.103655823684, 4903.705777653352, 4931.605471562195, 5021.748912554431, 5024.246363045572, 5048.495150484922, 5051.06555287273, 5097.770600323069, 4949.752512374439, 5072.137736584716, 5106.821261255838, 5118.616103792305, 5168.328117344721, 4986.80052797884, 5027.0, 5029.0, 5054.29239906406, 5075.847724568269, 5001.524855138756, 5014.222795466709, 5017.0216807722145, 5018.899622007612, 5063.620228482805, 4761.952380476151, 4860.854174374775, 4949.232868905326, 4960.429550601727, 4999.200127979651, 4980.8754781130765, 5023.2716256143385, 5031.974840125776, 5051.595872330131, 5062.519060689228, 4927.926081108858, 4932.087563800708, 4934.605231581537, 4941.75291235444, 4985.626078044089], "value": 4700.7621928022945, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.9606320433606, 574.4026212738753, 589.9911151930199, 599.9460048595625, 603.5051257968465, 38452.80773596135, 40019.199616007696, 40032.79983600081, 44032.559674403245, 44061.559384406144, 40973.180536389256, 41383.17233655325, 41422.0, 41458.79270603643, 41518.58481415185, 7310.195878453366, 7794.844103117948, 7928.445008849383, 7933.127355990837, 7933.16701746317, 7512.511686740371, 7600.467967242295, 7619.085709714815, 9762.804743905117, 10066.09405153536, 10305.742356441075, 10371.11845493134, 10371.585136594527, 10376.273660843744, 10396.636117735881, 10150.492475376239, 10208.48957552127, 10212.234082443847, 10215.489225538768, 10216.79566408675, 10061.698149055466, 10114.797704045952, 10115.089641932262, 10121.746956326151, 10160.4919754012, 39131.60868391308, 39443.21113577741, 39503.80248098741, 39504.20991580181, 39506.20987580261, 2888.9133326000256, 2974.8363839988792, 3003.039027511197, 3253.7722359434847, 3315.900522984314, 29980.8500957496, 30296.0, 30497.695023049706, 30628.8468557658, 31105.8444707775, 3261.5596894419423, 3446.207372304359, 3475.2701932594186, 3572.517710109154, 3628.3468975584647, 254582.0, 255612.0, 256180.0, 256743.99999999814, 256834.0, 32208.677913220567, 32874.67125328764, 33236.1690957728, 54383.72808136013, 54945.1758223627, 34062.82968585191, 34064.0, 34315.313693725984, 34387.31225375478, 34391.82804085963, 13146.605601831769, 13257.469701211843, 13277.203367797963, 13279.800802987962, 13291.534796282278, 10839.512221950032, 10925.289856159325, 10932.781344373068, 10953.945230273797, 10964.232503724663, 7352.742654007084, 7676.654550545239, 7903.920960790433, 7942.960285198537, 8065.677372905018, 3088.876444942222, 3103.6275646922513, 3105.7981231219896, 3121.5785868907406, 3163.9050828475183, 824.3240542755003, 894.821035792844, 965.6813251627086, 992.9623543397167, 1003.6637726361605, 29002.7099729, 29040.41919161647, 29062.0, 29106.27234319159, 29485.410291794044, 960.4477425480237, 991.1080027974737, 998.3161534348849, 1015.4009134610615, 1033.540074666766, 14730.55808325752, 15378.923105384401, 15529.0, 15759.842401576067, 15811.288492017888, 404313.0, 409346.0, 409531.0, 409890.0, 410765.0, 50652.493475065516, 51042.48957510452, 51163.0, 51470.485295147315, 51578.0, 1064.3454275620593, 1153.7807816515003, 1180.533689192764, 1189.7798907202196, 1209.3469526455792, 27825.304367390978, 29249.122526324245, 29261.0, 29574.260643484085, 30113.849430752707, 42767.5723242776, 43313.7834310839, 43345.78327108344, 43347.133057339306, 43382.698519044476, 311331.0, 313233.0, 314062.0, 314471.0, 314809.0, 22711.772882271627, 23139.0, 23196.884015579813, 24371.0, 27214.72785272201, 41657.79171104125, 42316.576834231266, 42342.36486452644, 42554.0, 42568.0, 35028.124296891765, 35099.64900351066, 35392.29215415729, 36091.63908360988, 36165.457518136725, 35982.8200858994, 36168.81915590405, 36550.0, 36832.81583592065, 36879.8156009229, 35997.0, 36682.44976325411, 36700.81649591735, 37175.81412092922, 37233.06917327089, 31056.844715776275, 32205.677943220267, 33053.0, 33127.0, 33218.5017224737, 25099.0, 25551.361215969006, 25657.358566035997, 26002.86998564995, 26049.0], "value": 16990.177763450763, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.3516301445747, 358.381791409818, 358.58404251068765, 363.5746176972941, 383.18764219853915, 12871.806922896165, 16402.83597164028, 16886.32454701812, 17512.474625761217, 17718.29126834925, 16726.163691815393, 17010.319587216498, 17181.484555463325, 17289.39487117948, 17437.7384339235, 4938.234573641092, 4966.354373931384, 5121.2318152277085, 5126.538611524962, 5134.152864777326, 3699.741018128732, 5018.498150184971, 5106.259592359106, 5652.717364131798, 5961.254843144607, 5976.731047102869, 6005.87988240235, 6007.18902948103, 6013.398660133974, 6018.759249630009, 5062.367204099506, 5288.603354748409, 5314.123169676998, 5378.596605254622, 5998.460138587527, 5210.687358758487, 5219.164933610642, 5225.634205605609, 5226.55574276187, 5229.267902493654, 12179.0, 17212.70904682154, 19007.524811879677, 21054.684179737316, 21214.89392553043, 1723.4312676816621, 1744.1628018551166, 1850.2229063793238, 1870.2892900697784, 1872.1201035513286, 8781.560921953876, 8833.955830220872, 8843.955780221122, 9355.766105847408, 9364.43813371193, 1648.8763342749342, 1650.31511922552, 1668.7663727078218, 1682.4700219430963, 1727.1105380728934, 67120.6643966777, 69756.0, 71470.2852971474, 71650.64174679146, 72717.6364118176, 9552.14030737237, 9588.808223835624, 9685.17675997534, 9800.803983920425, 9890.505474726235, 12970.546030888874, 12995.805062923875, 13002.219866808022, 13002.479900803863, 13010.739785204243, 6957.478189135835, 7123.572585644878, 7125.501214914906, 7129.89305160423, 7230.530015548972, 6781.62701051437, 6812.080369150101, 7034.964825175842, 7039.753608623673, 7072.787816365517, 4064.7357921734992, 4083.7958102094776, 4087.0, 4135.958640413557, 4184.4351012613515, 1713.387084695849, 1767.372582733137, 1783.848372888319, 1792.6145878636155, 1812.6012277298973, 697.2957313113792, 809.340387584117, 851.6678495386805, 854.6495936665937, 904.4618452021037, 15424.46014389491, 16059.357625694842, 16162.111083890499, 16405.261763220682, 16737.91631041837, 805.960311198559, 847.7668641123616, 848.8599381102049, 900.8738776571286, 907.5507623726297, 8693.782655433664, 9002.684906028258, 9177.816443671089, 9927.106560409598, 9943.552540135712, 390375.0, 396325.0, 396691.0, 406500.0, 406618.0, 81640.59179704063, 82695.58652206701, 84130.0, 84462.0, 86730.56634716786, 897.5512243878061, 902.472053848506, 939.2345238630494, 939.6100618243353, 942.2320808541017, 15846.776605714804, 15974.121423321829, 16238.350465981228, 16835.15824208809, 16894.915525422293, 39049.414258785575, 39050.804745976086, 39305.82082537414, 40408.19183616252, 40938.1812363757, 53031.0, 55124.243787809646, 55293.1706024418, 55858.246741261784, 56096.0, 7258.600776957318, 7301.817454563466, 7442.516236444614, 7646.732364367221, 7806.648700808364, 9547.618095276111, 11555.42222888869, 11577.131715121232, 11606.24559403636, 11801.29192248468, 6357.268914074755, 7734.0, 9105.817883642423, 9922.801543968935, 9926.15627671627, 9236.122568355995, 9800.362976406795, 9922.156616687374, 10012.098911098035, 10254.436006019441, 9962.252831037556, 10080.899191007997, 10192.796144077225, 10201.897981020391, 10329.483525823829, 7281.745138920112, 7355.411567074728, 7412.221716719667, 7476.159822751942, 7648.311651951351, 8988.86516702263, 10018.448985305879, 10037.849432258668, 10863.619773308212, 11046.558137674403], "value": 8077.69196903298, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1320.7688654485466, 1396.4344440501598, 1440.7694768836982, 1669.8998060116387, 1701.7107091794387, 7301.926980730191, 7809.882851757228, 7810.765677029684, 7818.491798033124, 7823.92176078239, 6522.086907832895, 6721.025451309557, 6727.22636896757, 6919.723211071551, 7678.308952194301, 1874.9812501874942, 2074.7406574178226, 2139.5186083131325, 2143.185589475997, 2175.1408193763455, 2162.9351119466364, 2176.586448574774, 2179.1610230061383, 2181.836362272828, 2182.6507758758603, 2089.404519711875, 2161.5244646177816, 2163.4266919266406, 2163.826893848496, 2164.0261882153013, 2105.368389483149, 2108.652072408051, 2117.714108595343, 2145.8819764912923, 2148.1729534129317, 2103.5792841431776, 2123.161351266241, 2155.795199456065, 2157.9460513487134, 2158.3740715192575, 7759.534427934285, 7852.803679907991, 7872.448928575002, 7874.5668988205625, 7876.251756083163, 4407.1846708358735, 4534.274516077445, 4536.296873984537, 4544.931826022612, 4548.840790572347, 2624.265205742376, 2736.1107640017076, 2777.7361150690654, 2790.316372488733, 2791.9441611167663, 820.4584973917187, 827.664795757719, 834.1533343656193, 1712.9571760705958, 1778.2975724588714, 10274.075333220046, 10355.79288414235, 10367.481625918674, 10387.169026477864, 10432.113270372027, 3783.754055986352, 3897.4738410314812, 3933.996830808183, 3964.8810535683974, 3991.1618560102124, 8248.340132789363, 8249.422540422112, 8251.463654862415, 8254.42219044661, 8256.958715206385, 4416.514183439884, 4429.357743127293, 4431.180231657154, 4431.667624928144, 4441.333799929972, 4026.0337518995816, 4083.714140010171, 4092.4270602115726, 4094.160697057096, 4096.897577560525, 3072.8924487642826, 3133.592632957701, 3242.4487837067754, 3362.0922350965593, 3386.102682789063, 988.9406635601887, 991.0040409388519, 1001.5943542865151, 1004.0862814838462, 1008.3647302199568, 743.6913680822506, 767.4742801181209, 769.0540635018846, 773.9342155916697, 795.4829360915336, 4832.940592831453, 4909.0672772173175, 4947.90104197914, 5041.0, 5096.566791822736, 826.8883700700328, 857.3484152044366, 858.1118542308681, 866.2290561400358, 868.1362044765499, 3445.0698311456267, 3471.6181220065773, 3516.349475347069, 3545.308664810337, 3636.1455058061333, 8416.747497575083, 8649.913500865037, 8698.652053917773, 9001.63993440255, 9506.904930950603, 8596.140385961355, 8949.194572488508, 8970.41692289999, 8974.28205743539, 9014.18872301483, 6634.170728658918, 6761.29006454325, 6794.184697836291, 6895.379415852497, 7065.611391373421, 5163.638545301793, 5183.81856635016, 5206.765695543634, 5408.188771684278, 5444.646098003617, 8361.0, 8550.486970781772, 8563.0, 8565.657373704982, 8565.871511927451, 8704.60829262672, 8951.104889510996, 8952.597133128895, 9103.681371151977, 9167.77080572991, 6976.162860456778, 7126.251743566851, 7275.236100209613, 7285.4900156990625, 7287.489875708649, 7147.892781608176, 7344.48588598793, 7375.741849035258, 7384.630768461663, 7387.372073373824, 6633.633229043578, 6720.327967203436, 6720.966395167993, 6783.559068660521, 6790.354916282945, 5814.331351894586, 5903.822885313448, 6332.145160403286, 6468.2561505427475, 6520.608763474207, 5779.335376431596, 5822.446867547576, 5877.353491115889, 5881.382454842351, 5889.499392551681, 4401.40581021558, 4426.070525189746, 4473.150101480708, 4498.122866041091, 4547.613452856544, 4338.544452832533, 4352.93470597935, 4400.757958312323, 4421.275702475973, 4433.423654924839], "value": 4009.1612804625015, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.4611139387045, 274.11051139053774, 283.7545523122497, 296.1426669790955, 305.2216847040048, 4892.5596696297325, 4936.802527898889, 4938.135826230413, 4946.332245146904, 4950.504949505048, 4572.291294849296, 4635.490096089427, 4763.809047738066, 4795.25673520604, 4876.731779752121, 3808.9619103808886, 3819.159784847336, 3821.7006217885914, 3831.3678243089857, 3832.482614847002, 3826.827792749319, 3827.7751119641725, 3839.4432807242933, 3845.38473844185, 3848.692104631623, 3613.235874555967, 3709.350863598873, 3774.75464094833, 3788.4696142540065, 3822.8853134406013, 3798.3542797724454, 3800.9429858552007, 3806.885793426202, 3812.1232116613332, 3814.0, 3383.1711230748624, 3385.830708464567, 3386.4412371958842, 3387.762856600039, 3393.9321213575836, 4640.976795116003, 4698.765061746899, 4735.371913084334, 4758.547937945889, 4795.136875362434, 4318.848840290606, 4330.675199360029, 4346.261135606955, 4373.890652733675, 4387.517373088957, 5046.697198168122, 5046.772895219724, 5056.519630635084, 5066.164082926312, 5132.794688212467, 3690.2435000825035, 3693.836441520912, 3698.5746639136573, 3713.9814300928324, 3722.571904230994, 5183.455737147583, 5276.0, 5300.496452836975, 5317.680939143625, 5322.840314790563, 3771.2080463102507, 3774.245150969817, 3775.112848480605, 3786.659200671953, 3800.3349413852466, 3830.5061026199805, 3842.1739326045035, 3847.076701591654, 3872.7869967151514, 3876.8643097491454, 4261.552536983572, 4342.131573685212, 4388.670849686287, 4396.758178300161, 4401.625861801719, 5061.392632884003, 5069.0, 5087.812240736519, 5099.158638824552, 5101.974490127525, 1253.7429826885466, 1306.8823805857328, 1324.2253281830044, 1326.6020193941972, 1336.7727486327349, 903.3315346643568, 924.542351535994, 943.5046600534774, 944.2068662323682, 945.9337846350827, 4466.062126953376, 4478.283474644041, 4503.626393949877, 4505.5719706627815, 4508.368828364031, 5178.870528236825, 5194.662401919151, 5204.73976301191, 5206.349206349207, 5212.4266330703595, 4282.8715138545895, 4428.0, 4451.931536431298, 4475.507694153641, 4485.0, 2750.2161883863005, 2758.613794068832, 2769.819961702483, 2777.375090604638, 2777.6666799983727, 4869.904271538875, 4894.632902532324, 4901.877453063631, 4904.166291730415, 4917.803287868445, 5164.741762911839, 5271.182966640177, 5295.4439783822145, 5295.4969277918535, 5297.761600727977, 4861.9027619447415, 4868.48880867518, 4870.586000190024, 4873.417088942866, 4873.561379475864, 5142.97428512855, 5151.922721159261, 5164.664296820694, 5175.741212939413, 5184.688918664892, 4965.304857319906, 5227.503387178212, 5228.320318358741, 5232.581393488588, 5247.816326428557, 4980.128477516492, 5004.024215277986, 5013.623978201723, 5018.924716129334, 5041.445441001413, 3725.5529336479794, 3759.0602349412648, 3763.8494460222378, 3767.981160094182, 3768.811559421963, 5182.522980950411, 5252.763625636779, 5317.787288508416, 5325.547328477122, 5330.466953304638, 5164.509371609691, 5167.741612919263, 5170.767315470737, 5177.870553236048, 5185.637005409736, 4955.388536175514, 5107.438181800073, 5147.897042059213, 5150.253213284128, 5169.844904652867, 4850.393700787402, 4938.209886418156, 5057.69653820772, 5075.923861142012, 5087.389513258433, 5123.692578445305, 5149.708379770966, 5168.0, 5173.430922598586, 5174.844754657366, 5068.82259120929, 5073.619478539051, 5078.866875912052, 5083.2883396323805, 5083.890185573094], "value": 3855.373711414452, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.8624916269258, 656.9047488114221, 677.4005005570069, 691.3259571917381, 700.7582384077491, 11657.708557286072, 12575.371231438427, 12627.24236545808, 12795.104342696015, 12841.614751557456, 12579.245245285289, 12765.425555849986, 12806.0, 12823.294718790463, 12829.230246185212, 633.762339122829, 766.5247546521165, 783.902012248469, 784.2784638132903, 785.332467402706, 742.3281929853491, 752.0373921380615, 759.8366351234483, 790.0795573157264, 791.0309870408761, 813.0649752784316, 815.9510429374227, 822.1572887790003, 824.3528829868554, 825.0553116681397, 650.265200323636, 813.2883726739103, 813.8535063688533, 814.3444527155638, 814.763718521628, 563.357772139761, 578.1356871477169, 582.2488989203922, 590.5777369181056, 591.8461200087966, 12463.875361246362, 12479.064070194772, 12509.499620015189, 12511.061670374667, 12538.310392928386, 11442.141839361999, 11506.942465287704, 11783.646490605295, 11966.162368634199, 12045.217060891102, 11607.419629018515, 11749.412529373498, 11784.528618855236, 11785.882141178565, 11897.821532677017, 3868.78721670308, 3908.159745654698, 3919.1573811630637, 3965.781881996489, 3966.920661586781, 60001.39998600002, 61026.0, 61352.386476135114, 62497.0, 62585.37414625841, 9044.773880652905, 9286.767830804149, 9510.95244523773, 9532.952335238419, 9533.427994320364, 17998.0, 18002.279908803765, 18011.189496472427, 18067.45797626073, 18125.36561220351, 6605.174353205849, 6676.732930682815, 6679.398854103154, 6683.2314283857, 6692.0, 3487.3897068013503, 3508.08789714676, 3520.559930008749, 3579.552555930509, 3634.7455678102806, 2632.5129850977632, 2710.4985577668194, 2728.877200525981, 2729.672439307256, 2735.7401046900513, 483.05803682818646, 484.394506866417, 487.73174753885223, 490.6687985609699, 526.978920843162, 511.562613965062, 567.3418834152446, 587.1398401341969, 599.7960693364278, 610.6397225636896, 7739.34215591681, 7776.183500732454, 7982.800429989296, 8049.798755031171, 8128.878066829002, 572.6134858970179, 576.1070340971478, 593.8871614393252, 594.9048152295612, 595.484905556699, 3577.5349204603235, 3681.2269423420844, 3709.870154544632, 3775.679067279257, 3781.1681430085337, 73979.63010185023, 76015.0, 76759.23240767633, 91009.0, 106748.0, 88016.55991720001, 88212.0, 88513.34459966212, 88722.0, 90122.54938725264, 73870.26129738742, 74198.25801741914, 74999.62500187464, 81169.59415202886, 81624.0, 7805.375569954276, 8182.427230093836, 8469.280111190372, 8515.74452766418, 8681.175288347597, 43341.3498797512, 45960.080798384515, 47173.52826471828, 47910.76044619754, 48088.7595562034, 38351.23297533978, 47304.05391892074, 54362.0, 54658.0, 55474.0, 10897.618583349546, 10933.343999360064, 11123.165762567678, 11203.0, 11460.885391145981, 12048.698782530508, 12226.14416990802, 12733.236005839679, 12997.350132493526, 13450.730985380042, 11365.602203922823, 11593.536258549564, 11760.76478470443, 11849.644510664693, 11981.820272695742, 10355.585776569154, 10870.728231794268, 11881.168318217642, 12046.277223366626, 12144.210626309261, 11178.496967636313, 11395.886041139482, 12082.577109801467, 12084.274943503418, 12090.939545302217, 9675.129238368581, 9768.316217864965, 9775.364601300893, 9909.685889912225, 10003.199744020316, 9171.63313467454, 9335.439873607605, 10157.847632285373, 10187.592496300065, 10204.948975255076], "value": 6428.326325632978, "unit": "score"}], "name": "dromaeo_css", "value": 6326.351128723767, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 114.0, 115.0, 113.0, 113.0, 113.0, 112.0, 114.0, 113.0, 112.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 123.0, 130.0, 182.0, 126.0, 124.0, 158.0, 123.0, 155.0, 123.0], "value": 139.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 160.0, 161.0, 162.0, 161.0, 162.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 77.0, 135.0, 76.0, 77.0, 74.0, 76.0, 104.0, 75.0], "value": 84.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 113.0, 85.0, 86.0, 86.0, 123.0, 84.0, 85.0, 125.0], "value": 95.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 145.0, 147.0, 146.0, 146.0, 146.0, 147.0, 145.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 63.0, 62.0, 63.0, 62.0, 64.0, 62.0, 61.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 66.0, 67.0, 72.0, 66.0, 66.0, 65.0, 65.0], "value": 66.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 83.0, 86.0, 81.0, 85.0, 79.0, 80.0, 80.0, 80.0, 83.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 129.0, 130.0, 127.0, 129.0, 126.0, 136.0, 169.0, 130.0], "value": 135.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 164.0, 167.0, 163.0, 165.0, 162.0, 164.0, 165.0, 167.0], "value": 165.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 66.0, 71.0, 67.0, 69.0, 66.0, 66.0, 64.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1528.8000000000002, "alertThreshold": 2.0}]} 23:07:59 INFO - Return code: 0 23:07:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:07:59 INFO - # TBPL SUCCESS # 23:07:59 INFO - Running post-action listener: _resource_record_post_action 23:07:59 INFO - [mozharness: 2016-07-28 06:07:59.919453Z] Finished run-tests step (success) 23:07:59 INFO - Running post-run listener: _resource_record_post_run 23:07:59 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 536633344; Read time: 12; Write time: 861056 23:07:59 INFO - TinderboxPrint: CPU usage
13.0% 23:07:59 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 23:07:59 INFO - TinderboxPrint: I/O write bytes / time
536,633,344 / 861,056 23:07:59 INFO - TinderboxPrint: CPU idle
8,332.3 (87.0%) 23:07:59 INFO - TinderboxPrint: CPU user
1,199.0 (12.5%) 23:07:59 INFO - TinderboxPrint: Swap in / out
0 / 0 23:08:00 INFO - install - Wall time: 17s; CPU: 16.0%; Read bytes: 0; Write bytes: 246571008; Read time: 0; Write time: 557880 23:08:00 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 289447936; Read time: 0; Write time: 302012 23:08:00 INFO - Running post-run listener: _upload_blobber_files 23:08:00 INFO - Blob upload gear active. 23:08:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:08:00 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 23:08:00 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:08:00 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:08:00 INFO - (blobuploader) - INFO - Open directory for files ... 23:08:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:08:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:08:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:01 INFO - (blobuploader) - INFO - Done attempting. 23:08:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:08:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:08:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:07 INFO - (blobuploader) - INFO - Done attempting. 23:08:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:08:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:08:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:08 INFO - (blobuploader) - INFO - Done attempting. 23:08:08 INFO - (blobuploader) - INFO - Iteration through files over. 23:08:08 INFO - Return code: 0 23:08:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:08:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:08:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/84071a71d9532369636d7b97dc21f2f4867cc81b803548583bbec2ed18d7e7cd7288a27dfa2dcc0aa48d6fc919cc3c4b7bbb4e9d28b2ba8974e2e9eec02c246c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/169e76dfe62e2c97ff687312725def8ef47ae098398309c66c74e36e2d8a9f8009781662bbda9df5d435126467692d93c9c8b351b6bcdd76bdd9f1eac7f75dd3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/80e5c635cdf340c087fa5dced9ee8a450e119d7434c400412016e25c2ff46b7dc7e26545f04e8de4796053e0bcf3bcd5380fee307b169ddc09ec181457030838"} 23:08:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:08:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:08:08 INFO - Contents: 23:08:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/84071a71d9532369636d7b97dc21f2f4867cc81b803548583bbec2ed18d7e7cd7288a27dfa2dcc0aa48d6fc919cc3c4b7bbb4e9d28b2ba8974e2e9eec02c246c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/169e76dfe62e2c97ff687312725def8ef47ae098398309c66c74e36e2d8a9f8009781662bbda9df5d435126467692d93c9c8b351b6bcdd76bdd9f1eac7f75dd3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/80e5c635cdf340c087fa5dced9ee8a450e119d7434c400412016e25c2ff46b7dc7e26545f04e8de4796053e0bcf3bcd5380fee307b169ddc09ec181457030838"} 23:08:08 INFO - Running post-run listener: copy_logs_to_upload_dir 23:08:08 INFO - Copying logs to upload dir... 23:08:08 INFO - mkdir: /builds/slave/test/build/upload/logs 23:08:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.121032 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-07-27 23:08:08.291335) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 23:08:08.295412) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775 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-Non-PGO/sha512/84071a71d9532369636d7b97dc21f2f4867cc81b803548583bbec2ed18d7e7cd7288a27dfa2dcc0aa48d6fc919cc3c4b7bbb4e9d28b2ba8974e2e9eec02c246c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/169e76dfe62e2c97ff687312725def8ef47ae098398309c66c74e36e2d8a9f8009781662bbda9df5d435126467692d93c9c8b351b6bcdd76bdd9f1eac7f75dd3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/80e5c635cdf340c087fa5dced9ee8a450e119d7434c400412016e25c2ff46b7dc7e26545f04e8de4796053e0bcf3bcd5380fee307b169ddc09ec181457030838"} build_url:https://queue.taskcluster.net/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011166 build_url: 'https://queue.taskcluster.net/v1/task/Qw0ZbsYkQxyAuyJLRu1cxw/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-Non-PGO/sha512/84071a71d9532369636d7b97dc21f2f4867cc81b803548583bbec2ed18d7e7cd7288a27dfa2dcc0aa48d6fc919cc3c4b7bbb4e9d28b2ba8974e2e9eec02c246c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/169e76dfe62e2c97ff687312725def8ef47ae098398309c66c74e36e2d8a9f8009781662bbda9df5d435126467692d93c9c8b351b6bcdd76bdd9f1eac7f75dd3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/80e5c635cdf340c087fa5dced9ee8a450e119d7434c400412016e25c2ff46b7dc7e26545f04e8de4796053e0bcf3bcd5380fee307b169ddc09ec181457030838"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 23:08:08.320846) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 23:08:08.321202) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1469684723.832786-833221775 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004401 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 23:08:08.406009) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 23:08:08.406349) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 23:08:08.406816) ========= ========= Total master_lag: 0.16 =========