builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-044 starttime: 1465007957.88 results: success (0) buildid: 20160603190727 builduid: b0500c55fdaf4a30984a92dd2b825791 revision: d1c3952c299da44bbb1215d73a5c34118f92279e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 19:39:17.876999) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 19:39:17.877659) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 19:39:17.878083) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 19:39:17.970320) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 19:39:17.970999) ========= 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=574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-03 19:39: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% 218M=0s 2016-06-03 19:39:18 (218 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110360 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 19:39:18.111931) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 19:39:18.112222) ========= 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=574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018635 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 19:39:18.162426) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 19:39:18.162841) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d1c3952c299da44bbb1215d73a5c34118f92279e --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 d1c3952c299da44bbb1215d73a5c34118f92279e --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=574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-03 19:39:18,488 truncating revision to first 12 chars 2016-06-03 19:39:18,488 Setting DEBUG logging. 2016-06-03 19:39:18,488 attempt 1/10 2016-06-03 19:39:18,488 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d1c3952c299d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-03 19:39:18,858 unpacking tar archive at: fx-team-d1c3952c299d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.714077 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 19:39:18.890276) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 19:39:18.890688) ========= script_repo_revision: d1c3952c299da44bbb1215d73a5c34118f92279e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 19:39:18.891106) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 19:39:18.891388) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 19:39:18.901086) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-06-03 19:39:18.901403) ========= /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=574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:39:19 INFO - MultiFileLogger online at 20160603 19:39:19 in /builds/slave/test 19:39: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 19:39:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:39:19 INFO - {'append_to_log': False, 19:39:19 INFO - 'base_work_dir': '/builds/slave/test', 19:39:19 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 19:39:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:39:19 INFO - 'branch': 'Fx-Team-Non-PGO', 19:39:19 INFO - 'buildbot_json_path': 'buildprops.json', 19:39:19 INFO - 'config_files': ('talos/linux_config.py',), 19:39:19 INFO - 'default_actions': ('clobber', 19:39:19 INFO - 'read-buildbot-config', 19:39:19 INFO - 'download-and-extract', 19:39:19 INFO - 'populate-webroot', 19:39:19 INFO - 'create-virtualenv', 19:39:19 INFO - 'install', 19:39:19 INFO - 'run-tests'), 19:39:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:39:19 INFO - 'download_minidump_stackwalk': True, 19:39:19 INFO - 'download_symbols': 'ondemand', 19:39:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:39:19 INFO - 'tooltool.py': '/tools/tooltool.py', 19:39:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:39:19 INFO - '/tools/misc-python/virtualenv.py')}, 19:39:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:39:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:39:19 INFO - 'installer_path': 'installer.exe', 19:39:19 INFO - 'log_level': 'info', 19:39:19 INFO - 'log_name': 'talos', 19:39:19 INFO - 'log_to_console': True, 19:39:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:39:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:39:19 INFO - 'opt_config_files': (), 19:39:19 INFO - 'pip_index': False, 19:39:19 INFO - 'sps_profile': False, 19:39:19 INFO - 'sps_profile_interval': 0, 19:39:19 INFO - 'suite': 'dromaeojs', 19:39:19 INFO - 'system_bits': '32', 19:39:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:39:19 INFO - 'title': 'talos-linux64-ix-044', 19:39:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:39:19 INFO - 'use_talos_json': True, 19:39:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:39:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:39:19 INFO - 'work_dir': 'build'} 19:39:19 INFO - ##### 19:39:19 INFO - ##### Running clobber step. 19:39:19 INFO - ##### 19:39:19 INFO - Running pre-action listener: _resource_record_pre_action 19:39:19 INFO - Running main action method: clobber 19:39:19 INFO - rmtree: /builds/slave/test/build 19:39:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:39:19 INFO - Running post-action listener: _resource_record_post_action 19:39:19 INFO - ##### 19:39:19 INFO - ##### Finished clobber step (success) 19:39:19 INFO - ##### 19:39:19 INFO - ##### 19:39:19 INFO - ##### Running read-buildbot-config step. 19:39:19 INFO - ##### 19:39:19 INFO - Running pre-action listener: _resource_record_pre_action 19:39:19 INFO - Running main action method: read_buildbot_config 19:39:19 INFO - Using buildbot properties: 19:39:19 INFO - { 19:39:19 INFO - "project": "", 19:39:19 INFO - "product": "firefox", 19:39:19 INFO - "script_repo_revision": "production", 19:39:19 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 19:39:19 INFO - "repository": "", 19:39:19 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 19:39:19 INFO - "buildid": "20160603190727", 19:39:19 INFO - "pgo_build": "False", 19:39:19 INFO - "basedir": "/builds/slave/test", 19:39:19 INFO - "buildnumber": 1813, 19:39:19 INFO - "slavename": "talos-linux64-ix-044", 19:39:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:39:19 INFO - "platform": "ubuntu64_hw", 19:39:19 INFO - "scheduler": "tests-fx-team-linux64-talos", 19:39:19 INFO - "branch": "fx-team", 19:39:19 INFO - "repo_path": "integration/fx-team", 19:39:19 INFO - "revision": "d1c3952c299da44bbb1215d73a5c34118f92279e", 19:39:19 INFO - "stage_platform": "linux64", 19:39:19 INFO - "builduid": "b0500c55fdaf4a30984a92dd2b825791", 19:39:19 INFO - "slavebuilddir": "test" 19:39:19 INFO - } 19:39:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 19:39:19 INFO - Running post-action listener: _resource_record_post_action 19:39:19 INFO - ##### 19:39:19 INFO - ##### Finished read-buildbot-config step (success) 19:39:19 INFO - ##### 19:39:19 INFO - ##### 19:39:19 INFO - ##### Running download-and-extract step. 19:39:19 INFO - ##### 19:39:19 INFO - Running pre-action listener: _resource_record_pre_action 19:39:19 INFO - Running main action method: download_and_extract 19:39:19 INFO - mkdir: /builds/slave/test/build/tests 19:39: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')]} 19:39:19 INFO - https://queue.taskcluster.net/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:39:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:39:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:39:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:39:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 19:39:21 INFO - Downloaded 1361 bytes. 19:39:21 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:39:21 INFO - Using the following test package requirements: 19:39:21 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:39:21 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:39:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:39:21 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:39:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:39:21 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:39:21 INFO - u'jsshell-linux-x86_64.zip'], 19:39:21 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:39:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:39:21 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:39:21 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:39:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:39:21 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:39:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:39:21 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:39:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:39:21 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:39:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:39:21 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:39:21 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')]} 19:39:21 INFO - https://queue.taskcluster.net/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:39:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:39:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:39:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:39:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:39:23 INFO - Downloaded 22868152 bytes. 19:39:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:39:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 19:39:24 INFO - Return code: 0 19:39:24 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 19:39:24 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')]} 19:39:24 INFO - https://queue.taskcluster.net/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:39:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:39:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:39:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:39:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:39:29 INFO - Downloaded 22868152 bytes. 19:39:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:39:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 19:39:31 INFO - Return code: 0 19:39:31 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')]} 19:39:31 INFO - https://queue.taskcluster.net/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:39:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:39:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:39:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:39:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:39:32 INFO - Downloaded 10833369 bytes. 19:39:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:39:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 19:39:32 INFO - Return code: 0 19:39:32 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')]} 19:39:32 INFO - https://queue.taskcluster.net/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:39:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:39:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:39:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:39:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 19:39:38 INFO - Downloaded 55178834 bytes. 19:39:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:39:38 INFO - mkdir: /builds/slave/test/properties 19:39:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:39:38 INFO - Writing to file /builds/slave/test/properties/build_url 19:39:38 INFO - Contents: 19:39:38 INFO - build_url:https://queue.taskcluster.net/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:39:39 INFO - Running post-action listener: _resource_record_post_action 19:39:39 INFO - Running post-action listener: set_extra_try_arguments 19:39:39 INFO - ##### 19:39:39 INFO - ##### Finished download-and-extract step (success) 19:39:39 INFO - ##### 19:39:39 INFO - ##### 19:39:39 INFO - ##### Running populate-webroot step. 19:39:39 INFO - ##### 19:39:39 INFO - Running pre-action listener: _resource_record_pre_action 19:39:39 INFO - Running main action method: populate_webroot 19:39:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:39:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:39:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:39:39 INFO - '--tppagecycles', 19:39:39 INFO - '7'], 19:39:39 INFO - 'tests': ['tsvgm']}}, 19:39:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:39:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:39:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:39:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:39:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:39:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:39:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:39:39 INFO - 'talos_options': ['--disable-e10s'], 19:39:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:39:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:39:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:39:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:39:39 INFO - 'talos_options': ['--disable-e10s'], 19:39:39 INFO - 'tests': ['damp', 'tps']}, 19:39:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:39:39 INFO - 'tests': ['damp', 'tps']}, 19:39:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:39:39 INFO - 'tests': ['dromaeo_dom']}, 19:39:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:39:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:39:39 INFO - 'tests': ['a11yr', 19:39:39 INFO - 'ts_paint', 19:39:39 INFO - 'tpaint', 19:39:39 INFO - 'sessionrestore', 19:39:39 INFO - 'sessionrestore_no_auto_restore', 19:39:39 INFO - 'tabpaint']}, 19:39:39 INFO - 'other-e10s': {'tests': ['a11yr', 19:39:39 INFO - 'ts_paint', 19:39:39 INFO - 'tpaint', 19:39:39 INFO - 'sessionrestore', 19:39:39 INFO - 'sessionrestore_no_auto_restore', 19:39:39 INFO - 'tabpaint']}, 19:39:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:39:39 INFO - 'ts_paint', 19:39:39 INFO - 'tpaint', 19:39:39 INFO - 'sessionrestore', 19:39:39 INFO - 'sessionrestore_no_auto_restore', 19:39:39 INFO - 'tabpaint']}, 19:39:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:39:39 INFO - 'ts_paint', 19:39:39 INFO - 'tpaint', 19:39:39 INFO - 'sessionrestore', 19:39:39 INFO - 'sessionrestore_no_auto_restore', 19:39:39 INFO - 'tabpaint']}, 19:39:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:39:39 INFO - 'tests': ['a11yr', 19:39:39 INFO - 'ts_paint', 19:39:39 INFO - 'tpaint', 19:39:39 INFO - 'sessionrestore', 19:39:39 INFO - 'sessionrestore_no_auto_restore', 19:39:39 INFO - 'tabpaint']}, 19:39:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:39:39 INFO - 'tests': ['a11yr', 19:39:39 INFO - 'ts_paint', 19:39:39 INFO - 'tpaint', 19:39:39 INFO - 'sessionrestore', 19:39:39 INFO - 'sessionrestore_no_auto_restore', 19:39:39 INFO - 'tabpaint']}, 19:39:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:39:39 INFO - 'tests': ['tsvgx', 19:39:39 INFO - 'tsvgr_opacity', 19:39:39 INFO - 'tart', 19:39:39 INFO - 'tscrollx', 19:39:39 INFO - 'cart']}, 19:39:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:39:39 INFO - 'tsvgr_opacity', 19:39:39 INFO - 'tart', 19:39:39 INFO - 'tscrollx', 19:39:39 INFO - 'cart']}, 19:39:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:39:39 INFO - 'talos_options': ['--disable-e10s'], 19:39:39 INFO - 'tests': ['tp5o']}, 19:39:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:39:39 INFO - 'tests': ['tp5o']}, 19:39:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:39:39 INFO - 'talos_options': ['--disable-e10s', 19:39:39 INFO - '--xperf_path', 19:39:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:39:39 INFO - 'tests': ['tp5n']}, 19:39:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:39:39 INFO - 'talos_options': ['--xperf_path', 19:39:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:39:39 INFO - 'tests': ['tp5n']}}, 19:39:39 INFO - 'talos.zip': {'path': '', 19:39:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:39:39 INFO - Running post-action listener: _resource_record_post_action 19:39:39 INFO - ##### 19:39:39 INFO - ##### Finished populate-webroot step (success) 19:39:39 INFO - ##### 19:39:39 INFO - ##### 19:39:39 INFO - ##### Running create-virtualenv step. 19:39:39 INFO - ##### 19:39:39 INFO - Running pre-action listener: _resource_record_pre_action 19:39:39 INFO - Running main action method: create_virtualenv 19:39:39 INFO - Creating virtualenv /builds/slave/test/build/venv 19:39:39 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 19:39:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:39:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:39:39 INFO - Using real prefix '/usr' 19:39:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:39:40 INFO - Installing distribute.............................................................................................................................................................................................done. 19:39:42 INFO - Installing pip.................done. 19:39:42 INFO - Return code: 0 19:39:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:39:42 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')]} 19:39:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:39:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:42 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')]} 19:39:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:39:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:42 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 0x7ff6e5ef4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc62c0>, '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 0x1bc71c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc7650>, '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': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', '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 19:39:42 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 19:39:42 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 19:39:42 INFO - Using env: {'DISPLAY': ':0', 19:39:42 INFO - 'HOME': '/home/cltbld', 19:39:42 INFO - 'LANG': 'en_US.UTF-8', 19:39:42 INFO - 'LANGUAGE': 'en_US:en', 19:39:42 INFO - 'LOGNAME': 'cltbld', 19:39:42 INFO - 'MAIL': '/var/mail/cltbld', 19:39:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:39:42 INFO - 'MOZ_NO_REMOTE': '1', 19:39:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:39:42 INFO - 'NO_EM_RESTART': '1', 19:39:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:39:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:39:42 INFO - 'PWD': '/builds/slave/test', 19:39:42 INFO - 'SHELL': '/bin/bash', 19:39:42 INFO - 'SHLVL': '1', 19:39:42 INFO - 'TERM': 'linux', 19:39:42 INFO - 'TMOUT': '86400', 19:39:42 INFO - 'USER': 'cltbld', 19:39:42 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', 19:39:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:39:42 INFO - '_': '/tools/buildbot/bin/python'} 19:39:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:39:42 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 19:39:42 INFO - Cleaning up... 19:39:42 INFO - Return code: 0 19:39:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:39:42 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')]} 19:39:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:39:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:42 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')]} 19:39:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:39:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:42 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 0x7ff6e5ef4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc62c0>, '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 0x1bc71c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc7650>, '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': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', '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 19:39:42 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 19:39:42 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 19:39:42 INFO - Using env: {'DISPLAY': ':0', 19:39:42 INFO - 'HOME': '/home/cltbld', 19:39:42 INFO - 'LANG': 'en_US.UTF-8', 19:39:42 INFO - 'LANGUAGE': 'en_US:en', 19:39:42 INFO - 'LOGNAME': 'cltbld', 19:39:42 INFO - 'MAIL': '/var/mail/cltbld', 19:39:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:39:42 INFO - 'MOZ_NO_REMOTE': '1', 19:39:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:39:42 INFO - 'NO_EM_RESTART': '1', 19:39:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:39:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:39:42 INFO - 'PWD': '/builds/slave/test', 19:39:42 INFO - 'SHELL': '/bin/bash', 19:39:42 INFO - 'SHLVL': '1', 19:39:42 INFO - 'TERM': 'linux', 19:39:42 INFO - 'TMOUT': '86400', 19:39:42 INFO - 'USER': 'cltbld', 19:39:42 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', 19:39:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:39:42 INFO - '_': '/tools/buildbot/bin/python'} 19:39:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:39:42 INFO - Downloading/unpacking psutil>=3.1.1 19:39:42 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 19:39:42 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 19:39:42 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 19:39:42 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 19:39:42 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 19:39:42 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 19:39:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:39:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:39:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:39:44 INFO - Installing collected packages: psutil 19:39:44 INFO - Running setup.py install for psutil 19:39:44 INFO - building 'psutil._psutil_linux' extension 19:39:44 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 19:39:44 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 19:39:44 INFO - building 'psutil._psutil_posix' extension 19:39:44 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 19:39:45 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 19:39:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:39:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:39:45 INFO - Successfully installed psutil 19:39:45 INFO - Cleaning up... 19:39:45 INFO - Return code: 0 19:39:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:39: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')]} 19:39:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:39:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39: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')]} 19:39:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:39:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e5ef4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc62c0>, '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 0x1bc71c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc7650>, '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': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', '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 19:39: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:39: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 mozsystemmonitor==0.3 19:39:45 INFO - Using env: {'DISPLAY': ':0', 19:39:45 INFO - 'HOME': '/home/cltbld', 19:39:45 INFO - 'LANG': 'en_US.UTF-8', 19:39:45 INFO - 'LANGUAGE': 'en_US:en', 19:39:45 INFO - 'LOGNAME': 'cltbld', 19:39:45 INFO - 'MAIL': '/var/mail/cltbld', 19:39:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:39:45 INFO - 'MOZ_NO_REMOTE': '1', 19:39:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:39:45 INFO - 'NO_EM_RESTART': '1', 19:39:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:39:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:39:45 INFO - 'PWD': '/builds/slave/test', 19:39:45 INFO - 'SHELL': '/bin/bash', 19:39:45 INFO - 'SHLVL': '1', 19:39:45 INFO - 'TERM': 'linux', 19:39:45 INFO - 'TMOUT': '86400', 19:39:45 INFO - 'USER': 'cltbld', 19:39:45 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', 19:39:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:39:45 INFO - '_': '/tools/buildbot/bin/python'} 19:39:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:39:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:39: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 19:39: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 19:39: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 19:39: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 19:39: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 19:39: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 19:39:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:39:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:39:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:39:47 INFO - Installing collected packages: mozsystemmonitor 19:39:47 INFO - Running setup.py install for mozsystemmonitor 19:39:47 INFO - Successfully installed mozsystemmonitor 19:39:47 INFO - Cleaning up... 19:39:47 INFO - Return code: 0 19:39:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:39:47 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')]} 19:39:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:39:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:47 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')]} 19:39:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:39:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:47 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 0x7ff6e5ef4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc62c0>, '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 0x1bc71c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc7650>, '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': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', '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 19:39:47 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 19:39:47 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 19:39:47 INFO - Using env: {'DISPLAY': ':0', 19:39:47 INFO - 'HOME': '/home/cltbld', 19:39:47 INFO - 'LANG': 'en_US.UTF-8', 19:39:47 INFO - 'LANGUAGE': 'en_US:en', 19:39:47 INFO - 'LOGNAME': 'cltbld', 19:39:47 INFO - 'MAIL': '/var/mail/cltbld', 19:39:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:39:47 INFO - 'MOZ_NO_REMOTE': '1', 19:39:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:39:47 INFO - 'NO_EM_RESTART': '1', 19:39:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:39:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:39:47 INFO - 'PWD': '/builds/slave/test', 19:39:47 INFO - 'SHELL': '/bin/bash', 19:39:47 INFO - 'SHLVL': '1', 19:39:47 INFO - 'TERM': 'linux', 19:39:47 INFO - 'TMOUT': '86400', 19:39:47 INFO - 'USER': 'cltbld', 19:39:47 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', 19:39:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:39:47 INFO - '_': '/tools/buildbot/bin/python'} 19:39:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:39:47 INFO - Downloading/unpacking jsonschema==2.5.1 19:39: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 19:39: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 19:39: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 19:39: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 19:39: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 19:39: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 19:39:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:39:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:39:49 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 19:39:49 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 19:39:49 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 19:39:49 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 19:39:49 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 19:39:49 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 19:39:49 INFO - Downloading functools32-3.2.3-2.tar.gz 19:39:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:39:49 INFO - warning: no files found matching '*.txt' 19:39:49 INFO - no previously-included directories found matching 'build' 19:39:49 INFO - no previously-included directories found matching 'dist' 19:39:49 INFO - no previously-included directories found matching '.git*' 19:39:49 INFO - Installing collected packages: jsonschema, functools32 19:39:49 INFO - Running setup.py install for functools32 19:39:50 INFO - warning: no files found matching '*.txt' 19:39:50 INFO - no previously-included directories found matching 'build' 19:39:50 INFO - no previously-included directories found matching 'dist' 19:39:50 INFO - no previously-included directories found matching '.git*' 19:39:50 INFO - Successfully installed jsonschema functools32 19:39:50 INFO - Cleaning up... 19:39:50 INFO - Return code: 0 19:39:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:39:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:39:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:39:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:39:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:39:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:50 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 0x7ff6e5ef4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc62c0>, '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 0x1bc71c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc7650>, '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': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', '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 19:39:50 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 19:39:50 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 19:39:50 INFO - Using env: {'DISPLAY': ':0', 19:39:50 INFO - 'HOME': '/home/cltbld', 19:39:50 INFO - 'LANG': 'en_US.UTF-8', 19:39:50 INFO - 'LANGUAGE': 'en_US:en', 19:39:50 INFO - 'LOGNAME': 'cltbld', 19:39:50 INFO - 'MAIL': '/var/mail/cltbld', 19:39:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:39:50 INFO - 'MOZ_NO_REMOTE': '1', 19:39:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:39:50 INFO - 'NO_EM_RESTART': '1', 19:39:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:39:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:39:50 INFO - 'PWD': '/builds/slave/test', 19:39:50 INFO - 'SHELL': '/bin/bash', 19:39:50 INFO - 'SHLVL': '1', 19:39:50 INFO - 'TERM': 'linux', 19:39:50 INFO - 'TMOUT': '86400', 19:39:50 INFO - 'USER': 'cltbld', 19:39:50 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', 19:39:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:39:50 INFO - '_': '/tools/buildbot/bin/python'} 19:39:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:39:50 INFO - Downloading/unpacking blobuploader==1.2.4 19:39: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 19:39: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 19:39: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 19:39: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 19:39: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 19:39: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 19:39:52 INFO - Downloading blobuploader-1.2.4.tar.gz 19:39:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:39:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:39:52 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 19:39:52 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 19:39:52 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 19:39:52 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 19:39:52 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 19:39:52 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 19:39:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:39:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:39:52 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 19:39:52 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 19:39:52 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 19:39:52 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 19:39:52 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 19:39:52 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 19:39:52 INFO - Downloading docopt-0.6.1.tar.gz 19:39:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:39:52 INFO - Installing collected packages: blobuploader, requests, docopt 19:39:52 INFO - Running setup.py install for blobuploader 19:39:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:39:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:39:53 INFO - Running setup.py install for requests 19:39:53 INFO - Running setup.py install for docopt 19:39:53 INFO - Successfully installed blobuploader requests docopt 19:39:53 INFO - Cleaning up... 19:39:53 INFO - Return code: 0 19:39:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:39:53 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')]} 19:39:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:39:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:53 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')]} 19:39:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:39:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:53 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 0x7ff6e5ef4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc62c0>, '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 0x1bc71c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc7650>, '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': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', '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 19:39:53 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 19:39:53 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 19:39:53 INFO - Using env: {'DISPLAY': ':0', 19:39:53 INFO - 'HOME': '/home/cltbld', 19:39:53 INFO - 'LANG': 'en_US.UTF-8', 19:39:53 INFO - 'LANGUAGE': 'en_US:en', 19:39:53 INFO - 'LOGNAME': 'cltbld', 19:39:53 INFO - 'MAIL': '/var/mail/cltbld', 19:39:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:39:53 INFO - 'MOZ_NO_REMOTE': '1', 19:39:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:39:53 INFO - 'NO_EM_RESTART': '1', 19:39:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:39:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:39:53 INFO - 'PWD': '/builds/slave/test', 19:39:53 INFO - 'SHELL': '/bin/bash', 19:39:53 INFO - 'SHLVL': '1', 19:39:53 INFO - 'TERM': 'linux', 19:39:53 INFO - 'TMOUT': '86400', 19:39:53 INFO - 'USER': 'cltbld', 19:39:53 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', 19:39:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:39:53 INFO - '_': '/tools/buildbot/bin/python'} 19:39:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:39:53 INFO - Running setup.py (path:/tmp/pip-uvlEp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:39:53 INFO - Running setup.py (path:/tmp/pip-ZWs713-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:39:53 INFO - Running setup.py (path:/tmp/pip-yNwnKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:39:54 INFO - Running setup.py (path:/tmp/pip-_FunvW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:39:54 INFO - Running setup.py (path:/tmp/pip-jxZ4Mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:39:54 INFO - Running setup.py (path:/tmp/pip-qDm761-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:39:54 INFO - Running setup.py (path:/tmp/pip-KCasg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:39:54 INFO - Running setup.py (path:/tmp/pip-yW9vAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:39:54 INFO - Running setup.py (path:/tmp/pip-lNAc7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:39:54 INFO - Running setup.py (path:/tmp/pip-Pm5_QJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:39:54 INFO - Running setup.py (path:/tmp/pip-EQl7Cj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:39:54 INFO - Running setup.py (path:/tmp/pip-qzI9q4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:39:54 INFO - Running setup.py (path:/tmp/pip-Z9S24P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:39:54 INFO - Running setup.py (path:/tmp/pip-9bcIPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:39:54 INFO - Running setup.py (path:/tmp/pip-nvmSOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:39:54 INFO - Running setup.py (path:/tmp/pip-pP25vg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:39:54 INFO - Running setup.py (path:/tmp/pip-3EYfko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:39:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:39:55 INFO - Running setup.py install for manifestparser 19:39:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:39:55 INFO - Running setup.py install for mozcrash 19:39:55 INFO - Running setup.py install for mozdebug 19:39:55 INFO - Running setup.py install for mozdevice 19:39:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:39:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:39:55 INFO - Running setup.py install for mozfile 19:39:55 INFO - Running setup.py install for mozhttpd 19:39:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:39:55 INFO - Running setup.py install for mozinfo 19:39:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:39:55 INFO - Running setup.py install for mozInstall 19:39:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:39:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:39:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:39:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:39:55 INFO - Running setup.py install for mozleak 19:39:55 INFO - Running setup.py install for mozlog 19:39:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:39:56 INFO - Running setup.py install for moznetwork 19:39:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:39:56 INFO - Running setup.py install for mozprocess 19:39:56 INFO - Running setup.py install for mozprofile 19:39:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:39:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:39:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:39:56 INFO - Running setup.py install for mozrunner 19:39:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:39:56 INFO - Running setup.py install for mozscreenshot 19:39:56 INFO - Running setup.py install for moztest 19:39:56 INFO - Running setup.py install for mozversion 19:39:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:39:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:39:56 INFO - Cleaning up... 19:39:56 INFO - Return code: 0 19:39:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:39:56 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')]} 19:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:39:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:56 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')]} 19:39:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:39:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:56 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 0x7ff6e5ef4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc62c0>, '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 0x1bc71c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc7650>, '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': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', '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 19:39:56 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 19:39:56 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 19:39:56 INFO - Using env: {'DISPLAY': ':0', 19:39:56 INFO - 'HOME': '/home/cltbld', 19:39:56 INFO - 'LANG': 'en_US.UTF-8', 19:39:56 INFO - 'LANGUAGE': 'en_US:en', 19:39:56 INFO - 'LOGNAME': 'cltbld', 19:39:56 INFO - 'MAIL': '/var/mail/cltbld', 19:39:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:39:56 INFO - 'MOZ_NO_REMOTE': '1', 19:39:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:39:56 INFO - 'NO_EM_RESTART': '1', 19:39:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:39:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:39:56 INFO - 'PWD': '/builds/slave/test', 19:39:56 INFO - 'SHELL': '/bin/bash', 19:39:56 INFO - 'SHLVL': '1', 19:39:56 INFO - 'TERM': 'linux', 19:39:56 INFO - 'TMOUT': '86400', 19:39:56 INFO - 'USER': 'cltbld', 19:39:56 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', 19:39:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:39:56 INFO - '_': '/tools/buildbot/bin/python'} 19:39:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:39:57 INFO - Running setup.py (path:/tmp/pip-ruI8j_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:39:57 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)) 19:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:39:57 INFO - Running setup.py (path:/tmp/pip-Qnr88t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:39:57 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)) 19:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:39:57 INFO - Running setup.py (path:/tmp/pip-5F7_E5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:39:57 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)) 19:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:39:57 INFO - Running setup.py (path:/tmp/pip-pHScBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:39:57 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)) 19:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:39:57 INFO - Running setup.py (path:/tmp/pip-UI_9za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:39:57 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)) 19:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:39:57 INFO - Running setup.py (path:/tmp/pip-PsLCTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:39:57 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)) 19:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:39:57 INFO - Running setup.py (path:/tmp/pip-vd8NsP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:39:57 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)) 19:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:39:57 INFO - Running setup.py (path:/tmp/pip-6UfDlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:39:57 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)) 19:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:39:57 INFO - Running setup.py (path:/tmp/pip-YjmuhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:39:57 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)) 19:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:39:57 INFO - Running setup.py (path:/tmp/pip-SWV0CX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 19:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:39:57 INFO - Running setup.py (path:/tmp/pip-9zGAcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:39:57 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)) 19:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:39:57 INFO - Running setup.py (path:/tmp/pip-43zlzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 19:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:39:57 INFO - Running setup.py (path:/tmp/pip-VIcR6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:39:57 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)) 19:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:39:57 INFO - Running setup.py (path:/tmp/pip-LLZ02u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:39:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 19:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:39:58 INFO - Running setup.py (path:/tmp/pip-bYbtuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:39:58 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)) 19:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:39:58 INFO - Running setup.py (path:/tmp/pip-Hog1p1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:39:58 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)) 19:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:39:58 INFO - Running setup.py (path:/tmp/pip-DnAthn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:39:58 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)) 19:39:58 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)) 19:39:58 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)) 19:39:58 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)) 19:39:58 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)) 19:39:58 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)) 19:39:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:39:58 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 19:39:58 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 19:39:58 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 19:39:58 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 19:39:58 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 19:39:58 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 19:40:00 INFO - Downloading blessings-1.6.tar.gz 19:40:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:40:00 INFO - Installing collected packages: blessings 19:40:00 INFO - Running setup.py install for blessings 19:40:00 INFO - Successfully installed blessings 19:40:00 INFO - Cleaning up... 19:40:00 INFO - Return code: 0 19:40:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:40:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:40:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:40:00 INFO - Reading from file tmpfile_stdout 19:40:00 INFO - Current package versions: 19:40:00 INFO - argparse == 1.2.1 19:40:00 INFO - blessings == 1.6 19:40:00 INFO - blobuploader == 1.2.4 19:40:00 INFO - docopt == 0.6.1 19:40:00 INFO - functools32 == 3.2.3-2 19:40:00 INFO - jsonschema == 2.5.1 19:40:00 INFO - manifestparser == 1.1 19:40:00 INFO - mozInstall == 1.12 19:40:00 INFO - mozcrash == 0.17 19:40:00 INFO - mozdebug == 0.1 19:40:00 INFO - mozdevice == 0.48 19:40:00 INFO - mozfile == 1.2 19:40:00 INFO - mozhttpd == 0.7 19:40:00 INFO - mozinfo == 0.9 19:40:00 INFO - mozleak == 0.1 19:40:00 INFO - mozlog == 3.2 19:40:00 INFO - moznetwork == 0.27 19:40:00 INFO - mozprocess == 0.22 19:40:00 INFO - mozprofile == 0.28 19:40:00 INFO - mozrunner == 6.11 19:40:00 INFO - mozscreenshot == 0.1 19:40:00 INFO - mozsystemmonitor == 0.3 19:40:00 INFO - moztest == 0.7 19:40:00 INFO - mozversion == 1.4 19:40:00 INFO - psutil == 3.1.1 19:40:00 INFO - requests == 1.2.3 19:40:00 INFO - wsgiref == 0.1.2 19:40:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:40:00 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')]} 19:40:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:40:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:40:00 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')]} 19:40:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:40:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:40:00 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 0x7ff6e5ef4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc62c0>, '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 0x1bc71c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc7650>, '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': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', '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 19:40:00 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 19:40:00 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 19:40:00 INFO - Using env: {'DISPLAY': ':0', 19:40:00 INFO - 'HOME': '/home/cltbld', 19:40:00 INFO - 'LANG': 'en_US.UTF-8', 19:40:00 INFO - 'LANGUAGE': 'en_US:en', 19:40:00 INFO - 'LOGNAME': 'cltbld', 19:40:00 INFO - 'MAIL': '/var/mail/cltbld', 19:40:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:40:00 INFO - 'MOZ_NO_REMOTE': '1', 19:40:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:40:00 INFO - 'NO_EM_RESTART': '1', 19:40:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:40:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:40:00 INFO - 'PWD': '/builds/slave/test', 19:40:00 INFO - 'SHELL': '/bin/bash', 19:40:00 INFO - 'SHLVL': '1', 19:40:00 INFO - 'TERM': 'linux', 19:40:00 INFO - 'TMOUT': '86400', 19:40:00 INFO - 'USER': 'cltbld', 19:40:00 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', 19:40:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:40:00 INFO - '_': '/tools/buildbot/bin/python'} 19:40:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:40:00 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)) 19:40:00 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)) 19:40:00 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)) 19:40:00 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)) 19:40:00 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)) 19:40:00 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)) 19:40:00 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)) 19:40:00 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)) 19:40:00 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)) 19:40:00 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)) 19:40:00 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)) 19:40:00 INFO - Cleaning up... 19:40:00 INFO - Return code: 0 19:40:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:40:00 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')]} 19:40:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:40:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:40:00 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')]} 19:40:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:40:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:40:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7ff6e5ef4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc62c0>, '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 0x1bc71c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc7650>, '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': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', '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 19:40:00 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 19:40:00 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 19:40:00 INFO - Using env: {'DISPLAY': ':0', 19:40:00 INFO - 'HOME': '/home/cltbld', 19:40:00 INFO - 'LANG': 'en_US.UTF-8', 19:40:00 INFO - 'LANGUAGE': 'en_US:en', 19:40:00 INFO - 'LOGNAME': 'cltbld', 19:40:00 INFO - 'MAIL': '/var/mail/cltbld', 19:40:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:40:00 INFO - 'MOZ_NO_REMOTE': '1', 19:40:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:40:00 INFO - 'NO_EM_RESTART': '1', 19:40:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:40:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:40:00 INFO - 'PWD': '/builds/slave/test', 19:40:00 INFO - 'SHELL': '/bin/bash', 19:40:00 INFO - 'SHLVL': '1', 19:40:00 INFO - 'TERM': 'linux', 19:40:00 INFO - 'TMOUT': '86400', 19:40:00 INFO - 'USER': 'cltbld', 19:40:00 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', 19:40:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:40:00 INFO - '_': '/tools/buildbot/bin/python'} 19:40:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:40:01 INFO - Cleaning up... 19:40:01 INFO - Return code: 0 19:40:01 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:40:01 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')]} 19:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:40:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:40:01 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')]} 19:40:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:40:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:40:01 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e5ef4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc62c0>, '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 0x1bc71c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc7650>, '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': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', '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 19:40:01 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', 'flake8'] in /builds/slave/test/build 19:40:01 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 flake8 19:40:01 INFO - Using env: {'DISPLAY': ':0', 19:40:01 INFO - 'HOME': '/home/cltbld', 19:40:01 INFO - 'LANG': 'en_US.UTF-8', 19:40:01 INFO - 'LANGUAGE': 'en_US:en', 19:40:01 INFO - 'LOGNAME': 'cltbld', 19:40:01 INFO - 'MAIL': '/var/mail/cltbld', 19:40:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:40:01 INFO - 'MOZ_NO_REMOTE': '1', 19:40:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:40:01 INFO - 'NO_EM_RESTART': '1', 19:40:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:40:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:40:01 INFO - 'PWD': '/builds/slave/test', 19:40:01 INFO - 'SHELL': '/bin/bash', 19:40:01 INFO - 'SHLVL': '1', 19:40:01 INFO - 'TERM': 'linux', 19:40:01 INFO - 'TMOUT': '86400', 19:40:01 INFO - 'USER': 'cltbld', 19:40:01 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', 19:40:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:40:01 INFO - '_': '/tools/buildbot/bin/python'} 19:40:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:40:01 INFO - Downloading/unpacking flake8 19:40:01 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 19:40:01 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 19:40:01 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 19:40:01 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 19:40:01 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 19:40:01 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 19:40:03 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:40:03 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:40:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:40:03 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 19:40:03 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 19:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:40:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:40:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:40:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:40:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:40:03 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 19:40:03 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 19:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:40:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:40:03 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:40:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:40:03 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 19:40:03 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 19:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:40:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:40:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:40:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:40:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:40:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:40:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:40:04 INFO - no previously-included directories found matching 'docs/_build' 19:40:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:40:04 INFO - Running setup.py install for pep8 19:40:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:40:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:40:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:40:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:40:04 INFO - no previously-included directories found matching 'docs/_build' 19:40:04 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:40:04 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:40:04 INFO - Cleaning up... 19:40:04 INFO - Return code: 0 19:40:04 INFO - Running post-action listener: _resource_record_post_action 19:40:04 INFO - Running post-action listener: _start_resource_monitoring 19:40:04 INFO - Starting resource monitoring. 19:40:04 INFO - ##### 19:40:04 INFO - ##### Finished create-virtualenv step (success) 19:40:04 INFO - ##### 19:40:04 INFO - ##### 19:40:04 INFO - ##### Running install step. 19:40:04 INFO - ##### 19:40:04 INFO - Running pre-action listener: _resource_record_pre_action 19:40:04 INFO - Running main action method: install 19:40:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:40:04 INFO - Reading from file tmpfile_stdout 19:40:04 INFO - Detecting whether we're running mozinstall >=1.0... 19:40:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:40:04 INFO - Reading from file tmpfile_stdout 19:40:04 INFO - Output received: 19:40:04 INFO - Usage: mozinstall [options] installer 19:40:04 INFO - Options: 19:40:04 INFO - -h, --help show this help message and exit 19:40:04 INFO - -d DEST, --destination=DEST 19:40:04 INFO - Directory to install application into. [default: 19:40:04 INFO - "/builds/slave/test"] 19:40:04 INFO - --app=APP Application being installed. [default: firefox] 19:40:04 INFO - mkdir: /builds/slave/test/build/application 19:40:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:40:20 INFO - Reading from file tmpfile_stdout 19:40:20 INFO - Output received: 19:40:20 INFO - /builds/slave/test/build/application/firefox/firefox 19:40:20 INFO - Running post-action listener: _resource_record_post_action 19:40:20 INFO - ##### 19:40:20 INFO - ##### Finished install step (success) 19:40:20 INFO - ##### 19:40:20 INFO - ##### 19:40:20 INFO - ##### Running run-tests step. 19:40:20 INFO - ##### 19:40:20 INFO - Running pre-action listener: _resource_record_pre_action 19:40:20 INFO - Running main action method: run_tests 19:40:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:40:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:40:20 INFO - Python 2.7.3 19:40:20 INFO - Return code: 0 19:40:20 INFO - grabbing minidump binary from tooltool 19:40:20 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')]} 19:40:20 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 0x1bc62c0>, '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 0x1bc71c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc7650>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:40:20 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 19:40:20 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 19:40:20 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 19:40:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:40:20 INFO - Return code: 0 19:40:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:40:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:40:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:40:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:40:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:40:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:40:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:40:21 INFO - Return code: 0 19:40:21 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/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:40:21 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/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-044 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:40:21 INFO - Using env: {'DISPLAY': ':0', 19:40:21 INFO - 'HOME': '/home/cltbld', 19:40:21 INFO - 'LANG': 'en_US.UTF-8', 19:40:21 INFO - 'LANGUAGE': 'en_US:en', 19:40:21 INFO - 'LOGNAME': 'cltbld', 19:40:21 INFO - 'MAIL': '/var/mail/cltbld', 19:40:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:40:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:40:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:40:21 INFO - 'MOZ_NO_REMOTE': '1', 19:40:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:40:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:40:21 INFO - 'NO_EM_RESTART': '1', 19:40:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:40:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:40:21 INFO - 'PWD': '/builds/slave/test', 19:40:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:40:21 INFO - 'SHELL': '/bin/bash', 19:40:21 INFO - 'SHLVL': '1', 19:40:21 INFO - 'TERM': 'linux', 19:40:21 INFO - 'TMOUT': '86400', 19:40:21 INFO - 'USER': 'cltbld', 19:40:21 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066', 19:40:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:40:21 INFO - '_': '/tools/buildbot/bin/python'} 19:40:21 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/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:40:21 INFO - mozversion application_buildid: 20160603190727 19:40:21 INFO - mozversion application_changeset: d1c3952c299da44bbb1215d73a5c34118f92279e 19:40:21 INFO - mozversion application_display_name: Nightly 19:40:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:40:21 INFO - mozversion application_name: Firefox 19:40:21 INFO - mozversion application_remotingname: firefox 19:40:21 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 19:40:21 INFO - mozversion application_vendor: Mozilla 19:40:21 INFO - mozversion application_version: 49.0a1 19:40:21 INFO - mozversion platform_buildid: 20160603190727 19:40:21 INFO - mozversion platform_changeset: d1c3952c299da44bbb1215d73a5c34118f92279e 19:40:21 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 19:40:21 INFO - mozversion platform_version: 49.0a1 19:40:21 INFO - using testdate: 1465008021 19:40:21 INFO - actual date: 1465008021 19:40:21 INFO - starting webserver on 'localhost:35244' 19:40:21 INFO - SUITE-START | Running 2 tests 19:40:21 INFO - TEST-START | dromaeo_css 19:40:21 INFO - operating with platform_type : linux_ 19:40:21 INFO - Initialising browser for dromaeo_css test... 19:40:21 INFO - TEST-INFO | started process 29226 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiuoklX/profile http://localhost:35244/getInfo.html) 19:40:26 INFO - PROCESS | 29226 | __metrics Screen width/height:1600/1200 19:40:26 INFO - PROCESS | 29226 | colorDepth:24 19:40:26 INFO - PROCESS | 29226 | Browser inner width/height: 1024/697 19:40:26 INFO - PROCESS | 29226 | __metrics 19:40:27 ERROR - PROCESS | 29226 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:40:28 INFO - TEST-INFO | 29226: exit 0 19:40:28 INFO - Browser initialized. 19:40:28 INFO - Running cycle 1/1 for dromaeo_css test... 19:40:28 INFO - TEST-INFO | started process 29308 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiuoklX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:40:40 INFO - PROCESS | 29308 | RSS: Main: 157347840 19:40:40 INFO - PROCESS | 29308 | 19:41:32 INFO - PROCESS | 29308 | 1465008092326 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] 19:43:43 INFO - PROCESS | 29308 | Cycle 1(1): loaded http://localhost:35244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35244/tests/dromaeo/cssquery-ext.html) 19:43:44 INFO - PROCESS | 29308 | RSS: Main: 272195584 19:43:45 INFO - PROCESS | 29308 | 19:46:47 INFO - PROCESS | 29308 | Cycle 1(1): loaded http://localhost:35244/tests/dromaeo/cssquery-ext.html (next: http://localhost:35244/tests/dromaeo/cssquery-jquery.html) 19:46:48 INFO - PROCESS | 29308 | RSS: Main: 242913280 19:46:48 INFO - PROCESS | 29308 | 19:49:50 INFO - PROCESS | 29308 | Cycle 1(1): loaded http://localhost:35244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35244/tests/dromaeo/cssquery-mootools.html) 19:49:51 INFO - PROCESS | 29308 | RSS: Main: 241582080 19:49:51 INFO - PROCESS | 29308 | 19:52:53 INFO - PROCESS | 29308 | Cycle 1(1): loaded http://localhost:35244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35244/tests/dromaeo/cssquery-prototype.html) 19:52:54 INFO - PROCESS | 29308 | RSS: Main: 240238592 19:52:54 INFO - PROCESS | 29308 | 19:55:57 INFO - PROCESS | 29308 | Cycle 1(1): loaded http://localhost:35244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35244/tests/dromaeo/cssquery-yui.html) 19:55:57 INFO - PROCESS | 29308 | RSS: Main: 217817088 19:55:57 INFO - PROCESS | 29308 | 19:59:00 INFO - PROCESS | 29308 | Cycle 1(1): loaded http://localhost:35244/tests/dromaeo/cssquery-yui.html (next: http://localhost:35244/tests/dromaeo/cssquery-dojo.html) 19:59:00 INFO - PROCESS | 29308 | RSS: Main: 283066368 19:59:00 INFO - PROCESS | 29308 | 19:59:00 INFO - PROCESS | 29308 | __start_tp_report 19:59:00 INFO - PROCESS | 29308 | _x_x_mozilla_page_load 19:59:00 INFO - PROCESS | 29308 | _x_x_mozilla_page_load_details 19:59:00 INFO - PROCESS | 29308 | |i|pagename|runs| 19:59:00 INFO - PROCESS | 29308 | |0;dojo.html;2679.129282983031;2682.329417645589;2692.636494073302;2729.6042073899284;2743.739344762248;4545.704529205599;4625.421822272216;4708.623310135185;4753.334533165355;4819.1084649339855;4682.876109733665;4706.8352607658735;4719.6460265480055;4724.716517008982;4732.763361831903;4264.997725534505;4337.566243375653;4374.49693285273;4400.977995110036;4509.751963641998;4410.933835992447;4426.645868330542;4435.334699795024;4461.040876211614;4483.843065492709;4221.113566151112;4255.234057869582;4258.127083947783;4313.051128751701;4362.978185109086;4297.376880352363;4302;4307.3108302671735;4347.043650396908;4350.891227719332;4313.654907607384;4318.913621727579;4325.6106950374615;4356.150550642626;4357.019670574127;4461.06317673289;4638.9072218555775;4645.721256724607;4689.20283551797;4691.296305554194;5088.643794934356;5102.872428189325;5113.181890897438;5146.845594632167;5159.25190847325;5097.490250974873;5114.43741188469;5115.5396014358885;5160.587153027749;5169.715665638389;4676.579107880307;4715.552022557851;4766;4834.564889159992;4835.298881662175;5176.7411629418375;5184.274201611777;5238.2404551340605;5249.895002099936;5266.47335266478;5010.571986983692;5044.924326135111;5047.747612619354;5054.545090941833;5057.620678449131;4962.20604703253;5019.196928491424;5033.345665063518;5071.442141364447;5121.2574176744165;4990.925136122961;5008.499150084962;5014.749262536859;5021.397432308073;5069.391672999263;4919.163742163769;4988.326575912205;4992.975035124801;5003.674761140514;5040.873978150576;4160.292750232467;4193.685473589493;4235.682323825664;4268.274393353137;4269.23153832307;3717.126475278307;3719.795411252407;3731.738778285548;3763.0592351912023;3781.5084039074745;4374.890627734332;4375.978120109379;4379.124175164916;4403.515613282536;4417.933730994037;4992.450830408652;5032.194848824171;5055;5069.39167299919;5092.872678183075;4311.223979683625;4339.1104823511105;4349.369341445536;4349.804258808361;4366.886443956771;4545.477270113913;4559.338895860082;4561.270196768501;4561.543845615478;4605.953940460552;4599.999999999933;4725.905481890274;4769.713817170981;4776.928346074742;4799.352087468287;5088.847334579968;5104.183330667075;5111.923321150111;5123.615728820428;5171.3535808024;4604.3323718061365;4780.784864681167;4781.617470602413;4791.377120974251;4835.58897493717;4964.751762411937;4966.602671786176;4977.552020318189;4993.975030124826;5003.374578177728;5060.898782024413;5068.7972481101815;5073.441921388718;5079.5428411443145;5117.974410128075;4830.275458681226;4894.92657610129;4929.630277729257;4951.72765497901;4987.725675087905;4898;4930;4947.82682606107;4953.801847926043;5006.924896126488;4827.131116399082;4869;4909.214525676018;4909.705417674951;4911.386076740408;4888.315635810918;4931.950680493149;4967.7019378837385;5025.572826309658;5026.597872170145;4827.758612069312;4843.685160464559;4859.028194361213;4885.8045678172475;4895.657303988686 19:59:00 INFO - PROCESS | 29308 | |1;ext.html;550.2131951309626;598.6168851934763;605.1286147946959;605.2071785960395;615.8952977993742;27474.587881181775;39014.21971560571;39991;40053.799731001374;40446.39330410043;38821.61178388215;42419.78790106053;42495.787521062426;42500.787496062476;42547.78726106373;7475.401967842587;7665.846683066336;7824.647890844897;7845.137034926153;9911.950440247789;9626.711198664052;10052.648157314496;10057.396556206651;10060.195184385268;10066.245031622657;10071.194304455663;10144.847827282596;10146.64486742964;10148.847767283496;10149.898501015006;10059.597616095347;10134.239932005055;10140.949295253477;10141.442220677896;10142.087212150942;9931.80136397268;10017.749556261151;10032.247581431422;10040.246981476419;10043.19654427644;38143.80928095342;38635.61364386376;38648;38648.22703545913;38656.61343386558;3083.799553029068;3099.364630250793;3167.3823604397153;3357.3285342931513;3381.2392211752403;29875.25311867199;29914.55128173079;30040.399192016255;30148.84925575358;30562.54156187659;3309.8676052957853;3435.7594968352223;3457.723382129449;3465.203003309228;3496.4755286706945;245075;245271;245891;246001;246100;52176;52625;52650;52667;52815.471845281056;55911.16133258004;56029;56044.71977640086;56089;56116.15825762617;13602.047856649939;13706.862931370759;13768.586942391745;13781.310934453237;13789.51736689231;10768.946155269174;10864.239503234698;10874.836877446845;10878.292910960763;10886.836697449544;15332.156731379659;15878.44425445127;15942.681146377008;15969.121698306704;15995.920020399824;5976.8804623908145;6101.694915254219;6109.419605137505;6119.888638419738;6205.100260462298;823.275517544569;869.3479890082439;884.1556313720511;1050.5482642463662;1057.3972835483855;25447.36381590475;26830;26831.86584067067;26864.86567567189;27191.320216994733;941.0730430525927;948.2840455456087;958.726762872578;1010.545254635417;1022.3252653248969;14495.492657756928;14571.198584077765;14666.486672966395;14884.70230595382;14970.775438368433;371146;374231;374528;375020;375285;49855.252171217464;50035.74910627263;50080.24879626808;50101.74949125231;50198.24702629464;1189.6667269979075;1220.6460126563197;1226.5523084074428;1244.2099266965722;1254.711416374239;26424;28051.158465246077;28348.43303133967;28394.71605283921;28722.569161462176;43107.78446107749;43366;43541.3468797962;43548.56451435572;43619.12761744811;280104;281208;281212;281314;281493;36850.63149368579;36855.44716829196;38210.80894595509;38246.80876595599;38377.232455350175;43029.56970430256;43296;43653.56346436495;44323.33514997213;44576;36268.27463450663;37151.25697485981;37186;37278.627213727515;37718.2456350877;36043.09892252715;36194.819025904704;36910.44634330541;37174.6282537182;37327.626723732414;35806;36564.81717591395;36601;36916.81541592275;37101.6289837109;32155;33275.83362083174;33869.83065084659;33995.320093598486;34174.82912585421;24590.94249448821;24863.502729945663;24902.75097249077;25032;25284.241472755846 19:59:00 INFO - PROCESS | 29308 | |2;jquery.html;343.56048158217084;365.45547134769186;369.5842177550256;370.9870154544591;391.80409795102446;12330.383480825967;15339.846601533995;15872.12703301318;16498.83501164988;17374.391896283632;15229.314680839361;15411.46059887904;15510.30203640836;15650.295736691847;15814.525564233063;4939.53074457926;4944.357233559641;5018.84943451697;5118;5837;5823.388544202861;5824;5864.032434648278;5902.439268269508;5906.320773111082;5745.310562732457;5769.624974376652;5801.216835727165;5836.270466191726;5842.328132264802;5830.504407125378;5860.589758716891;5871.1193321001765;5876.118582212681;5908.231929849114;4914.950850491485;5090.134677104901;5104.25988231708;5104.61715371345;5109.105906466353;11289.209755317132;11893.226940248942;11913.225640333438;19924.800751992585;20150.193992240293;1742.9041402722846;1806.6206096719704;1808.1863161577344;1897.4497395755213;1945.3872030310406;8424.957875210646;9164.584790646573;9321.673741419085;9419.952900235523;9532;1626.6665333606581;1632.428649972512;1646.777685012532;1663.251536808429;1727.9222434990456;65018;68823.65588172077;71937.64031179863;76610;84587.15412845854;8381.832363352698;8750.062471887675;8977.147171018743;9213.723588292361;9627.181689556326;13169.407376668072;13780.242086685328;13797.862021379658;14222.288885555681;14378.496752613608;7035.33164349386;7301.707931682673;7352.448566357438;7453.701851925971;7572.280633339824;5878.147668588031;6785.185777706707;6790.185177778698;6830.658467076626;6990.231074581792;4196;4258.254805409041;4401.889952751207;4459.44256967879;4499.775011249424;1788.2131861980688;1832.1114259584092;1836.392322901483;1850.5466160790693;1919.9040047997544;773.6866569039548;911.2345629670975;924.5469719837366;931.0224264522225;936.6066252174103;15287.770683439534;15782.842171578366;16362.836371636131;16447.09540975258;16711.749323760152;853.5007020892723;873.4671850171391;883.6907082521067;894.6645008121955;895.1853813030242;9011.234045106108;9128.497932613636;9391.483468409304;9506.382085164441;9630.229581633452;377830;379314;379345;386903;388357;74997;75934.24065759272;76369;77535.61232193802;77714;921.8663293822358;934.2759361494896;944.1832814615308;952.547539918547;954.9045095490534;14589.562313130624;14596.301128384957;14670.413183472541;14695.853041469449;15144.394224231348;34075.48886766651;34470.48294275638;34502;35248.647513524535;35310.29379412346;47027;47219;48634.513654862996;49000.754996224794;50008.74995624999;7696.691562434287;7737.723275659453;7781.182975787688;7797.142314345304;7930.841383172419;10048.196144308291;11613.593524226611;11719.707007324543;11760.176787624785;12042.578509752117;9632.518374081408;9985.500724963578;10277.74305642365;10321.277510574188;10505.737356566147;10083.193344532567;10176;10191.541380638037;10334.741631459274;10392.58429662805;9863.506824658882;10084.344517606332;10430.322029068086;10518.263721539724;10691.946540267249;7738.110117336353;7750.689972401041;7779.7666070017995;7795.33739632115;7863.488873223222;9421.104995025462;9450.669226577313;9618.182454491447;9832.852507212254;10472.162227021972 19:59:00 INFO - PROCESS | 29308 | |3;mootools.html;1243.533674871923;1390.7635701930674;1439.0214654035258;1502.181311185404;1752.3078384038313;6554.737810487581;7353.595552244625;7547.886781698266;7554.660040298193;7828.452008359417;7610.657520411581;7635.350995165418;7655.311022008018;7667.348275396584;7688.308052275294;2100.4433825036376;2125.6598944168936;2125.7661657217695;2127.287358734818;2127.712758777561;2116.6507526258147;2127.2022991378235;2129.872207667537;2132.754733205678;2143.6248656485054;2056.1980827477296;2070.1201989154624;2077.3248694174376;2079.469735217525;2094.287942099686;2031.8882461464614;2032.756069271682;2035.7964203579525;2037.7656569494475;2038.3171637501455;2032.5934813037452;2036.9796302037087;2067.462459760458;2068.503559145809;2069.813716765483;7419.888701669479;7604.201558836297;7689.461737678364;7690;7714.074311082706;4419.62433193179;4524.47968483622;4527.4567051953645;4527.705699129546;4531.456225252958;2806.2984253936515;2968.896088636909;3006.5339872319823;3012.5631783391286;3013.7438317743017;888.076400543434;898.1512470715177;901.4230892228978;909.5043201455156;912;10102.39385636864;10219.233557483218;10238.385696858213;10241.385516869012;10256.79486410275;3871.8644847430205;4001.239764444804;4048.7570745755347;4075.021994721305;4084.550699423061;8194.590270486451;8223.95888020556;8252.504849709037;8270;8275.8344833103;4372.475302963665;4388.473383193973;4389.100234451929;4394.384786129945;4397.450318710161;3602.7838329700303;3821.961780382216;3885.0675837798726;3950.861719839792;3976.2842688315804;3243.6918492743152;3279.1474216703828;3371.4100032494216;3372.460406334975;3377.257003459235;940.8965013848472;948.4878165790534;949.4777872170279;962.9133377996014;969.5879251318303;703.5567592416745;827.9586020699062;841.8947631546057;846.1369403208693;871.6339137562239;5133.794648214104;5321.414644389114;5385.165299378603;5537.252470916455;5598.608097433141;876.548577482597;877.8463768840427;894.5661354243186;906.4153620914474;928.9814203716023;3786.1481166737262;3809.29528037314;3812.4281357796;3847.8460861565222;3866.1881004988704;9097.590608422637;9418.858717119248;9475.241980641533;9481.336306458481;9490.288228382899;9094.818103638023;9357.438553686801;9361.391509551857;9370.344075914756;9378.249740020781;6786.287439818748;6878.896816547755;7003.614801185881;7006.194287656883;7016.403605693573;5136.460671629575;5198.662086964335;5293.761780719799;5345.171498417596;5384.757685904222;8181.181881812009;8334.291585215082;8354.12281710436;8384.077751447458;8413.495190288602;8407.495550267004;8498.957505212435;8907;8918.10818918103;8928.73213803587;5812.854678633068;5988.970055149696;6327.96836015817;6472.093906853139;6474.2554606219;6936.167659880846;7207.459440541876;7215.639218038972;7220.133583969957;7224.241454647186;6330;6505.2844187138435;6576;6644.10304608891;6655.301193374819;6238.625682459067;6353.047042943595;6394.26465956421;6477.190351206099;6612.702428390638;6070.271567411939;6105.5420843437805;6131.478824299985;6141.539384546088;6144.354842741626;4550.362949187051;4683.297505374085;4688.484266730588;4695.34265202865;4773.928391074067;4524.208263553931;4596.793144308447;4616.0537089895815;4619.69971951822;4626.1441633297945 19:59:00 INFO - PROCESS | 29308 | |4;prototype.html;290.9054557268888;293.4014610195202;296.45156460547975;298.82220079052956;304;4829.78265978031;4833.419989601244;4833.782479788413;4841.612670986321;4867;4676.39206903103;4709.788059537321;4736.9578692687555;4761.381020467343;4772.713637181763;3570.660787225216;3682.4108142697173;3714.4985426967282;3772.849086036569;3810.8475660973527;3746.3256613809504;3754.2866855297543;3764.4729737836724;3786.848526058954;3789.3368660484307;3534.702443411777;3721.2929543386804;3730.757500762442;3747.388622892155;3747.5877653458097;3621.764585301947;3715.2383761328997;3727.925441491182;3747.2318174774105;3754.5682246541724;3313.4201514735064;3316.9004929852144;3328.683775041367;3340.2484441000825;3343.264481813997;4609.377734005933;4609.67732258742;4610.423697037871;4612.100640375128;4625.352450656911;4012.1775036117524;4228.77365563986;4277.636400905927;4278.935815962763;4289.6997210195295;4965.900681986376;4980.601551875878;4992.775325110379;5001.824936127255;5002.099622068026;3557.2351944331836;3572.1783989682517;3576.749627526074;3577.7853328800097;3583.211693427442;4971.90056198874;5237.397699264595;5263.000059977222;5272.130098533776;5283.577313814924;3614.7831130132276;3632.219072899339;3641.289948460025;3644.6902013328636;3647.854085836537;3694.8522059117336;3698.9273110798104;3699.408094704834;3711.777293362407;3720;4225.471816022997;4239.97880010598;4247.150569886036;4251.404803327537;4276.379924910871;4866.46468888422;4972.577330926912;4974.900501990012;4989.700617962933;5079.796808127633;1250.6310638361706;1268.1440027981075;1278.622806272152;1281.5898912348146;1284.556827894375;797.9281864632211;813.8819871118654;844.8690452979798;857.8713193021097;859.9914000860036;4373.781310934441;4389.4732632083715;4412.470503539532;4433.911321773547;4436.511983681792;5143.588512918957;5168.405633352138;5172.818951336685;5209.348831396075;5219.530242278137;4241.703080784316;4414.035754089399;4428.68999170055;4437.733735975852;4438.667099967451;2749.0207050923304;2757.7380148885823;2758.8620568971874;2761.613374127624;2778.305423644089;4750.596199323097;4822.397200349957;4826.1795494765975;4829.613630909519;4861.6596838220985;4972.726500042532;5214.504622060974;5216.947830521722;5223.302426711334;5232.843014709565;4782.402199725035;4787.61699064081;4800.95199048012;4813.879653008703;4834.685745426536;5060.721660308718;5093.949060509348;5094.2613321068975;5106.234064890147;5108.872278193075;4770.856874293777;5019.297298378303;5029.723365214948;5043.117454445384;5052.368453943257;4741.715497070187;4753.714777113384;4773.928391074067;4779.091972525209;4781.418034261599;3578;3591.622879597709;3602.6037135915553;3609.783412995229;3640.399334109842;4874.610031197425;5145.228215767665;5149.7682604282145;5179.663321884065;5184.922226166686;5030.647854650286;5033.370828646419;5045.394552653705;5058;5061.8734531637;5033.119204139334;5034.974825125997;5039.26930595069;5054.949450505448;5064.518870707277;4690.226112691368;4768.068932082512;4934.5805606523845;4946.975265123651;4949.257611358325;4957.702537847741;5003.54968052877;5060.924086138637;5096.490350964874;5109;4930.975345123252;4931.630127740362;4942.085714142895;4942.75286235694;4973.378327709036 19:59:00 INFO - PROCESS | 29308 | |5;yui.html;644.816227375198;668.4852663449142;671.6373158494416;745.742718762027;745.9333153590363;12000.399980000997;12502;12735.745285094292;12827.358632068383;12841.871581284184;11855.940720296387;11948.342841143756;11980.281183129018;11980.400979951011;12001.879981200185;781.9296263336313;799.4843326054691;801.5591424716412;801.8035581282564;802.9437939344248;629.4964028776983;780.637003793236;789.9763007109767;796.144145044077;806.2622700229313;844.6705784743955;844.8141408890034;845.5349557743217;845.6870957745642;846.551327796269;839.1692224697535;839.8278352937695;840.0297656207091;840.4201101240155;841.1966571923798;486.1929197532115;497.39318032000904;497.4304421437434;497.64667086368723;597.1043434847728;12663.49346026167;13275.86724132756;13324.86675133246;13371;13375.197488150741;11262.493187806569;11731.589394371242;11764.58823941158;12041.036717062614;12093.093018023683;9442.527873606292;9586.185174260196;9731.464769437678;11649.825252621218;11719;4064.3700226464944;4094.242565125461;4124.792795467484;4150.27370210212;4249;59126;59427;59483;59551.702241488514;60421;10291.382517049002;10389.688309350731;10429.217808664229;10465.26743127989;10570.471476426148;17903.910480447776;18032.188551515213;18109.7283540747;18109.818901811075;18366.724499132524;6647.601143931282;6721.978912413487;6728.327167283331;6753.932460675429;6833.4191593715095;3726.1802403471193;3735.9066023349633;3796.1458671798623;3800.3539398302355;3831.731778775459;2884.682684904659;2974.5240761478494;2976.04766474726;2978.7468065214266;2979.8361090139815;519.6180807106773;530.5172293213158;530.891167310708;533.4638688118416;548.9807856725075;485.61151079137255;537.8843548637062;552.1414200917532;556.4963707844407;588.2411688921329;7723.266289702469;7943.364530837519;8015.879761803461;8049.758507244792;8066.7579972600915;467.49510528628997;468.43553518010714;481.38863643172976;496.9340764061069;529.0239508107535;3128.3430479599083;3194.9994774533807;3196.009237136495;3249.8050116993054;3272.3291725196277;89703.55148224217;90598.54700726454;92026;95345.52327238319;95367.52316238513;65530.34469655338;65683;67544.66227668831;76662.61668691621;82237.58881205556;72923.63538182275;74353.25646743571;74742;74761.99999999891;74945.62527187438;8858.15847494487;8879.33404994615;8907.153820387053;8977.236934860348;8978.461292322481;42470.362944555236;42677;43493;43960.34059489174;44089.559104408545;35882.64117358898;36439.81780091082;36475.27049458943;36640;41272.587274126876;11223.831642525533;11401.20191586581;11488.827667584826;11561.30632162073;11803.468843902217;12082;12335.259884406963;12351.567695130987;12572.748545029232;12822;12232.755344892874;12314.076444266895;12466.065045121833;12477.93761031189;12597;11024.338539687644;11227.607033753779;11371.886281137082;11754.29474231549;11762.76474470523;11925.582604609144;12393.256404615751;12791.424385902843;12903.548375807177;12921.741565168455;9422.387544809566;9755.365901216683;10161.644342447978;10311.484425778832;10455.634052808115;9636;9936.701898943043;10253.692389228336;10289.639862604772;10355.94822025885 19:59:00 INFO - PROCESS | 29308 | __end_tp_report 19:59:00 INFO - PROCESS | 29308 | __start_cc_report 19:59:00 INFO - PROCESS | 29308 | _x_x_mozilla_cycle_collect,1098 19:59:00 INFO - PROCESS | 29308 | __end_cc_report 19:59:00 INFO - PROCESS | 29308 | __startTimestamp1465009140787__endTimestamp 19:59:00 INFO - PROCESS | 29308 | 19:59:00 INFO - PROCESS | 29308 | ------- Summary: start ------- 19:59:00 INFO - PROCESS | 29308 | Number of tests: 6 19:59:00 INFO - PROCESS | 29308 | 19:59:00 INFO - PROCESS | 29308 | [#0] dojo.html Cycles:165 Average:4678.88 Median:4827.13 stddev:489.87 (10.1%) stddev-sans-first:465.57 19:59:00 INFO - PROCESS | 29308 | Values: 2679.1 2682.3 2692.6 2729.6 2743.7 4545.7 4625.4 4708.6 4753.3 4819.1 4682.9 4706.8 4719.6 4724.7 4732.8 4265.0 4337.6 4374.5 4401.0 4509.8 4410.9 4426.6 4435.3 4461.0 4483.8 4221.1 4255.2 4258.1 4313.1 4363.0 4297.4 4302.0 4307.3 4347.0 4350.9 4313.7 4318.9 4325.6 4356.2 4357.0 4461.1 4638.9 4645.7 4689.2 4691.3 5088.6 5102.9 5113.2 5146.8 5159.3 5097.5 5114.4 5115.5 5160.6 5169.7 4676.6 4715.6 4766.0 4834.6 4835.3 5176.7 5184.3 5238.2 5249.9 5266.5 5010.6 5044.9 5047.7 5054.5 5057.6 4962.2 5019.2 5033.3 5071.4 5121.3 4990.9 5008.5 5014.7 5021.4 5069.4 4919.2 4988.3 4993.0 5003.7 5040.9 4160.3 4193.7 4235.7 4268.3 4269.2 3717.1 3719.8 3731.7 3763.1 3781.5 4374.9 4376.0 4379.1 4403.5 4417.9 4992.5 5032.2 5055.0 5069.4 5092.9 4311.2 4339.1 4349.4 4349.8 4366.9 4545.5 4559.3 4561.3 4561.5 4606.0 4600.0 4725.9 4769.7 4776.9 4799.4 5088.8 5104.2 5111.9 5123.6 5171.4 4604.3 4780.8 4781.6 4791.4 4835.6 4964.8 4966.6 4977.6 4994.0 5003.4 5060.9 5068.8 5073.4 5079.5 5118.0 4830.3 4894.9 4929.6 4951.7 4987.7 4898.0 4930.0 4947.8 4953.8 5006.9 4827.1 4869.0 4909.2 4909.7 4911.4 4888.3 4932.0 4967.7 5025.6 5026.6 4827.8 4843.7 4859.0 4885.8 4895.7 19:59:00 INFO - PROCESS | 29308 | 19:59:00 INFO - PROCESS | 29308 | [#1] ext.html Cycles:180 Average:46546.74 Median:3461.46 stddev:79992.21 (2310.9%) stddev-sans-first:80142.05 19:59:00 INFO - PROCESS | 29308 | Values: 550.2 598.6 605.1 605.2 615.9 27474.6 39014.2 39991.0 40053.8 40446.4 38821.6 42419.8 42495.8 42500.8 42547.8 7475.4 7665.8 7824.6 7845.1 9912.0 9626.7 10052.6 10057.4 10060.2 10066.2 10071.2 10144.8 10146.6 10148.8 10149.9 10059.6 10134.2 10140.9 10141.4 10142.1 9931.8 10017.7 10032.2 10040.2 10043.2 38143.8 38635.6 38648.0 38648.2 38656.6 3083.8 3099.4 3167.4 3357.3 3381.2 29875.3 29914.6 30040.4 30148.8 30562.5 3309.9 3435.8 3457.7 3465.2 3496.5 245075.0 245271.0 245891.0 246001.0 246100.0 52176.0 52625.0 52650.0 52667.0 52815.5 55911.2 56029.0 56044.7 56089.0 56116.2 13602.0 13706.9 13768.6 13781.3 13789.5 10768.9 10864.2 10874.8 10878.3 10886.8 15332.2 15878.4 15942.7 15969.1 15995.9 5976.9 6101.7 6109.4 6119.9 6205.1 823.3 869.3 884.2 1050.5 1057.4 25447.4 26830.0 26831.9 26864.9 27191.3 941.1 948.3 958.7 1010.5 1022.3 14495.5 14571.2 14666.5 14884.7 14970.8 371146.0 374231.0 374528.0 375020.0 375285.0 49855.3 50035.7 50080.2 50101.7 50198.2 1189.7 1220.6 1226.6 1244.2 1254.7 26424.0 28051.2 28348.4 28394.7 28722.6 43107.8 43366.0 43541.3 43548.6 43619.1 280104.0 281208.0 281212.0 281314.0 281493.0 36850.6 36855.4 38210.8 38246.8 38377.2 43029.6 43296.0 43653.6 44323.3 44576.0 36268.3 37151.3 37186.0 37278.6 37718.2 36043.1 36194.8 36910.4 37174.6 37327.6 35806.0 36564.8 36601.0 36916.8 37101.6 32155.0 33275.8 33869.8 33995.3 34174.8 24590.9 24863.5 24902.8 25032.0 25284.2 19:59:00 INFO - PROCESS | 29308 | 19:59:00 INFO - PROCESS | 29308 | [#2] jquery.html Cycles:180 Average:23767.95 Median:27513.80 stddev:63268.55 (230.0%) stddev-sans-first:63421.58 19:59:00 INFO - PROCESS | 29308 | Values: 343.6 365.5 369.6 371.0 391.8 12330.4 15339.8 15872.1 16498.8 17374.4 15229.3 15411.5 15510.3 15650.3 15814.5 4939.5 4944.4 5018.8 5118.0 5837.0 5823.4 5824.0 5864.0 5902.4 5906.3 5745.3 5769.6 5801.2 5836.3 5842.3 5830.5 5860.6 5871.1 5876.1 5908.2 4915.0 5090.1 5104.3 5104.6 5109.1 11289.2 11893.2 11913.2 19924.8 20150.2 1742.9 1806.6 1808.2 1897.4 1945.4 8425.0 9164.6 9321.7 9420.0 9532.0 1626.7 1632.4 1646.8 1663.3 1727.9 65018.0 68823.7 71937.6 76610.0 84587.2 8381.8 8750.1 8977.1 9213.7 9627.2 13169.4 13780.2 13797.9 14222.3 14378.5 7035.3 7301.7 7352.4 7453.7 7572.3 5878.1 6785.2 6790.2 6830.7 6990.2 4196.0 4258.3 4401.9 4459.4 4499.8 1788.2 1832.1 1836.4 1850.5 1919.9 773.7 911.2 924.5 931.0 936.6 15287.8 15782.8 16362.8 16447.1 16711.7 853.5 873.5 883.7 894.7 895.2 9011.2 9128.5 9391.5 9506.4 9630.2 377830.0 379314.0 379345.0 386903.0 388357.0 74997.0 75934.2 76369.0 77535.6 77714.0 921.9 934.3 944.2 952.5 954.9 14589.6 14596.3 14670.4 14695.9 15144.4 34075.5 34470.5 34502.0 35248.6 35310.3 47027.0 47219.0 48634.5 49000.8 50008.7 7696.7 7737.7 7781.2 7797.1 7930.8 10048.2 11613.6 11719.7 11760.2 12042.6 9632.5 9985.5 10277.7 10321.3 10505.7 10083.2 10176.0 10191.5 10334.7 10392.6 9863.5 10084.3 10430.3 10518.3 10691.9 7738.1 7750.7 7779.8 7795.3 7863.5 9421.1 9450.7 9618.2 9832.9 10472.2 19:59:00 INFO - PROCESS | 29308 | 19:59:00 INFO - PROCESS | 29308 | [#3] mootools.html Cycles:180 Average:4959.10 Median:5900.91 stddev:2748.27 (46.6%) stddev-sans-first:2741.79 19:59:00 INFO - PROCESS | 29308 | Values: 1243.5 1390.8 1439.0 1502.2 1752.3 6554.7 7353.6 7547.9 7554.7 7828.5 7610.7 7635.4 7655.3 7667.3 7688.3 2100.4 2125.7 2125.8 2127.3 2127.7 2116.7 2127.2 2129.9 2132.8 2143.6 2056.2 2070.1 2077.3 2079.5 2094.3 2031.9 2032.8 2035.8 2037.8 2038.3 2032.6 2037.0 2067.5 2068.5 2069.8 7419.9 7604.2 7689.5 7690.0 7714.1 4419.6 4524.5 4527.5 4527.7 4531.5 2806.3 2968.9 3006.5 3012.6 3013.7 888.1 898.2 901.4 909.5 912.0 10102.4 10219.2 10238.4 10241.4 10256.8 3871.9 4001.2 4048.8 4075.0 4084.6 8194.6 8224.0 8252.5 8270.0 8275.8 4372.5 4388.5 4389.1 4394.4 4397.5 3602.8 3822.0 3885.1 3950.9 3976.3 3243.7 3279.1 3371.4 3372.5 3377.3 940.9 948.5 949.5 962.9 969.6 703.6 828.0 841.9 846.1 871.6 5133.8 5321.4 5385.2 5537.3 5598.6 876.5 877.8 894.6 906.4 929.0 3786.1 3809.3 3812.4 3847.8 3866.2 9097.6 9418.9 9475.2 9481.3 9490.3 9094.8 9357.4 9361.4 9370.3 9378.2 6786.3 6878.9 7003.6 7006.2 7016.4 5136.5 5198.7 5293.8 5345.2 5384.8 8181.2 8334.3 8354.1 8384.1 8413.5 8407.5 8499.0 8907.0 8918.1 8928.7 5812.9 5989.0 6328.0 6472.1 6474.3 6936.2 7207.5 7215.6 7220.1 7224.2 6330.0 6505.3 6576.0 6644.1 6655.3 6238.6 6353.0 6394.3 6477.2 6612.7 6070.3 6105.5 6131.5 6141.5 6144.4 4550.4 4683.3 4688.5 4695.3 4773.9 4524.2 4596.8 4616.1 4619.7 4626.1 19:59:00 INFO - PROCESS | 29308 | 19:59:00 INFO - PROCESS | 29308 | [#4] prototype.html Cycles:180 Average:4149.70 Median:4752.16 stddev:1202.06 (25.3%) stddev-sans-first:1170.02 19:59:00 INFO - PROCESS | 29308 | Values: 290.9 293.4 296.5 298.8 304.0 4829.8 4833.4 4833.8 4841.6 4867.0 4676.4 4709.8 4737.0 4761.4 4772.7 3570.7 3682.4 3714.5 3772.8 3810.8 3746.3 3754.3 3764.5 3786.8 3789.3 3534.7 3721.3 3730.8 3747.4 3747.6 3621.8 3715.2 3727.9 3747.2 3754.6 3313.4 3316.9 3328.7 3340.2 3343.3 4609.4 4609.7 4610.4 4612.1 4625.4 4012.2 4228.8 4277.6 4278.9 4289.7 4965.9 4980.6 4992.8 5001.8 5002.1 3557.2 3572.2 3576.7 3577.8 3583.2 4971.9 5237.4 5263.0 5272.1 5283.6 3614.8 3632.2 3641.3 3644.7 3647.9 3694.9 3698.9 3699.4 3711.8 3720.0 4225.5 4240.0 4247.2 4251.4 4276.4 4866.5 4972.6 4974.9 4989.7 5079.8 1250.6 1268.1 1278.6 1281.6 1284.6 797.9 813.9 844.9 857.9 860.0 4373.8 4389.5 4412.5 4433.9 4436.5 5143.6 5168.4 5172.8 5209.3 5219.5 4241.7 4414.0 4428.7 4437.7 4438.7 2749.0 2757.7 2758.9 2761.6 2778.3 4750.6 4822.4 4826.2 4829.6 4861.7 4972.7 5214.5 5216.9 5223.3 5232.8 4782.4 4787.6 4801.0 4813.9 4834.7 5060.7 5093.9 5094.3 5106.2 5108.9 4770.9 5019.3 5029.7 5043.1 5052.4 4741.7 4753.7 4773.9 4779.1 4781.4 3578.0 3591.6 3602.6 3609.8 3640.4 4874.6 5145.2 5149.8 5179.7 5184.9 5030.6 5033.4 5045.4 5058.0 5061.9 5033.1 5035.0 5039.3 5054.9 5064.5 4690.2 4768.1 4934.6 4947.0 4949.3 4957.7 5003.5 5060.9 5096.5 5109.0 4931.0 4931.6 4942.1 4942.8 4973.4 19:59:00 INFO - PROCESS | 29308 | 19:59:00 INFO - PROCESS | 29308 | [#5] yui.html Cycles:180 Average:16428.81 Median:43726.67 stddev:22867.96 (52.3%) stddev-sans-first:22901.40 19:59:00 INFO - PROCESS | 29308 | Values: 644.8 668.5 671.6 745.7 745.9 12000.4 12502.0 12735.7 12827.4 12841.9 11855.9 11948.3 11980.3 11980.4 12001.9 781.9 799.5 801.6 801.8 802.9 629.5 780.6 790.0 796.1 806.3 844.7 844.8 845.5 845.7 846.6 839.2 839.8 840.0 840.4 841.2 486.2 497.4 497.4 497.6 597.1 12663.5 13275.9 13324.9 13371.0 13375.2 11262.5 11731.6 11764.6 12041.0 12093.1 9442.5 9586.2 9731.5 11649.8 11719.0 4064.4 4094.2 4124.8 4150.3 4249.0 59126.0 59427.0 59483.0 59551.7 60421.0 10291.4 10389.7 10429.2 10465.3 10570.5 17903.9 18032.2 18109.7 18109.8 18366.7 6647.6 6722.0 6728.3 6753.9 6833.4 3726.2 3735.9 3796.1 3800.4 3831.7 2884.7 2974.5 2976.0 2978.7 2979.8 519.6 530.5 530.9 533.5 549.0 485.6 537.9 552.1 556.5 588.2 7723.3 7943.4 8015.9 8049.8 8066.8 467.5 468.4 481.4 496.9 529.0 3128.3 3195.0 3196.0 3249.8 3272.3 89703.6 90598.5 92026.0 95345.5 95367.5 65530.3 65683.0 67544.7 76662.6 82237.6 72923.6 74353.3 74742.0 74762.0 74945.6 8858.2 8879.3 8907.2 8977.2 8978.5 42470.4 42677.0 43493.0 43960.3 44089.6 35882.6 36439.8 36475.3 36640.0 41272.6 11223.8 11401.2 11488.8 11561.3 11803.5 12082.0 12335.3 12351.6 12572.7 12822.0 12232.8 12314.1 12466.1 12477.9 12597.0 11024.3 11227.6 11371.9 11754.3 11762.8 11925.6 12393.3 12791.4 12903.5 12921.7 9422.4 9755.4 10161.6 10311.5 10455.6 9636.0 9936.7 10253.7 10289.6 10355.9 19:59:00 INFO - PROCESS | 29308 | -------- Summary: end -------- 19:59:00 INFO - PROCESS | 29308 | 19:59:01 INFO - TEST-INFO | 29308: exit 0 19:59:01 INFO - TEST-OK | dromaeo_css | took 1119711ms 19:59:01 INFO - TEST-START | kraken 19:59:01 INFO - operating with platform_type : linux_ 19:59:01 INFO - Initialising browser for kraken test... 19:59:01 INFO - TEST-INFO | started process 29488 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkfJ2tF/profile http://localhost:35244/getInfo.html) 19:59:06 INFO - PROCESS | 29488 | __metrics Screen width/height:1600/1200 19:59:06 INFO - PROCESS | 29488 | colorDepth:24 19:59:06 INFO - PROCESS | 29488 | Browser inner width/height: 1024/697 19:59:06 INFO - PROCESS | 29488 | __metrics 19:59:07 ERROR - PROCESS | 29488 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:59:08 INFO - TEST-INFO | 29488: exit 0 19:59:08 INFO - Browser initialized. 19:59:08 INFO - Running cycle 1/1 for kraken test... 19:59:08 INFO - TEST-INFO | started process 29570 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkfJ2tF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:59:20 INFO - PROCESS | 29570 | RSS: Main: 157736960 19:59:20 INFO - PROCESS | 29570 | 19:59:22 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:35244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35244/tests/kraken/driver.html?testName=audio-beat-detection) 19:59:23 INFO - PROCESS | 29570 | RSS: Main: 215191552 19:59:23 INFO - PROCESS | 29570 | 19:59:26 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:35244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35244/tests/kraken/driver.html?testName=audio-dft) 19:59:27 INFO - PROCESS | 29570 | RSS: Main: 202190848 19:59:27 INFO - PROCESS | 29570 | 19:59:30 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:35244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35244/tests/kraken/driver.html?testName=audio-fft) 19:59:31 INFO - PROCESS | 29570 | RSS: Main: 270667776 19:59:31 INFO - PROCESS | 29570 | 19:59:33 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:35244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35244/tests/kraken/driver.html?testName=audio-oscillator) 19:59:33 INFO - PROCESS | 29570 | RSS: Main: 180637696 19:59:33 INFO - PROCESS | 29570 | 19:59:36 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:35244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:59:36 INFO - PROCESS | 29570 | RSS: Main: 183758848 19:59:36 INFO - PROCESS | 29570 | 19:59:39 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:35244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35244/tests/kraken/driver.html?testName=imaging-darkroom) 19:59:40 INFO - PROCESS | 29570 | RSS: Main: 220557312 19:59:40 INFO - PROCESS | 29570 | 19:59:43 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:35244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35244/tests/kraken/driver.html?testName=imaging-desaturate) 19:59:44 INFO - PROCESS | 29570 | RSS: Main: 216780800 19:59:44 INFO - PROCESS | 29570 | 19:59:47 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:35244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35244/tests/kraken/driver.html?testName=json-parse-financial) 19:59:48 INFO - PROCESS | 29570 | RSS: Main: 219299840 19:59:48 INFO - PROCESS | 29570 | 19:59:49 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:35244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:59:50 INFO - PROCESS | 29570 | RSS: Main: 207663104 19:59:50 INFO - PROCESS | 29570 | 19:59:52 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:35244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35244/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:59:52 INFO - PROCESS | 29570 | RSS: Main: 208138240 19:59:52 INFO - PROCESS | 29570 | 19:59:55 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:35244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:59:55 INFO - PROCESS | 29570 | RSS: Main: 219394048 19:59:55 INFO - PROCESS | 29570 | 19:59:58 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:35244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:59:59 INFO - PROCESS | 29570 | RSS: Main: 223899648 19:59:59 INFO - PROCESS | 29570 | 20:00:02 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:35244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:00:02 INFO - PROCESS | 29570 | RSS: Main: 220798976 20:00:02 INFO - PROCESS | 29570 | 20:00:04 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:35244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35244/tests/kraken/driver.html?testName=ai-astar) 20:00:04 INFO - PROCESS | 29570 | RSS: Main: 262737920 20:00:04 INFO - PROCESS | 29570 | 20:00:04 INFO - PROCESS | 29570 | __start_tp_report 20:00:04 INFO - PROCESS | 29570 | _x_x_mozilla_page_load 20:00:04 INFO - PROCESS | 29570 | _x_x_mozilla_page_load_details 20:00:04 INFO - PROCESS | 29570 | |i|pagename|runs| 20:00:04 INFO - PROCESS | 29570 | |0;ai-astar;112;107;115;113;112;111;112;112;113;112 20:00:04 INFO - PROCESS | 29570 | |1;audio-beat-detection;162;131;133;180;138;156;154;130;182;129 20:00:04 INFO - PROCESS | 29570 | |2;audio-dft;162;162;160;161;162;160;162;163;160;159 20:00:04 INFO - PROCESS | 29570 | |3;audio-fft;75;75;75;114;77;75;73;76;98;75 20:00:04 INFO - PROCESS | 29570 | |4;audio-oscillator;88;88;117;87;86;86;129;88;86;123 20:00:04 INFO - PROCESS | 29570 | |5;imaging-gaussian-blur;117;117;117;117;117;117;116;117;117;116 20:00:04 INFO - PROCESS | 29570 | |6;imaging-darkroom;147;147;146;146;146;146;146;146;146;146 20:00:04 INFO - PROCESS | 29570 | |7;imaging-desaturate;96;96;96;95;96;96;96;96;96;95 20:00:04 INFO - PROCESS | 29570 | |8;json-parse-financial;65;63;65;64;65;65;65;64;63;64 20:00:04 INFO - PROCESS | 29570 | |9;json-stringify-tinderbox;67;66;66;68;67;66;68;66;67;66 20:00:04 INFO - PROCESS | 29570 | |10;stanford-crypto-aes;82;83;82;84;85;85;84;93;117;97 20:00:04 INFO - PROCESS | 29570 | |11;stanford-crypto-ccm;132;135;129;133;133;129;137;132;135;135 20:00:04 INFO - PROCESS | 29570 | |12;stanford-crypto-pbkdf2;169;167;168;169;167;166;171;173;166;170 20:00:04 INFO - PROCESS | 29570 | |13;stanford-crypto-sha256-iterative;67;67;68;71;66;67;69;66;68;66 20:00:04 INFO - PROCESS | 29570 | __end_tp_report 20:00:04 INFO - PROCESS | 29570 | __start_cc_report 20:00:04 INFO - PROCESS | 29570 | _x_x_mozilla_cycle_collect,1881 20:00:04 INFO - PROCESS | 29570 | __end_cc_report 20:00:04 INFO - PROCESS | 29570 | __startTimestamp1465009204729__endTimestamp 20:00:04 INFO - PROCESS | 29570 | 20:00:04 INFO - PROCESS | 29570 | ------- Summary: start ------- 20:00:04 INFO - PROCESS | 29570 | Number of tests: 14 20:00:04 INFO - PROCESS | 29570 | 20:00:04 INFO - PROCESS | 29570 | [#0] ai-astar Cycles:10 Average:111.90 Median:112.00 stddev:2.02 (1.8%) stddev-sans-first:2.15 20:00:04 INFO - PROCESS | 29570 | Values: 112.0 107.0 115.0 113.0 112.0 111.0 112.0 112.0 113.0 112.0 20:00:04 INFO - PROCESS | 29570 | 20:00:04 INFO - PROCESS | 29570 | [#1] audio-beat-detection Cycles:10 Average:149.50 Median:155.00 stddev:20.42 (13.2%) stddev-sans-first:21.15 20:00:04 INFO - PROCESS | 29570 | Values: 162.0 131.0 133.0 180.0 138.0 156.0 154.0 130.0 182.0 129.0 20:00:04 INFO - PROCESS | 29570 | 20:00:04 INFO - PROCESS | 29570 | [#2] audio-dft Cycles:10 Average:161.10 Median:162.00 stddev:1.29 (0.8%) stddev-sans-first:1.32 20:00:04 INFO - PROCESS | 29570 | Values: 162.0 162.0 160.0 161.0 162.0 160.0 162.0 163.0 160.0 159.0 20:00:04 INFO - PROCESS | 29570 | 20:00:04 INFO - PROCESS | 29570 | [#3] audio-fft Cycles:10 Average:81.30 Median:75.00 stddev:13.59 (18.1%) stddev-sans-first:14.22 20:00:04 INFO - PROCESS | 29570 | Values: 75.0 75.0 75.0 114.0 77.0 75.0 73.0 76.0 98.0 75.0 20:00:04 INFO - PROCESS | 29570 | 20:00:04 INFO - PROCESS | 29570 | [#4] audio-oscillator Cycles:10 Average:97.80 Median:86.50 stddev:17.64 (20.4%) stddev-sans-first:18.35 20:00:04 INFO - PROCESS | 29570 | Values: 88.0 88.0 117.0 87.0 86.0 86.0 129.0 88.0 86.0 123.0 20:00:04 INFO - PROCESS | 29570 | 20:00:04 INFO - PROCESS | 29570 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 20:00:04 INFO - PROCESS | 29570 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 20:00:04 INFO - PROCESS | 29570 | 20:00:04 INFO - PROCESS | 29570 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.33 20:00:04 INFO - PROCESS | 29570 | Values: 147.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 20:00:04 INFO - PROCESS | 29570 | 20:00:04 INFO - PROCESS | 29570 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 20:00:04 INFO - PROCESS | 29570 | Values: 96.0 96.0 96.0 95.0 96.0 96.0 96.0 96.0 96.0 95.0 20:00:04 INFO - PROCESS | 29570 | 20:00:04 INFO - PROCESS | 29570 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:65.00 stddev:0.82 (1.3%) stddev-sans-first:0.83 20:00:04 INFO - PROCESS | 29570 | Values: 65.0 63.0 65.0 64.0 65.0 65.0 65.0 64.0 63.0 64.0 20:00:04 INFO - PROCESS | 29570 | 20:00:04 INFO - PROCESS | 29570 | [#9] json-stringify-tinderbox Cycles:10 Average:66.70 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 20:00:04 INFO - PROCESS | 29570 | Values: 67.0 66.0 66.0 68.0 67.0 66.0 68.0 66.0 67.0 66.0 20:00:04 INFO - PROCESS | 29570 | 20:00:04 INFO - PROCESS | 29570 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:84.50 stddev:10.95 (13.0%) stddev-sans-first:11.30 20:00:04 INFO - PROCESS | 29570 | Values: 82.0 83.0 82.0 84.0 85.0 85.0 84.0 93.0 117.0 97.0 20:00:04 INFO - PROCESS | 29570 | 20:00:04 INFO - PROCESS | 29570 | [#11] stanford-crypto-ccm Cycles:10 Average:133.00 Median:134.00 stddev:2.62 (2.0%) stddev-sans-first:2.76 20:00:04 INFO - PROCESS | 29570 | Values: 132.0 135.0 129.0 133.0 133.0 129.0 137.0 132.0 135.0 135.0 20:00:04 INFO - PROCESS | 29570 | 20:00:04 INFO - PROCESS | 29570 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.60 Median:169.00 stddev:2.27 (1.3%) stddev-sans-first:2.40 20:00:04 INFO - PROCESS | 29570 | Values: 169.0 167.0 168.0 169.0 167.0 166.0 171.0 173.0 166.0 170.0 20:00:04 INFO - PROCESS | 29570 | 20:00:04 INFO - PROCESS | 29570 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.50 stddev:1.58 (2.3%) stddev-sans-first:1.67 20:00:04 INFO - PROCESS | 29570 | Values: 67.0 67.0 68.0 71.0 66.0 67.0 69.0 66.0 68.0 66.0 20:00:04 INFO - PROCESS | 29570 | -------- Summary: end -------- 20:00:04 INFO - PROCESS | 29570 | 20:00:05 INFO - TEST-INFO | 29570: exit 0 20:00:05 INFO - TEST-OK | kraken | took 64137ms 20:00:05 INFO - SUITE-END | took 1183s 20:00:05 INFO - Completed test suite (00:19:43) 20:00:05 INFO - javascript benchmark 20:00:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2679.129282983031, 2682.329417645589, 2692.636494073302, 2729.6042073899284, 2743.739344762248, 4545.704529205599, 4625.421822272216, 4708.623310135185, 4753.334533165355, 4819.1084649339855, 4682.876109733665, 4706.8352607658735, 4719.6460265480055, 4724.716517008982, 4732.763361831903, 4264.997725534505, 4337.566243375653, 4374.49693285273, 4400.977995110036, 4509.751963641998, 4410.933835992447, 4426.645868330542, 4435.334699795024, 4461.040876211614, 4483.843065492709, 4221.113566151112, 4255.234057869582, 4258.127083947783, 4313.051128751701, 4362.978185109086, 4297.376880352363, 4302.0, 4307.3108302671735, 4347.043650396908, 4350.891227719332, 4313.654907607384, 4318.913621727579, 4325.6106950374615, 4356.150550642626, 4357.019670574127, 4461.06317673289, 4638.9072218555775, 4645.721256724607, 4689.20283551797, 4691.296305554194, 5088.643794934356, 5102.872428189325, 5113.181890897438, 5146.845594632167, 5159.25190847325, 5097.490250974873, 5114.43741188469, 5115.5396014358885, 5160.587153027749, 5169.715665638389, 4676.579107880307, 4715.552022557851, 4766.0, 4834.564889159992, 4835.298881662175, 5176.7411629418375, 5184.274201611777, 5238.2404551340605, 5249.895002099936, 5266.47335266478, 5010.571986983692, 5044.924326135111, 5047.747612619354, 5054.545090941833, 5057.620678449131, 4962.20604703253, 5019.196928491424, 5033.345665063518, 5071.442141364447, 5121.2574176744165, 4990.925136122961, 5008.499150084962, 5014.749262536859, 5021.397432308073, 5069.391672999263, 4919.163742163769, 4988.326575912205, 4992.975035124801, 5003.674761140514, 5040.873978150576, 4160.292750232467, 4193.685473589493, 4235.682323825664, 4268.274393353137, 4269.23153832307, 3717.126475278307, 3719.795411252407, 3731.738778285548, 3763.0592351912023, 3781.5084039074745, 4374.890627734332, 4375.978120109379, 4379.124175164916, 4403.515613282536, 4417.933730994037, 4992.450830408652, 5032.194848824171, 5055.0, 5069.39167299919, 5092.872678183075, 4311.223979683625, 4339.1104823511105, 4349.369341445536, 4349.804258808361, 4366.886443956771, 4545.477270113913, 4559.338895860082, 4561.270196768501, 4561.543845615478, 4605.953940460552, 4599.999999999933, 4725.905481890274, 4769.713817170981, 4776.928346074742, 4799.352087468287, 5088.847334579968, 5104.183330667075, 5111.923321150111, 5123.615728820428, 5171.3535808024, 4604.3323718061365, 4780.784864681167, 4781.617470602413, 4791.377120974251, 4835.58897493717, 4964.751762411937, 4966.602671786176, 4977.552020318189, 4993.975030124826, 5003.374578177728, 5060.898782024413, 5068.7972481101815, 5073.441921388718, 5079.5428411443145, 5117.974410128075, 4830.275458681226, 4894.92657610129, 4929.630277729257, 4951.72765497901, 4987.725675087905, 4898.0, 4930.0, 4947.82682606107, 4953.801847926043, 5006.924896126488, 4827.131116399082, 4869.0, 4909.214525676018, 4909.705417674951, 4911.386076740408, 4888.315635810918, 4931.950680493149, 4967.7019378837385, 5025.572826309658, 5026.597872170145, 4827.758612069312, 4843.685160464559, 4859.028194361213, 4885.8045678172475, 4895.657303988686], "value": 4648.19450170148, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.2131951309626, 598.6168851934763, 605.1286147946959, 605.2071785960395, 615.8952977993742, 27474.587881181775, 39014.21971560571, 39991.0, 40053.799731001374, 40446.39330410043, 38821.61178388215, 42419.78790106053, 42495.787521062426, 42500.787496062476, 42547.78726106373, 7475.401967842587, 7665.846683066336, 7824.647890844897, 7845.137034926153, 9911.950440247789, 9626.711198664052, 10052.648157314496, 10057.396556206651, 10060.195184385268, 10066.245031622657, 10071.194304455663, 10144.847827282596, 10146.64486742964, 10148.847767283496, 10149.898501015006, 10059.597616095347, 10134.239932005055, 10140.949295253477, 10141.442220677896, 10142.087212150942, 9931.80136397268, 10017.749556261151, 10032.247581431422, 10040.246981476419, 10043.19654427644, 38143.80928095342, 38635.61364386376, 38648.0, 38648.22703545913, 38656.61343386558, 3083.799553029068, 3099.364630250793, 3167.3823604397153, 3357.3285342931513, 3381.2392211752403, 29875.25311867199, 29914.55128173079, 30040.399192016255, 30148.84925575358, 30562.54156187659, 3309.8676052957853, 3435.7594968352223, 3457.723382129449, 3465.203003309228, 3496.4755286706945, 245075.0, 245271.0, 245891.0, 246001.0, 246100.0, 52176.0, 52625.0, 52650.0, 52667.0, 52815.471845281056, 55911.16133258004, 56029.0, 56044.71977640086, 56089.0, 56116.15825762617, 13602.047856649939, 13706.862931370759, 13768.586942391745, 13781.310934453237, 13789.51736689231, 10768.946155269174, 10864.239503234698, 10874.836877446845, 10878.292910960763, 10886.836697449544, 15332.156731379659, 15878.44425445127, 15942.681146377008, 15969.121698306704, 15995.920020399824, 5976.8804623908145, 6101.694915254219, 6109.419605137505, 6119.888638419738, 6205.100260462298, 823.275517544569, 869.3479890082439, 884.1556313720511, 1050.5482642463662, 1057.3972835483855, 25447.36381590475, 26830.0, 26831.86584067067, 26864.86567567189, 27191.320216994733, 941.0730430525927, 948.2840455456087, 958.726762872578, 1010.545254635417, 1022.3252653248969, 14495.492657756928, 14571.198584077765, 14666.486672966395, 14884.70230595382, 14970.775438368433, 371146.0, 374231.0, 374528.0, 375020.0, 375285.0, 49855.252171217464, 50035.74910627263, 50080.24879626808, 50101.74949125231, 50198.24702629464, 1189.6667269979075, 1220.6460126563197, 1226.5523084074428, 1244.2099266965722, 1254.711416374239, 26424.0, 28051.158465246077, 28348.43303133967, 28394.71605283921, 28722.569161462176, 43107.78446107749, 43366.0, 43541.3468797962, 43548.56451435572, 43619.12761744811, 280104.0, 281208.0, 281212.0, 281314.0, 281493.0, 36850.63149368579, 36855.44716829196, 38210.80894595509, 38246.80876595599, 38377.232455350175, 43029.56970430256, 43296.0, 43653.56346436495, 44323.33514997213, 44576.0, 36268.27463450663, 37151.25697485981, 37186.0, 37278.627213727515, 37718.2456350877, 36043.09892252715, 36194.819025904704, 36910.44634330541, 37174.6282537182, 37327.626723732414, 35806.0, 36564.81717591395, 36601.0, 36916.81541592275, 37101.6289837109, 32155.0, 33275.83362083174, 33869.83065084659, 33995.320093598486, 34174.82912585421, 24590.94249448821, 24863.502729945663, 24902.75097249077, 25032.0, 25284.241472755846], "value": 18132.008278656274, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.56048158217084, 365.45547134769186, 369.5842177550256, 370.9870154544591, 391.80409795102446, 12330.383480825967, 15339.846601533995, 15872.12703301318, 16498.83501164988, 17374.391896283632, 15229.314680839361, 15411.46059887904, 15510.30203640836, 15650.295736691847, 15814.525564233063, 4939.53074457926, 4944.357233559641, 5018.84943451697, 5118.0, 5837.0, 5823.388544202861, 5824.0, 5864.032434648278, 5902.439268269508, 5906.320773111082, 5745.310562732457, 5769.624974376652, 5801.216835727165, 5836.270466191726, 5842.328132264802, 5830.504407125378, 5860.589758716891, 5871.1193321001765, 5876.118582212681, 5908.231929849114, 4914.950850491485, 5090.134677104901, 5104.25988231708, 5104.61715371345, 5109.105906466353, 11289.209755317132, 11893.226940248942, 11913.225640333438, 19924.800751992585, 20150.193992240293, 1742.9041402722846, 1806.6206096719704, 1808.1863161577344, 1897.4497395755213, 1945.3872030310406, 8424.957875210646, 9164.584790646573, 9321.673741419085, 9419.952900235523, 9532.0, 1626.6665333606581, 1632.428649972512, 1646.777685012532, 1663.251536808429, 1727.9222434990456, 65018.0, 68823.65588172077, 71937.64031179863, 76610.0, 84587.15412845854, 8381.832363352698, 8750.062471887675, 8977.147171018743, 9213.723588292361, 9627.181689556326, 13169.407376668072, 13780.242086685328, 13797.862021379658, 14222.288885555681, 14378.496752613608, 7035.33164349386, 7301.707931682673, 7352.448566357438, 7453.701851925971, 7572.280633339824, 5878.147668588031, 6785.185777706707, 6790.185177778698, 6830.658467076626, 6990.231074581792, 4196.0, 4258.254805409041, 4401.889952751207, 4459.44256967879, 4499.775011249424, 1788.2131861980688, 1832.1114259584092, 1836.392322901483, 1850.5466160790693, 1919.9040047997544, 773.6866569039548, 911.2345629670975, 924.5469719837366, 931.0224264522225, 936.6066252174103, 15287.770683439534, 15782.842171578366, 16362.836371636131, 16447.09540975258, 16711.749323760152, 853.5007020892723, 873.4671850171391, 883.6907082521067, 894.6645008121955, 895.1853813030242, 9011.234045106108, 9128.497932613636, 9391.483468409304, 9506.382085164441, 9630.229581633452, 377830.0, 379314.0, 379345.0, 386903.0, 388357.0, 74997.0, 75934.24065759272, 76369.0, 77535.61232193802, 77714.0, 921.8663293822358, 934.2759361494896, 944.1832814615308, 952.547539918547, 954.9045095490534, 14589.562313130624, 14596.301128384957, 14670.413183472541, 14695.853041469449, 15144.394224231348, 34075.48886766651, 34470.48294275638, 34502.0, 35248.647513524535, 35310.29379412346, 47027.0, 47219.0, 48634.513654862996, 49000.754996224794, 50008.74995624999, 7696.691562434287, 7737.723275659453, 7781.182975787688, 7797.142314345304, 7930.841383172419, 10048.196144308291, 11613.593524226611, 11719.707007324543, 11760.176787624785, 12042.578509752117, 9632.518374081408, 9985.500724963578, 10277.74305642365, 10321.277510574188, 10505.737356566147, 10083.193344532567, 10176.0, 10191.541380638037, 10334.741631459274, 10392.58429662805, 9863.506824658882, 10084.344517606332, 10430.322029068086, 10518.263721539724, 10691.946540267249, 7738.110117336353, 7750.689972401041, 7779.7666070017995, 7795.33739632115, 7863.488873223222, 9421.104995025462, 9450.669226577313, 9618.182454491447, 9832.852507212254, 10472.162227021972], "value": 8054.223496991096, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1243.533674871923, 1390.7635701930674, 1439.0214654035258, 1502.181311185404, 1752.3078384038313, 6554.737810487581, 7353.595552244625, 7547.886781698266, 7554.660040298193, 7828.452008359417, 7610.657520411581, 7635.350995165418, 7655.311022008018, 7667.348275396584, 7688.308052275294, 2100.4433825036376, 2125.6598944168936, 2125.7661657217695, 2127.287358734818, 2127.712758777561, 2116.6507526258147, 2127.2022991378235, 2129.872207667537, 2132.754733205678, 2143.6248656485054, 2056.1980827477296, 2070.1201989154624, 2077.3248694174376, 2079.469735217525, 2094.287942099686, 2031.8882461464614, 2032.756069271682, 2035.7964203579525, 2037.7656569494475, 2038.3171637501455, 2032.5934813037452, 2036.9796302037087, 2067.462459760458, 2068.503559145809, 2069.813716765483, 7419.888701669479, 7604.201558836297, 7689.461737678364, 7690.0, 7714.074311082706, 4419.62433193179, 4524.47968483622, 4527.4567051953645, 4527.705699129546, 4531.456225252958, 2806.2984253936515, 2968.896088636909, 3006.5339872319823, 3012.5631783391286, 3013.7438317743017, 888.076400543434, 898.1512470715177, 901.4230892228978, 909.5043201455156, 912.0, 10102.39385636864, 10219.233557483218, 10238.385696858213, 10241.385516869012, 10256.79486410275, 3871.8644847430205, 4001.239764444804, 4048.7570745755347, 4075.021994721305, 4084.550699423061, 8194.590270486451, 8223.95888020556, 8252.504849709037, 8270.0, 8275.8344833103, 4372.475302963665, 4388.473383193973, 4389.100234451929, 4394.384786129945, 4397.450318710161, 3602.7838329700303, 3821.961780382216, 3885.0675837798726, 3950.861719839792, 3976.2842688315804, 3243.6918492743152, 3279.1474216703828, 3371.4100032494216, 3372.460406334975, 3377.257003459235, 940.8965013848472, 948.4878165790534, 949.4777872170279, 962.9133377996014, 969.5879251318303, 703.5567592416745, 827.9586020699062, 841.8947631546057, 846.1369403208693, 871.6339137562239, 5133.794648214104, 5321.414644389114, 5385.165299378603, 5537.252470916455, 5598.608097433141, 876.548577482597, 877.8463768840427, 894.5661354243186, 906.4153620914474, 928.9814203716023, 3786.1481166737262, 3809.29528037314, 3812.4281357796, 3847.8460861565222, 3866.1881004988704, 9097.590608422637, 9418.858717119248, 9475.241980641533, 9481.336306458481, 9490.288228382899, 9094.818103638023, 9357.438553686801, 9361.391509551857, 9370.344075914756, 9378.249740020781, 6786.287439818748, 6878.896816547755, 7003.614801185881, 7006.194287656883, 7016.403605693573, 5136.460671629575, 5198.662086964335, 5293.761780719799, 5345.171498417596, 5384.757685904222, 8181.181881812009, 8334.291585215082, 8354.12281710436, 8384.077751447458, 8413.495190288602, 8407.495550267004, 8498.957505212435, 8907.0, 8918.10818918103, 8928.73213803587, 5812.854678633068, 5988.970055149696, 6327.96836015817, 6472.093906853139, 6474.2554606219, 6936.167659880846, 7207.459440541876, 7215.639218038972, 7220.133583969957, 7224.241454647186, 6330.0, 6505.2844187138435, 6576.0, 6644.10304608891, 6655.301193374819, 6238.625682459067, 6353.047042943595, 6394.26465956421, 6477.190351206099, 6612.702428390638, 6070.271567411939, 6105.5420843437805, 6131.478824299985, 6141.539384546088, 6144.354842741626, 4550.362949187051, 4683.297505374085, 4688.484266730588, 4695.34265202865, 4773.928391074067, 4524.208263553931, 4596.793144308447, 4616.0537089895815, 4619.69971951822, 4626.1441633297945], "value": 3999.147980558349, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.9054557268888, 293.4014610195202, 296.45156460547975, 298.82220079052956, 304.0, 4829.78265978031, 4833.419989601244, 4833.782479788413, 4841.612670986321, 4867.0, 4676.39206903103, 4709.788059537321, 4736.9578692687555, 4761.381020467343, 4772.713637181763, 3570.660787225216, 3682.4108142697173, 3714.4985426967282, 3772.849086036569, 3810.8475660973527, 3746.3256613809504, 3754.2866855297543, 3764.4729737836724, 3786.848526058954, 3789.3368660484307, 3534.702443411777, 3721.2929543386804, 3730.757500762442, 3747.388622892155, 3747.5877653458097, 3621.764585301947, 3715.2383761328997, 3727.925441491182, 3747.2318174774105, 3754.5682246541724, 3313.4201514735064, 3316.9004929852144, 3328.683775041367, 3340.2484441000825, 3343.264481813997, 4609.377734005933, 4609.67732258742, 4610.423697037871, 4612.100640375128, 4625.352450656911, 4012.1775036117524, 4228.77365563986, 4277.636400905927, 4278.935815962763, 4289.6997210195295, 4965.900681986376, 4980.601551875878, 4992.775325110379, 5001.824936127255, 5002.099622068026, 3557.2351944331836, 3572.1783989682517, 3576.749627526074, 3577.7853328800097, 3583.211693427442, 4971.90056198874, 5237.397699264595, 5263.000059977222, 5272.130098533776, 5283.577313814924, 3614.7831130132276, 3632.219072899339, 3641.289948460025, 3644.6902013328636, 3647.854085836537, 3694.8522059117336, 3698.9273110798104, 3699.408094704834, 3711.777293362407, 3720.0, 4225.471816022997, 4239.97880010598, 4247.150569886036, 4251.404803327537, 4276.379924910871, 4866.46468888422, 4972.577330926912, 4974.900501990012, 4989.700617962933, 5079.796808127633, 1250.6310638361706, 1268.1440027981075, 1278.622806272152, 1281.5898912348146, 1284.556827894375, 797.9281864632211, 813.8819871118654, 844.8690452979798, 857.8713193021097, 859.9914000860036, 4373.781310934441, 4389.4732632083715, 4412.470503539532, 4433.911321773547, 4436.511983681792, 5143.588512918957, 5168.405633352138, 5172.818951336685, 5209.348831396075, 5219.530242278137, 4241.703080784316, 4414.035754089399, 4428.68999170055, 4437.733735975852, 4438.667099967451, 2749.0207050923304, 2757.7380148885823, 2758.8620568971874, 2761.613374127624, 2778.305423644089, 4750.596199323097, 4822.397200349957, 4826.1795494765975, 4829.613630909519, 4861.6596838220985, 4972.726500042532, 5214.504622060974, 5216.947830521722, 5223.302426711334, 5232.843014709565, 4782.402199725035, 4787.61699064081, 4800.95199048012, 4813.879653008703, 4834.685745426536, 5060.721660308718, 5093.949060509348, 5094.2613321068975, 5106.234064890147, 5108.872278193075, 4770.856874293777, 5019.297298378303, 5029.723365214948, 5043.117454445384, 5052.368453943257, 4741.715497070187, 4753.714777113384, 4773.928391074067, 4779.091972525209, 4781.418034261599, 3578.0, 3591.622879597709, 3602.6037135915553, 3609.783412995229, 3640.399334109842, 4874.610031197425, 5145.228215767665, 5149.7682604282145, 5179.663321884065, 5184.922226166686, 5030.647854650286, 5033.370828646419, 5045.394552653705, 5058.0, 5061.8734531637, 5033.119204139334, 5034.974825125997, 5039.26930595069, 5054.949450505448, 5064.518870707277, 4690.226112691368, 4768.068932082512, 4934.5805606523845, 4946.975265123651, 4949.257611358325, 4957.702537847741, 5003.54968052877, 5060.924086138637, 5096.490350964874, 5109.0, 4930.975345123252, 4931.630127740362, 4942.085714142895, 4942.75286235694, 4973.378327709036], "value": 3766.63139827632, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [644.816227375198, 668.4852663449142, 671.6373158494416, 745.742718762027, 745.9333153590363, 12000.399980000997, 12502.0, 12735.745285094292, 12827.358632068383, 12841.871581284184, 11855.940720296387, 11948.342841143756, 11980.281183129018, 11980.400979951011, 12001.879981200185, 781.9296263336313, 799.4843326054691, 801.5591424716412, 801.8035581282564, 802.9437939344248, 629.4964028776983, 780.637003793236, 789.9763007109767, 796.144145044077, 806.2622700229313, 844.6705784743955, 844.8141408890034, 845.5349557743217, 845.6870957745642, 846.551327796269, 839.1692224697535, 839.8278352937695, 840.0297656207091, 840.4201101240155, 841.1966571923798, 486.1929197532115, 497.39318032000904, 497.4304421437434, 497.64667086368723, 597.1043434847728, 12663.49346026167, 13275.86724132756, 13324.86675133246, 13371.0, 13375.197488150741, 11262.493187806569, 11731.589394371242, 11764.58823941158, 12041.036717062614, 12093.093018023683, 9442.527873606292, 9586.185174260196, 9731.464769437678, 11649.825252621218, 11719.0, 4064.3700226464944, 4094.242565125461, 4124.792795467484, 4150.27370210212, 4249.0, 59126.0, 59427.0, 59483.0, 59551.702241488514, 60421.0, 10291.382517049002, 10389.688309350731, 10429.217808664229, 10465.26743127989, 10570.471476426148, 17903.910480447776, 18032.188551515213, 18109.7283540747, 18109.818901811075, 18366.724499132524, 6647.601143931282, 6721.978912413487, 6728.327167283331, 6753.932460675429, 6833.4191593715095, 3726.1802403471193, 3735.9066023349633, 3796.1458671798623, 3800.3539398302355, 3831.731778775459, 2884.682684904659, 2974.5240761478494, 2976.04766474726, 2978.7468065214266, 2979.8361090139815, 519.6180807106773, 530.5172293213158, 530.891167310708, 533.4638688118416, 548.9807856725075, 485.61151079137255, 537.8843548637062, 552.1414200917532, 556.4963707844407, 588.2411688921329, 7723.266289702469, 7943.364530837519, 8015.879761803461, 8049.758507244792, 8066.7579972600915, 467.49510528628997, 468.43553518010714, 481.38863643172976, 496.9340764061069, 529.0239508107535, 3128.3430479599083, 3194.9994774533807, 3196.009237136495, 3249.8050116993054, 3272.3291725196277, 89703.55148224217, 90598.54700726454, 92026.0, 95345.52327238319, 95367.52316238513, 65530.34469655338, 65683.0, 67544.66227668831, 76662.61668691621, 82237.58881205556, 72923.63538182275, 74353.25646743571, 74742.0, 74761.99999999891, 74945.62527187438, 8858.15847494487, 8879.33404994615, 8907.153820387053, 8977.236934860348, 8978.461292322481, 42470.362944555236, 42677.0, 43493.0, 43960.34059489174, 44089.559104408545, 35882.64117358898, 36439.81780091082, 36475.27049458943, 36640.0, 41272.587274126876, 11223.831642525533, 11401.20191586581, 11488.827667584826, 11561.30632162073, 11803.468843902217, 12082.0, 12335.259884406963, 12351.567695130987, 12572.748545029232, 12822.0, 12232.755344892874, 12314.076444266895, 12466.065045121833, 12477.93761031189, 12597.0, 11024.338539687644, 11227.607033753779, 11371.886281137082, 11754.29474231549, 11762.76474470523, 11925.582604609144, 12393.256404615751, 12791.424385902843, 12903.548375807177, 12921.741565168455, 9422.387544809566, 9755.365901216683, 10161.644342447978, 10311.484425778832, 10455.634052808115, 9636.0, 9936.701898943043, 10253.692389228336, 10289.639862604772, 10355.94822025885], "value": 6369.319457296541, "unit": "score"}], "name": "dromaeo_css", "value": 6343.126116663779, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 115.0, 113.0, 112.0, 111.0, 112.0, 112.0, 113.0, 112.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 131.0, 133.0, 180.0, 138.0, 156.0, 154.0, 130.0, 182.0, 129.0], "value": 149.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 160.0, 161.0, 162.0, 160.0, 162.0, 163.0, 160.0, 159.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 114.0, 77.0, 75.0, 73.0, 76.0, 98.0, 75.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 117.0, 87.0, 86.0, 86.0, 129.0, 88.0, 86.0, 123.0], "value": 97.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 65.0, 64.0, 65.0, 65.0, 65.0, 64.0, 63.0, 64.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 68.0, 67.0, 66.0, 68.0, 66.0, 67.0, 66.0], "value": 66.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 82.0, 84.0, 85.0, 85.0, 84.0, 93.0, 117.0, 97.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 135.0, 129.0, 133.0, 133.0, 129.0, 137.0, 132.0, 135.0, 135.0], "value": 133.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 168.0, 169.0, 167.0, 166.0, 171.0, 173.0, 166.0, 170.0], "value": 168.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 71.0, 66.0, 67.0, 69.0, 66.0, 68.0, 66.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1549.6999999999998, "alertThreshold": 2.0}]} 20:00:05 INFO - Return code: 0 20:00:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:00:06 ERROR - # TBPL SUCCESS # 20:00:06 INFO - Running post-action listener: _resource_record_post_action 20:00:06 INFO - ##### 20:00:06 INFO - ##### Finished run-tests step (success) 20:00:06 INFO - ##### 20:00:06 INFO - Running post-run listener: _resource_record_post_run 20:00:06 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 384811008; Read time: 12; Write time: 325540 20:00:06 INFO - TinderboxPrint: CPU usage
13.1% 20:00:06 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 20:00:06 INFO - TinderboxPrint: I/O write bytes / time
384,811,008 / 325,540 20:00:06 INFO - TinderboxPrint: CPU idle
8,331.2 (86.9%) 20:00:06 INFO - TinderboxPrint: CPU user
1,212.1 (12.6%) 20:00:06 INFO - TinderboxPrint: Swap in / out
0 / 0 20:00:06 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 74153984; Read time: 0; Write time: 41652 20:00:06 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 293748736; Read time: 12; Write time: 256400 20:00:06 INFO - Running post-run listener: _upload_blobber_files 20:00:06 INFO - Blob upload gear active. 20:00:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:00:06 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 20:00:06 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'] 20:00:06 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 20:00:07 INFO - (blobuploader) - INFO - Open directory for files ... 20:00:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:00:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:00:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:00:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:00:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:00:07 INFO - (blobuploader) - INFO - Done attempting. 20:00:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:00:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:00:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:00:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:00:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:00:12 INFO - (blobuploader) - INFO - Done attempting. 20:00:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:00:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:00:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:00:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:00:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:00:12 INFO - (blobuploader) - INFO - Done attempting. 20:00:12 INFO - (blobuploader) - INFO - Iteration through files over. 20:00:12 INFO - Return code: 0 20:00:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:00:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:00:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8d6d1ce79448e6e13b7a63484d55d18ff1e99d802bcfa836b0e5d93b7e0a8570e0ce2329532b7610a9e5c15a80b794796c96628af0ff54b2bf280036c4468368", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0cc990deb1580ab3d53029177fae0d35eeb280ab569105f4e3f8103d3a05f771c9c04bae24906bccc0be9dcaee2d50e1707161bc80c884f4f8689142c499f374", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/28fd246f19b354b5b3b3d7340323412d1e80b7aad256e725d88b0819585fe206b12c1289624b3971522a3172aa30e7bc617fc84281ed29792c8dfde217d6afaf"} 20:00:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:00:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:00:12 INFO - Contents: 20:00:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8d6d1ce79448e6e13b7a63484d55d18ff1e99d802bcfa836b0e5d93b7e0a8570e0ce2329532b7610a9e5c15a80b794796c96628af0ff54b2bf280036c4468368", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0cc990deb1580ab3d53029177fae0d35eeb280ab569105f4e3f8103d3a05f771c9c04bae24906bccc0be9dcaee2d50e1707161bc80c884f4f8689142c499f374", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/28fd246f19b354b5b3b3d7340323412d1e80b7aad256e725d88b0819585fe206b12c1289624b3971522a3172aa30e7bc617fc84281ed29792c8dfde217d6afaf"} 20:00:12 INFO - Running post-run listener: copy_logs_to_upload_dir 20:00:12 INFO - Copying logs to upload dir... 20:00:12 INFO - mkdir: /builds/slave/test/build/upload/logs 20:00:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.696979 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-06-03 20:00:12.608559) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 20:00:12.611989) ========= 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=574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066 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/8d6d1ce79448e6e13b7a63484d55d18ff1e99d802bcfa836b0e5d93b7e0a8570e0ce2329532b7610a9e5c15a80b794796c96628af0ff54b2bf280036c4468368", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0cc990deb1580ab3d53029177fae0d35eeb280ab569105f4e3f8103d3a05f771c9c04bae24906bccc0be9dcaee2d50e1707161bc80c884f4f8689142c499f374", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/28fd246f19b354b5b3b3d7340323412d1e80b7aad256e725d88b0819585fe206b12c1289624b3971522a3172aa30e7bc617fc84281ed29792c8dfde217d6afaf"} build_url:https://queue.taskcluster.net/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011284 build_url: 'https://queue.taskcluster.net/v1/task/aeZyBYJGSN2raK1uSv9aKQ/artifacts/public/build/firefox-49.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/8d6d1ce79448e6e13b7a63484d55d18ff1e99d802bcfa836b0e5d93b7e0a8570e0ce2329532b7610a9e5c15a80b794796c96628af0ff54b2bf280036c4468368", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0cc990deb1580ab3d53029177fae0d35eeb280ab569105f4e3f8103d3a05f771c9c04bae24906bccc0be9dcaee2d50e1707161bc80c884f4f8689142c499f374", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/28fd246f19b354b5b3b3d7340323412d1e80b7aad256e725d88b0819585fe206b12c1289624b3971522a3172aa30e7bc617fc84281ed29792c8dfde217d6afaf"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 20:00:12.637831) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 20:00:12.638180) ========= 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=574dc5aafe09851ec43d388c000001b6-1465006894.38729-661101066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004418 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 20:00:12.720707) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 20:00:12.721041) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 20:00:12.721356) ========= ========= Total master_lag: 0.18 =========