builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-018 starttime: 1465952730.48 results: success (0) buildid: 20160614172925 builduid: cf2c01f39d4b4b089fbd47722727ed68 revision: 071e9554e3e76a7692fd1a24b845bc42064352b0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-14 18:05:30.480954) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-14 18:05:30.481452) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-14 18:05:30.481739) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-14 18:05:30.500344) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 18:05:30.500725) ========= 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=62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-14 18:05:30-- 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% 46.3M=0s 2016-06-14 18:05:30 (46.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121952 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 18:05:30.636640) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 18:05:30.636961) ========= 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=62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018160 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 18:05:30.687015) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 18:05:30.687530) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 071e9554e3e76a7692fd1a24b845bc42064352b0 --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 071e9554e3e76a7692fd1a24b845bc42064352b0 --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=62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-14 18:05:31,067 truncating revision to first 12 chars 2016-06-14 18:05:31,068 Setting DEBUG logging. 2016-06-14 18:05:31,068 attempt 1/10 2016-06-14 18:05:31,068 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/071e9554e3e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-14 18:05:31,506 unpacking tar archive at: fx-team-071e9554e3e7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.756279 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 18:05:31.458060) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-14 18:05:31.458562) ========= script_repo_revision: 071e9554e3e76a7692fd1a24b845bc42064352b0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-14 18:05:31.459131) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-14 18:05:31.459536) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-14 18:05:31.470366) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-06-14 18:05:31.470911) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:05:31 INFO - MultiFileLogger online at 20160614 18:05:31 in /builds/slave/test 18:05:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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 18:05:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:05:31 INFO - {'append_to_log': False, 18:05:31 INFO - 'base_work_dir': '/builds/slave/test', 18:05:31 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 18:05:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:05:31 INFO - 'branch': 'Fx-Team-Non-PGO', 18:05:31 INFO - 'buildbot_json_path': 'buildprops.json', 18:05:31 INFO - 'config_files': ('talos/linux_config.py',), 18:05:31 INFO - 'default_actions': ('clobber', 18:05:31 INFO - 'read-buildbot-config', 18:05:31 INFO - 'download-and-extract', 18:05:31 INFO - 'populate-webroot', 18:05:31 INFO - 'create-virtualenv', 18:05:31 INFO - 'install', 18:05:31 INFO - 'run-tests'), 18:05:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:05:31 INFO - 'download_minidump_stackwalk': True, 18:05:31 INFO - 'download_symbols': 'ondemand', 18:05:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:05:31 INFO - 'tooltool.py': '/tools/tooltool.py', 18:05:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:05:31 INFO - '/tools/misc-python/virtualenv.py')}, 18:05:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:05:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:05:31 INFO - 'installer_path': 'installer.exe', 18:05:31 INFO - 'log_level': 'info', 18:05:31 INFO - 'log_name': 'talos', 18:05:31 INFO - 'log_to_console': True, 18:05:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:05:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:05:31 INFO - 'opt_config_files': (), 18:05:31 INFO - 'pip_index': False, 18:05:31 INFO - 'sps_profile': False, 18:05:31 INFO - 'sps_profile_interval': 0, 18:05:31 INFO - 'suite': 'dromaeojs-e10s', 18:05:31 INFO - 'system_bits': '32', 18:05:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:05:31 INFO - 'title': 'talos-linux64-ix-018', 18:05:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:05:31 INFO - 'use_talos_json': True, 18:05:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:05:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:05:31 INFO - 'work_dir': 'build'} 18:05:31 INFO - ##### 18:05:31 INFO - ##### Running clobber step. 18:05:31 INFO - ##### 18:05:31 INFO - Running pre-action listener: _resource_record_pre_action 18:05:31 INFO - Running main action method: clobber 18:05:31 INFO - rmtree: /builds/slave/test/build 18:05:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:05:32 INFO - Running post-action listener: _resource_record_post_action 18:05:32 INFO - ##### 18:05:32 INFO - ##### Finished clobber step (success) 18:05:32 INFO - ##### 18:05:32 INFO - ##### 18:05:32 INFO - ##### Running read-buildbot-config step. 18:05:32 INFO - ##### 18:05:32 INFO - Running pre-action listener: _resource_record_pre_action 18:05:32 INFO - Running main action method: read_buildbot_config 18:05:32 INFO - Using buildbot properties: 18:05:32 INFO - { 18:05:32 INFO - "project": "", 18:05:32 INFO - "product": "firefox", 18:05:32 INFO - "script_repo_revision": "production", 18:05:32 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 18:05:32 INFO - "repository": "", 18:05:32 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 18:05:32 INFO - "buildid": "20160614172925", 18:05:32 INFO - "pgo_build": "False", 18:05:32 INFO - "basedir": "/builds/slave/test", 18:05:32 INFO - "buildnumber": 1411, 18:05:32 INFO - "slavename": "talos-linux64-ix-018", 18:05:32 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:05:32 INFO - "platform": "ubuntu64_hw", 18:05:32 INFO - "scheduler": "tests-fx-team-linux64-talos", 18:05:32 INFO - "branch": "fx-team", 18:05:32 INFO - "repo_path": "integration/fx-team", 18:05:32 INFO - "revision": "071e9554e3e76a7692fd1a24b845bc42064352b0", 18:05:32 INFO - "stage_platform": "linux64", 18:05:32 INFO - "builduid": "cf2c01f39d4b4b089fbd47722727ed68", 18:05:32 INFO - "slavebuilddir": "test" 18:05:32 INFO - } 18:05:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 18:05:32 INFO - Running post-action listener: _resource_record_post_action 18:05:32 INFO - ##### 18:05:32 INFO - ##### Finished read-buildbot-config step (success) 18:05:32 INFO - ##### 18:05:32 INFO - ##### 18:05:32 INFO - ##### Running download-and-extract step. 18:05:32 INFO - ##### 18:05:32 INFO - Running pre-action listener: _resource_record_pre_action 18:05:32 INFO - Running main action method: download_and_extract 18:05:32 INFO - mkdir: /builds/slave/test/build/tests 18:05: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')]} 18:05:32 INFO - https://queue.taskcluster.net/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:05:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:05:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:05:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:05:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:05:34 INFO - Downloaded 1361 bytes. 18:05:34 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:05:34 INFO - Using the following test package requirements: 18:05:34 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:05:34 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:05:34 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:05:34 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:05:34 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:05:34 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:05:34 INFO - u'jsshell-linux-x86_64.zip'], 18:05:34 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:05:34 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:05:34 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:05:34 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:05:34 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:05:34 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:05:34 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:05:34 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:05:34 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:05:34 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:05:34 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:05:34 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:05:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:05:34 INFO - https://queue.taskcluster.net/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:05:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:05:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:05:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:05:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:05:35 INFO - Downloaded 22653673 bytes. 18:05:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:05:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:05:36 INFO - Return code: 0 18:05:36 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:05:36 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')]} 18:05:36 INFO - https://queue.taskcluster.net/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:05:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:05:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:05:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:05:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:05:37 INFO - Downloaded 22653673 bytes. 18:05:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:05:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:05:39 INFO - Return code: 0 18:05:39 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')]} 18:05:39 INFO - https://queue.taskcluster.net/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:05:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:05:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:05:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:05:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:05:40 INFO - Downloaded 14405384 bytes. 18:05:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:05:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:05:40 INFO - Return code: 0 18:05:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:05:40 INFO - https://queue.taskcluster.net/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:05:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:05:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:05:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:05:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:05:41 INFO - Downloaded 55402216 bytes. 18:05:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:05:41 INFO - mkdir: /builds/slave/test/properties 18:05:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:05:41 INFO - Writing to file /builds/slave/test/properties/build_url 18:05:41 INFO - Contents: 18:05:41 INFO - build_url:https://queue.taskcluster.net/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:05:43 INFO - Running post-action listener: _resource_record_post_action 18:05:43 INFO - Running post-action listener: set_extra_try_arguments 18:05:43 INFO - ##### 18:05:43 INFO - ##### Finished download-and-extract step (success) 18:05:43 INFO - ##### 18:05:43 INFO - ##### 18:05:43 INFO - ##### Running populate-webroot step. 18:05:43 INFO - ##### 18:05:43 INFO - Running pre-action listener: _resource_record_pre_action 18:05:43 INFO - Running main action method: populate_webroot 18:05:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:05:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:05:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:05:43 INFO - '--tppagecycles', 18:05:43 INFO - '7'], 18:05:43 INFO - 'tests': ['tsvgm']}}, 18:05:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:05:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:05:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:05:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:05:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:05:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:05:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:43 INFO - 'talos_options': ['--disable-e10s'], 18:05:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:05:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:05:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:43 INFO - 'talos_options': ['--disable-e10s'], 18:05:43 INFO - 'tests': ['damp', 'tps']}, 18:05:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:43 INFO - 'tests': ['damp', 'tps']}, 18:05:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:05:43 INFO - 'tests': ['dromaeo_dom']}, 18:05:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:05:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:05:43 INFO - 'tests': ['basic_compositor_video']}, 18:05:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:05:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:05:43 INFO - 'tests': ['a11yr', 18:05:43 INFO - 'ts_paint', 18:05:43 INFO - 'tpaint', 18:05:43 INFO - 'sessionrestore', 18:05:43 INFO - 'sessionrestore_no_auto_restore', 18:05:43 INFO - 'tabpaint']}, 18:05:43 INFO - 'other-e10s': {'tests': ['a11yr', 18:05:43 INFO - 'ts_paint', 18:05:43 INFO - 'tpaint', 18:05:43 INFO - 'sessionrestore', 18:05:43 INFO - 'sessionrestore_no_auto_restore', 18:05:43 INFO - 'tabpaint']}, 18:05:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:05:43 INFO - 'ts_paint', 18:05:43 INFO - 'tpaint', 18:05:43 INFO - 'sessionrestore', 18:05:43 INFO - 'sessionrestore_no_auto_restore', 18:05:43 INFO - 'tabpaint']}, 18:05:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:05:43 INFO - 'ts_paint', 18:05:43 INFO - 'tpaint', 18:05:43 INFO - 'sessionrestore', 18:05:43 INFO - 'sessionrestore_no_auto_restore', 18:05:43 INFO - 'tabpaint']}, 18:05:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:05:43 INFO - 'tests': ['a11yr', 18:05:43 INFO - 'ts_paint', 18:05:43 INFO - 'tpaint', 18:05:43 INFO - 'sessionrestore', 18:05:43 INFO - 'sessionrestore_no_auto_restore', 18:05:43 INFO - 'tabpaint']}, 18:05:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:05:43 INFO - 'tests': ['a11yr', 18:05:43 INFO - 'ts_paint', 18:05:43 INFO - 'tpaint', 18:05:43 INFO - 'sessionrestore', 18:05:43 INFO - 'sessionrestore_no_auto_restore', 18:05:43 INFO - 'tabpaint']}, 18:05:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:05:43 INFO - 'tests': ['tsvgx', 18:05:43 INFO - 'tsvgr_opacity', 18:05:43 INFO - 'tart', 18:05:43 INFO - 'tscrollx', 18:05:43 INFO - 'cart']}, 18:05:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:05:43 INFO - 'tsvgr_opacity', 18:05:43 INFO - 'tart', 18:05:43 INFO - 'tscrollx', 18:05:43 INFO - 'cart']}, 18:05:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:43 INFO - 'talos_options': ['--disable-e10s'], 18:05:43 INFO - 'tests': ['tp5o']}, 18:05:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:43 INFO - 'tests': ['tp5o']}, 18:05:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:43 INFO - 'talos_options': ['--disable-e10s', 18:05:43 INFO - '--xperf_path', 18:05:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:05:43 INFO - 'tests': ['tp5n']}, 18:05:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:43 INFO - 'talos_options': ['--xperf_path', 18:05:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:05:43 INFO - 'tests': ['tp5n']}}, 18:05:43 INFO - 'talos.zip': {'path': '', 18:05:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:05:43 INFO - Running post-action listener: _resource_record_post_action 18:05:43 INFO - ##### 18:05:43 INFO - ##### Finished populate-webroot step (success) 18:05:43 INFO - ##### 18:05:43 INFO - ##### 18:05:43 INFO - ##### Running create-virtualenv step. 18:05:43 INFO - ##### 18:05:43 INFO - Running pre-action listener: _resource_record_pre_action 18:05:43 INFO - Running main action method: create_virtualenv 18:05:43 INFO - Creating virtualenv /builds/slave/test/build/venv 18:05:43 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 18:05:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:05:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:05:43 INFO - Using real prefix '/usr' 18:05:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:05:44 INFO - Installing distribute.............................................................................................................................................................................................done. 18:05:46 INFO - Installing pip.................done. 18:05:46 INFO - Return code: 0 18:05:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:05:46 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')]} 18:05:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:46 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')]} 18:05:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:46 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 0x7fe9966faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d661c0>, '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 0x1d670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d67550>, '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': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', '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 18:05:46 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 18:05:46 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 18:05:46 INFO - Using env: {'DISPLAY': ':0', 18:05:46 INFO - 'HOME': '/home/cltbld', 18:05:46 INFO - 'LANG': 'en_US.UTF-8', 18:05:46 INFO - 'LANGUAGE': 'en_US:en', 18:05:46 INFO - 'LOGNAME': 'cltbld', 18:05:46 INFO - 'MAIL': '/var/mail/cltbld', 18:05:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:46 INFO - 'MOZ_NO_REMOTE': '1', 18:05:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:46 INFO - 'NO_EM_RESTART': '1', 18:05:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:46 INFO - 'PWD': '/builds/slave/test', 18:05:46 INFO - 'SHELL': '/bin/bash', 18:05:46 INFO - 'SHLVL': '1', 18:05:46 INFO - 'TERM': 'linux', 18:05:46 INFO - 'TMOUT': '86400', 18:05:46 INFO - 'USER': 'cltbld', 18:05:46 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', 18:05:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:46 INFO - '_': '/tools/buildbot/bin/python'} 18:05:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:46 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 18:05:46 INFO - Cleaning up... 18:05:46 INFO - Return code: 0 18:05:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:05:46 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')]} 18:05:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:46 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')]} 18:05:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:46 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 0x7fe9966faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d661c0>, '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 0x1d670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d67550>, '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': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', '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 18:05:46 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 18:05:46 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 18:05:46 INFO - Using env: {'DISPLAY': ':0', 18:05:46 INFO - 'HOME': '/home/cltbld', 18:05:46 INFO - 'LANG': 'en_US.UTF-8', 18:05:46 INFO - 'LANGUAGE': 'en_US:en', 18:05:46 INFO - 'LOGNAME': 'cltbld', 18:05:46 INFO - 'MAIL': '/var/mail/cltbld', 18:05:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:46 INFO - 'MOZ_NO_REMOTE': '1', 18:05:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:46 INFO - 'NO_EM_RESTART': '1', 18:05:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:46 INFO - 'PWD': '/builds/slave/test', 18:05:46 INFO - 'SHELL': '/bin/bash', 18:05:46 INFO - 'SHLVL': '1', 18:05:46 INFO - 'TERM': 'linux', 18:05:46 INFO - 'TMOUT': '86400', 18:05:46 INFO - 'USER': 'cltbld', 18:05:46 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', 18:05:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:46 INFO - '_': '/tools/buildbot/bin/python'} 18:05:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:46 INFO - Downloading/unpacking psutil>=3.1.1 18:05:46 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 18:05:46 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 18:05:46 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 18:05:46 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 18:05:46 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 18:05:46 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 18:05:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:05:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:05:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:05:48 INFO - Installing collected packages: psutil 18:05:48 INFO - Running setup.py install for psutil 18:05:48 INFO - building 'psutil._psutil_linux' extension 18:05:48 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 18:05:48 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 18:05:48 INFO - building 'psutil._psutil_posix' extension 18:05:48 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 18:05:49 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 18:05:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:05:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:05:49 INFO - Successfully installed psutil 18:05:49 INFO - Cleaning up... 18:05:49 INFO - Return code: 0 18:05:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:05:49 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')]} 18:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:49 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')]} 18:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:49 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 0x7fe9966faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d661c0>, '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 0x1d670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d67550>, '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': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', '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 18:05:49 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 18:05:49 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 18:05:49 INFO - Using env: {'DISPLAY': ':0', 18:05:49 INFO - 'HOME': '/home/cltbld', 18:05:49 INFO - 'LANG': 'en_US.UTF-8', 18:05:49 INFO - 'LANGUAGE': 'en_US:en', 18:05:49 INFO - 'LOGNAME': 'cltbld', 18:05:49 INFO - 'MAIL': '/var/mail/cltbld', 18:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:49 INFO - 'MOZ_NO_REMOTE': '1', 18:05:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:49 INFO - 'NO_EM_RESTART': '1', 18:05:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:49 INFO - 'PWD': '/builds/slave/test', 18:05:49 INFO - 'SHELL': '/bin/bash', 18:05:49 INFO - 'SHLVL': '1', 18:05:49 INFO - 'TERM': 'linux', 18:05:49 INFO - 'TMOUT': '86400', 18:05:49 INFO - 'USER': 'cltbld', 18:05:49 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', 18:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:49 INFO - '_': '/tools/buildbot/bin/python'} 18:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:05:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:05:51 INFO - Installing collected packages: mozsystemmonitor 18:05:51 INFO - Running setup.py install for mozsystemmonitor 18:05:51 INFO - Successfully installed mozsystemmonitor 18:05:51 INFO - Cleaning up... 18:05:51 INFO - Return code: 0 18:05:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:05:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:05:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:51 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 0x7fe9966faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d661c0>, '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 0x1d670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d67550>, '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': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', '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 18:05:51 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 18:05:51 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 18:05:51 INFO - Using env: {'DISPLAY': ':0', 18:05:51 INFO - 'HOME': '/home/cltbld', 18:05:51 INFO - 'LANG': 'en_US.UTF-8', 18:05:51 INFO - 'LANGUAGE': 'en_US:en', 18:05:51 INFO - 'LOGNAME': 'cltbld', 18:05:51 INFO - 'MAIL': '/var/mail/cltbld', 18:05:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:51 INFO - 'MOZ_NO_REMOTE': '1', 18:05:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:51 INFO - 'NO_EM_RESTART': '1', 18:05:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:51 INFO - 'PWD': '/builds/slave/test', 18:05:51 INFO - 'SHELL': '/bin/bash', 18:05:51 INFO - 'SHLVL': '1', 18:05:51 INFO - 'TERM': 'linux', 18:05:51 INFO - 'TMOUT': '86400', 18:05:51 INFO - 'USER': 'cltbld', 18:05:51 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', 18:05:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:51 INFO - '_': '/tools/buildbot/bin/python'} 18:05:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:51 INFO - Downloading/unpacking jsonschema==2.5.1 18:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:05:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:05:53 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 18:05:53 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 18:05:53 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 18:05:53 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 18:05:53 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 18:05:53 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 18:05:53 INFO - Downloading functools32-3.2.3-2.tar.gz 18:05:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:05:53 INFO - warning: no files found matching '*.txt' 18:05:53 INFO - no previously-included directories found matching 'build' 18:05:53 INFO - no previously-included directories found matching 'dist' 18:05:53 INFO - no previously-included directories found matching '.git*' 18:05:53 INFO - Installing collected packages: jsonschema, functools32 18:05:53 INFO - Running setup.py install for functools32 18:05:53 INFO - warning: no files found matching '*.txt' 18:05:53 INFO - no previously-included directories found matching 'build' 18:05:53 INFO - no previously-included directories found matching 'dist' 18:05:53 INFO - no previously-included directories found matching '.git*' 18:05:53 INFO - Successfully installed jsonschema functools32 18:05:53 INFO - Cleaning up... 18:05:54 INFO - Return code: 0 18:05:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:05:54 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')]} 18:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:54 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')]} 18:05:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:54 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 0x7fe9966faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d661c0>, '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 0x1d670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d67550>, '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': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', '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 18:05:54 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 18:05:54 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 18:05:54 INFO - Using env: {'DISPLAY': ':0', 18:05:54 INFO - 'HOME': '/home/cltbld', 18:05:54 INFO - 'LANG': 'en_US.UTF-8', 18:05:54 INFO - 'LANGUAGE': 'en_US:en', 18:05:54 INFO - 'LOGNAME': 'cltbld', 18:05:54 INFO - 'MAIL': '/var/mail/cltbld', 18:05:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:54 INFO - 'MOZ_NO_REMOTE': '1', 18:05:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:54 INFO - 'NO_EM_RESTART': '1', 18:05:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:54 INFO - 'PWD': '/builds/slave/test', 18:05:54 INFO - 'SHELL': '/bin/bash', 18:05:54 INFO - 'SHLVL': '1', 18:05:54 INFO - 'TERM': 'linux', 18:05:54 INFO - 'TMOUT': '86400', 18:05:54 INFO - 'USER': 'cltbld', 18:05:54 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', 18:05:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:54 INFO - '_': '/tools/buildbot/bin/python'} 18:05:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:54 INFO - Downloading/unpacking blobuploader==1.2.4 18:05:54 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 18:05:54 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 18:05:54 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 18:05:54 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 18:05:54 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 18:05:54 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 18:05:56 INFO - Downloading blobuploader-1.2.4.tar.gz 18:05:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:05:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:05:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:56 INFO - Downloading docopt-0.6.1.tar.gz 18:05:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:05:56 INFO - Installing collected packages: blobuploader, requests, docopt 18:05:56 INFO - Running setup.py install for blobuploader 18:05:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:05:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:05:57 INFO - Running setup.py install for requests 18:05:57 INFO - Running setup.py install for docopt 18:05:57 INFO - Successfully installed blobuploader requests docopt 18:05:57 INFO - Cleaning up... 18:05:57 INFO - Return code: 0 18:05:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05:57 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')]} 18:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:57 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')]} 18:05:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:57 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 0x7fe9966faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d661c0>, '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 0x1d670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d67550>, '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': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', '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 18:05:57 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 18:05:57 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 18:05:57 INFO - Using env: {'DISPLAY': ':0', 18:05:57 INFO - 'HOME': '/home/cltbld', 18:05:57 INFO - 'LANG': 'en_US.UTF-8', 18:05:57 INFO - 'LANGUAGE': 'en_US:en', 18:05:57 INFO - 'LOGNAME': 'cltbld', 18:05:57 INFO - 'MAIL': '/var/mail/cltbld', 18:05:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:57 INFO - 'MOZ_NO_REMOTE': '1', 18:05:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:57 INFO - 'NO_EM_RESTART': '1', 18:05:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:57 INFO - 'PWD': '/builds/slave/test', 18:05:57 INFO - 'SHELL': '/bin/bash', 18:05:57 INFO - 'SHLVL': '1', 18:05:57 INFO - 'TERM': 'linux', 18:05:57 INFO - 'TMOUT': '86400', 18:05:57 INFO - 'USER': 'cltbld', 18:05:57 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', 18:05:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:57 INFO - '_': '/tools/buildbot/bin/python'} 18:05:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:05:57 INFO - Running setup.py (path:/tmp/pip-qybjgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:05:57 INFO - Running setup.py (path:/tmp/pip-M80MQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:05:57 INFO - Running setup.py (path:/tmp/pip-cUTG9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:05:57 INFO - Running setup.py (path:/tmp/pip-0te7Kx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:05:58 INFO - Running setup.py (path:/tmp/pip-GT51JW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:05:58 INFO - Running setup.py (path:/tmp/pip-RNB18Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:05:58 INFO - Running setup.py (path:/tmp/pip-56YGVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:05:58 INFO - Running setup.py (path:/tmp/pip-ZTr_Ul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:05:58 INFO - Running setup.py (path:/tmp/pip-fpsw2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:05:58 INFO - Running setup.py (path:/tmp/pip-C2ZwYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:05:58 INFO - Running setup.py (path:/tmp/pip-Nn9kaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:05:58 INFO - Running setup.py (path:/tmp/pip-S_nHEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:05:58 INFO - Running setup.py (path:/tmp/pip-fAHcmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:05:58 INFO - Running setup.py (path:/tmp/pip-9hstZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:58 INFO - Running setup.py (path:/tmp/pip-1R0tMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:05:58 INFO - Running setup.py (path:/tmp/pip-EGlyuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:05:58 INFO - Running setup.py (path:/tmp/pip-rqVaGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:05:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:05:58 INFO - Running setup.py install for manifestparser 18:05:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:05:59 INFO - Running setup.py install for mozcrash 18:05:59 INFO - Running setup.py install for mozdebug 18:05:59 INFO - Running setup.py install for mozdevice 18:05:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:05:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:05:59 INFO - Running setup.py install for mozfile 18:05:59 INFO - Running setup.py install for mozhttpd 18:05:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:05:59 INFO - Running setup.py install for mozinfo 18:05:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:05:59 INFO - Running setup.py install for mozInstall 18:05:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:05:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:05:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:05:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:05:59 INFO - Running setup.py install for mozleak 18:05:59 INFO - Running setup.py install for mozlog 18:05:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:06:00 INFO - Running setup.py install for moznetwork 18:06:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:06:00 INFO - Running setup.py install for mozprocess 18:06:00 INFO - Running setup.py install for mozprofile 18:06:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:06:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:06:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:06:00 INFO - Running setup.py install for mozrunner 18:06:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:06:00 INFO - Running setup.py install for mozscreenshot 18:06:00 INFO - Running setup.py install for moztest 18:06:00 INFO - Running setup.py install for mozversion 18:06:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:06:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:06:00 INFO - Cleaning up... 18:06:00 INFO - Return code: 0 18:06:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:06: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')]} 18:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06: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')]} 18:06:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:00 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 0x7fe9966faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d661c0>, '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 0x1d670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d67550>, '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': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', '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 18:06:00 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 18:06:00 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 18:06:00 INFO - Using env: {'DISPLAY': ':0', 18:06:00 INFO - 'HOME': '/home/cltbld', 18:06:00 INFO - 'LANG': 'en_US.UTF-8', 18:06:00 INFO - 'LANGUAGE': 'en_US:en', 18:06:00 INFO - 'LOGNAME': 'cltbld', 18:06:00 INFO - 'MAIL': '/var/mail/cltbld', 18:06:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:00 INFO - 'MOZ_NO_REMOTE': '1', 18:06:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:06:00 INFO - 'NO_EM_RESTART': '1', 18:06:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:06:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:00 INFO - 'PWD': '/builds/slave/test', 18:06:00 INFO - 'SHELL': '/bin/bash', 18:06:00 INFO - 'SHLVL': '1', 18:06:00 INFO - 'TERM': 'linux', 18:06:00 INFO - 'TMOUT': '86400', 18:06:00 INFO - 'USER': 'cltbld', 18:06:00 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', 18:06:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:00 INFO - '_': '/tools/buildbot/bin/python'} 18:06:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:06:00 INFO - Running setup.py (path:/tmp/pip-kXnmot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:06:01 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)) 18:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:06:01 INFO - Running setup.py (path:/tmp/pip-drUnb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:06:01 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)) 18:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:06:01 INFO - Running setup.py (path:/tmp/pip-m3sr8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:06:01 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)) 18:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:06:01 INFO - Running setup.py (path:/tmp/pip-nw2sUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:06:01 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)) 18:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:06:01 INFO - Running setup.py (path:/tmp/pip-s6x7GI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:06:01 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)) 18:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:06:01 INFO - Running setup.py (path:/tmp/pip-BFHHLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:06:01 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)) 18:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:06:01 INFO - Running setup.py (path:/tmp/pip-ZIefHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:06:01 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)) 18:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:06:01 INFO - Running setup.py (path:/tmp/pip-E0tNa1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:06:01 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)) 18:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:06:01 INFO - Running setup.py (path:/tmp/pip-6d7qKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:06:01 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)) 18:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:06:01 INFO - Running setup.py (path:/tmp/pip-Qv9iWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:06:01 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)) 18:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:06:01 INFO - Running setup.py (path:/tmp/pip-lvq6uL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:06:01 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)) 18:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:06:01 INFO - Running setup.py (path:/tmp/pip-VB3C1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:06:01 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)) 18:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:06:01 INFO - Running setup.py (path:/tmp/pip-gjxBnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:06:01 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)) 18:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:06:01 INFO - Running setup.py (path:/tmp/pip-MReRE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:06:02 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)) 18:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:06:02 INFO - Running setup.py (path:/tmp/pip-fJruzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:06:02 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)) 18:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:06:02 INFO - Running setup.py (path:/tmp/pip-FIfGJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:06:02 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)) 18:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:06:02 INFO - Running setup.py (path:/tmp/pip-y5QxhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:06:02 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)) 18:06:02 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)) 18:06:02 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)) 18:06:02 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)) 18:06:02 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)) 18:06:02 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)) 18:06:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:06:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:06:02 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 18:06:02 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 18:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:06:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:06:04 INFO - Downloading blessings-1.6.tar.gz 18:06:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:06:04 INFO - Installing collected packages: blessings 18:06:04 INFO - Running setup.py install for blessings 18:06:04 INFO - Successfully installed blessings 18:06:04 INFO - Cleaning up... 18:06:04 INFO - Return code: 0 18:06:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:06:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:06:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:06:04 INFO - Reading from file tmpfile_stdout 18:06:04 INFO - Current package versions: 18:06:04 INFO - argparse == 1.2.1 18:06:04 INFO - blessings == 1.6 18:06:04 INFO - blobuploader == 1.2.4 18:06:04 INFO - docopt == 0.6.1 18:06:04 INFO - functools32 == 3.2.3-2 18:06:04 INFO - jsonschema == 2.5.1 18:06:04 INFO - manifestparser == 1.1 18:06:04 INFO - mozInstall == 1.12 18:06:04 INFO - mozcrash == 0.17 18:06:04 INFO - mozdebug == 0.1 18:06:04 INFO - mozdevice == 0.48 18:06:04 INFO - mozfile == 1.2 18:06:04 INFO - mozhttpd == 0.7 18:06:04 INFO - mozinfo == 0.9 18:06:04 INFO - mozleak == 0.1 18:06:04 INFO - mozlog == 3.2 18:06:04 INFO - moznetwork == 0.27 18:06:04 INFO - mozprocess == 0.22 18:06:04 INFO - mozprofile == 0.28 18:06:04 INFO - mozrunner == 6.11 18:06:04 INFO - mozscreenshot == 0.1 18:06:04 INFO - mozsystemmonitor == 0.3 18:06:04 INFO - moztest == 0.7 18:06:04 INFO - mozversion == 1.4 18:06:04 INFO - psutil == 3.1.1 18:06:04 INFO - requests == 1.2.3 18:06:04 INFO - wsgiref == 0.1.2 18:06:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:06:04 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')]} 18:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:04 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')]} 18:06:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:04 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 0x7fe9966faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d661c0>, '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 0x1d670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d67550>, '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': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', '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 18:06:04 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 18:06:04 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 18:06:04 INFO - Using env: {'DISPLAY': ':0', 18:06:04 INFO - 'HOME': '/home/cltbld', 18:06:04 INFO - 'LANG': 'en_US.UTF-8', 18:06:04 INFO - 'LANGUAGE': 'en_US:en', 18:06:04 INFO - 'LOGNAME': 'cltbld', 18:06:04 INFO - 'MAIL': '/var/mail/cltbld', 18:06:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:04 INFO - 'MOZ_NO_REMOTE': '1', 18:06:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:06:04 INFO - 'NO_EM_RESTART': '1', 18:06:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:06:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:04 INFO - 'PWD': '/builds/slave/test', 18:06:04 INFO - 'SHELL': '/bin/bash', 18:06:04 INFO - 'SHLVL': '1', 18:06:04 INFO - 'TERM': 'linux', 18:06:04 INFO - 'TMOUT': '86400', 18:06:04 INFO - 'USER': 'cltbld', 18:06:04 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', 18:06:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:04 INFO - '_': '/tools/buildbot/bin/python'} 18:06:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:04 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)) 18:06:04 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)) 18:06:04 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)) 18:06:04 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)) 18:06:04 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)) 18:06:04 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)) 18:06:04 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)) 18:06:04 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)) 18:06:04 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)) 18:06:04 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)) 18:06:04 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)) 18:06:04 INFO - Cleaning up... 18:06:04 INFO - Return code: 0 18:06:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:06:04 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')]} 18:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:04 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')]} 18:06:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:04 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 0x7fe9966faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d661c0>, '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 0x1d670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d67550>, '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': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', '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 18:06:04 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 18:06:04 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 18:06:04 INFO - Using env: {'DISPLAY': ':0', 18:06:04 INFO - 'HOME': '/home/cltbld', 18:06:04 INFO - 'LANG': 'en_US.UTF-8', 18:06:04 INFO - 'LANGUAGE': 'en_US:en', 18:06:04 INFO - 'LOGNAME': 'cltbld', 18:06:04 INFO - 'MAIL': '/var/mail/cltbld', 18:06:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:04 INFO - 'MOZ_NO_REMOTE': '1', 18:06:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:06:04 INFO - 'NO_EM_RESTART': '1', 18:06:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:06:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:04 INFO - 'PWD': '/builds/slave/test', 18:06:04 INFO - 'SHELL': '/bin/bash', 18:06:04 INFO - 'SHLVL': '1', 18:06:04 INFO - 'TERM': 'linux', 18:06:04 INFO - 'TMOUT': '86400', 18:06:04 INFO - 'USER': 'cltbld', 18:06:04 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', 18:06:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:04 INFO - '_': '/tools/buildbot/bin/python'} 18:06:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:06:05 INFO - Cleaning up... 18:06:05 INFO - Return code: 0 18:06:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:06:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:06:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:06:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7fe9966faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d661c0>, '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 0x1d670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d67550>, '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': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', '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 18:06:05 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 18:06:05 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 18:06:05 INFO - Using env: {'DISPLAY': ':0', 18:06:05 INFO - 'HOME': '/home/cltbld', 18:06:05 INFO - 'LANG': 'en_US.UTF-8', 18:06:05 INFO - 'LANGUAGE': 'en_US:en', 18:06:05 INFO - 'LOGNAME': 'cltbld', 18:06:05 INFO - 'MAIL': '/var/mail/cltbld', 18:06:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:05 INFO - 'MOZ_NO_REMOTE': '1', 18:06:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:06:05 INFO - 'NO_EM_RESTART': '1', 18:06:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:06:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:05 INFO - 'PWD': '/builds/slave/test', 18:06:05 INFO - 'SHELL': '/bin/bash', 18:06:05 INFO - 'SHLVL': '1', 18:06:05 INFO - 'TERM': 'linux', 18:06:05 INFO - 'TMOUT': '86400', 18:06:05 INFO - 'USER': 'cltbld', 18:06:05 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', 18:06:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:05 INFO - '_': '/tools/buildbot/bin/python'} 18:06:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:05 INFO - Downloading/unpacking flake8 18:06:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:06:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:06:05 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 18:06:05 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 18:06:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:06:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:06:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:06:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:06:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:06:07 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 18:06:07 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 18:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:06:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:06:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:06:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:06:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:06:07 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 18:06:07 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 18:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:06:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:06:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:06:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:06:07 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 18:06:07 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 18:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:06:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:06:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:06:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:06:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:06:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:06:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:06:07 INFO - no previously-included directories found matching 'docs/_build' 18:06:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:06:07 INFO - Running setup.py install for pep8 18:06:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:06:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:06:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:06:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:06:08 INFO - no previously-included directories found matching 'docs/_build' 18:06:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:06:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:06:08 INFO - Cleaning up... 18:06:08 INFO - Return code: 0 18:06:08 INFO - Running post-action listener: _resource_record_post_action 18:06:08 INFO - Running post-action listener: _start_resource_monitoring 18:06:08 INFO - Starting resource monitoring. 18:06:08 INFO - ##### 18:06:08 INFO - ##### Finished create-virtualenv step (success) 18:06:08 INFO - ##### 18:06:08 INFO - ##### 18:06:08 INFO - ##### Running install step. 18:06:08 INFO - ##### 18:06:08 INFO - Running pre-action listener: _resource_record_pre_action 18:06:08 INFO - Running main action method: install 18:06:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:06:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:06:08 INFO - Reading from file tmpfile_stdout 18:06:08 INFO - Detecting whether we're running mozinstall >=1.0... 18:06:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:06:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:06:08 INFO - Reading from file tmpfile_stdout 18:06:08 INFO - Output received: 18:06:08 INFO - Usage: mozinstall [options] installer 18:06:08 INFO - Options: 18:06:08 INFO - -h, --help show this help message and exit 18:06:08 INFO - -d DEST, --destination=DEST 18:06:08 INFO - Directory to install application into. [default: 18:06:08 INFO - "/builds/slave/test"] 18:06:08 INFO - --app=APP Application being installed. [default: firefox] 18:06:08 INFO - mkdir: /builds/slave/test/build/application 18:06:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:06:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:06:24 INFO - Reading from file tmpfile_stdout 18:06:24 INFO - Output received: 18:06:24 INFO - /builds/slave/test/build/application/firefox/firefox 18:06:24 INFO - Running post-action listener: _resource_record_post_action 18:06:24 INFO - ##### 18:06:24 INFO - ##### Finished install step (success) 18:06:24 INFO - ##### 18:06:24 INFO - ##### 18:06:24 INFO - ##### Running run-tests step. 18:06:24 INFO - ##### 18:06:24 INFO - Running pre-action listener: _resource_record_pre_action 18:06:24 INFO - Running main action method: run_tests 18:06:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:06:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:06:24 INFO - Python 2.7.3 18:06:24 INFO - Return code: 0 18:06:24 INFO - grabbing minidump binary from tooltool 18:06: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')]} 18:06:24 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 0x1d661c0>, '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 0x1d670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d67550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:06:24 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 18:06:24 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 18:06:24 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 18:06:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:06:24 INFO - Return code: 0 18:06:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:06:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:06:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:06:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:06:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:06:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:06:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:06:25 INFO - Return code: 0 18:06:25 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:06:25 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:06:25 INFO - Using env: {'DISPLAY': ':0', 18:06:25 INFO - 'HOME': '/home/cltbld', 18:06:25 INFO - 'LANG': 'en_US.UTF-8', 18:06:25 INFO - 'LANGUAGE': 'en_US:en', 18:06:25 INFO - 'LOGNAME': 'cltbld', 18:06:25 INFO - 'MAIL': '/var/mail/cltbld', 18:06:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:06:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:06:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:25 INFO - 'MOZ_NO_REMOTE': '1', 18:06:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:06:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:06:25 INFO - 'NO_EM_RESTART': '1', 18:06:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:06:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:25 INFO - 'PWD': '/builds/slave/test', 18:06:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:06:25 INFO - 'SHELL': '/bin/bash', 18:06:25 INFO - 'SHLVL': '1', 18:06:25 INFO - 'TERM': 'linux', 18:06:25 INFO - 'TMOUT': '86400', 18:06:25 INFO - 'USER': 'cltbld', 18:06:25 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769', 18:06:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:25 INFO - '_': '/tools/buildbot/bin/python'} 18:06:25 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:06:25 INFO - mozversion application_buildid: 20160614172925 18:06:25 INFO - mozversion application_changeset: 071e9554e3e76a7692fd1a24b845bc42064352b0 18:06:25 INFO - mozversion application_display_name: Nightly 18:06:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:06:25 INFO - mozversion application_name: Firefox 18:06:25 INFO - mozversion application_remotingname: firefox 18:06:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:06:25 INFO - mozversion application_vendor: Mozilla 18:06:25 INFO - mozversion application_version: 50.0a1 18:06:25 INFO - mozversion platform_buildid: 20160614172925 18:06:25 INFO - mozversion platform_changeset: 071e9554e3e76a7692fd1a24b845bc42064352b0 18:06:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:06:25 INFO - mozversion platform_version: 50.0a1 18:06:25 INFO - using testdate: 1465952785 18:06:25 INFO - actual date: 1465952785 18:06:25 INFO - starting webserver on 'localhost:59397' 18:06:25 INFO - SUITE-START | Running 2 tests 18:06:25 INFO - TEST-START | dromaeo_css 18:06:25 INFO - operating with platform_type : linux_ 18:06:25 INFO - Initialising browser for dromaeo_css test... 18:06:25 INFO - TEST-INFO | started process 22453 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvT9EVq/profile http://localhost:59397/getInfo.html) 18:06:30 INFO - PROCESS | 22453 | __metrics Screen width/height:1600/1200 18:06:30 INFO - PROCESS | 22453 | colorDepth:24 18:06:30 INFO - PROCESS | 22453 | Browser inner width/height: 1024/697 18:06:30 INFO - PROCESS | 22453 | __metrics 18:06:31 ERROR - PROCESS | 22453 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2947: TypeError: this.tabs is undefined 18:06:32 INFO - TEST-INFO | 22453: exit 0 18:06:32 INFO - Browser initialized. 18:06:32 INFO - Running cycle 1/1 for dromaeo_css test... 18:06:32 INFO - TEST-INFO | started process 22571 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvT9EVq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:06:44 INFO - PROCESS | 22571 | RSS: Main: 186470400 18:06:44 INFO - PROCESS | 22571 | 18:07:33 INFO - PROCESS | 22571 | 1465952853867 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] 18:09:46 INFO - PROCESS | 22571 | Cycle 1(1): loaded http://localhost:59397/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59397/tests/dromaeo/cssquery-ext.html) 18:09:47 INFO - PROCESS | 22571 | RSS: Main: 189624320 18:09:47 INFO - PROCESS | 22571 | 18:12:49 INFO - PROCESS | 22571 | Cycle 1(1): loaded http://localhost:59397/tests/dromaeo/cssquery-ext.html (next: http://localhost:59397/tests/dromaeo/cssquery-jquery.html) 18:12:50 INFO - PROCESS | 22571 | RSS: Main: 188227584 18:12:50 INFO - PROCESS | 22571 | 18:15:52 INFO - PROCESS | 22571 | Cycle 1(1): loaded http://localhost:59397/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59397/tests/dromaeo/cssquery-mootools.html) 18:15:52 INFO - PROCESS | 22571 | RSS: Main: 190267392 18:15:52 INFO - PROCESS | 22571 | 18:18:54 INFO - PROCESS | 22571 | Cycle 1(1): loaded http://localhost:59397/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59397/tests/dromaeo/cssquery-prototype.html) 18:18:55 INFO - PROCESS | 22571 | RSS: Main: 187568128 18:18:55 INFO - PROCESS | 22571 | 18:21:57 INFO - PROCESS | 22571 | Cycle 1(1): loaded http://localhost:59397/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59397/tests/dromaeo/cssquery-yui.html) 18:21:58 INFO - PROCESS | 22571 | RSS: Main: 189640704 18:21:58 INFO - PROCESS | 22571 | 18:25:00 INFO - PROCESS | 22571 | Cycle 1(1): loaded http://localhost:59397/tests/dromaeo/cssquery-yui.html (next: http://localhost:59397/tests/dromaeo/cssquery-dojo.html) 18:25:01 INFO - PROCESS | 22571 | RSS: Main: 190685184 18:25:01 INFO - PROCESS | 22571 | 18:25:01 INFO - PROCESS | 22571 | __start_tp_report 18:25:01 INFO - PROCESS | 22571 | _x_x_mozilla_page_load 18:25:01 INFO - PROCESS | 22571 | _x_x_mozilla_page_load_details 18:25:01 INFO - PROCESS | 22571 | |i|pagename|runs| 18:25:01 INFO - PROCESS | 22571 | |0;dojo.html;2592;2641.8811153498086;2645.96031059534;2650.416908280179;2664.214056853228;4683.765811709413;4712;4730.621550275978;4776.808927642899;4812.374391329131;4604.378408914796;4771.689840160388;4814.9759251203695;4839.951600483994;4842.782074806633;4442.133783912146;4462.910741785162;4513.097380523908;4527.864164075067;4536.500984891659;525.0975132131261;4294.892627684318;4386.890327741816;4409.29451287794;4410;4405.449318835145;4433.157700037015;4444.333349997526;4447.888802779924;4488.887777805581;4421.314696222059;4443.488998765151;4456.46522417312;4460.93308600368;4490.39379683745;4299.183155200533;4344.45694288214;4353.673474489426;4365.017870979036;4372.650187984954;4731.889380794513;4741.312509686076;4747.169245382044;4753.239481682948;4754.072955773626;4916.532929371704;5088.898222035538;5095.79616815327;5099.541041306263;5152.9742351288005;5195.636305458619;5204.3234379530795;5209.661372010807;5210.166373380279;5258.290130832327;4810.134175848346;4834.516548345207;4839.975800120977;4846.951530484721;4887.608991280725;5106.259592359087;5284.128118860345;5306.946930530646;5313.362396512443;5345.1447768356875;5094.949050509447;5098.898022039539;5121.692698438105;5129.435762066169;5174.24973378858;5213.687178769286;5214.6610470320065;5220.791168353299;5234.633575649744;5269.998700246941;4919.311296418504;4967.08108999829;5046.26829109777;5059.06407314648;5179.352580927384;5045.8738531537;5060.507150390644;5073.568746656501;5076.060928728197;5099.260607211933;4334.609885110355;4347.934780978288;4352.673549483801;4368.7815609219415;4385.605295523418;3687.6681098701247;3700.20445604191;3777.1879046005242;3784.299904517673;3811.19964807388;4379.299312110047;4384.956150438518;4395.692301538926;4426.092651006536;4439.489458712224;5173.120569503193;5183.585313174937;5187.118189907649;5204.8959020819375;5232.92150617741;4317.0934103838545;4425.048614547888;4440.644748420119;4453.465584129925;4471.441069866267;4675.859724208279;4680.882977925579;4683.8360657376825;4708.223143181405;4718.976405117953;4834.323194752737;4903.583195428429;4914.189158788903;4919.975400123048;4932.383452068491;4949.406071271326;5175.585953123817;5179.559737422362;5218.3737951444555;5251.711155886463;4832.34763306949;4844.370231869834;4864.537868902448;4881.487443818348;4886.462489126265;5131.666441681279;5135.768890400016;5138.152204886152;5142.562882155059;5187.507186817246;5008.899822003613;5163.509466600667;5180.197069454241;5213.6350455467755;5240.895182096413;5059.671121377099;5088.465711100281;5088.465711100429;5102.744862756922;5165.819196328111;4878.243872199815;5082.949170508247;5098.592112631055;5100.5409513143995;5101.897962040664;4882.072406242803;4885;4902.730349830794;4909.5090490950615;4912.582430493448;5069.797208111634;5092.668976516515;5093.847184584469;5094.133997220407;5143.819966301162;4830.541098595628;4921.876953076058;4969.0807200668;4994.925076123788;5034.0938631046765 18:25:01 INFO - PROCESS | 22571 | |1;ext.html;528.0018175621947;571.7255717255717;585.1515302810923;596.1385797522577;599.6192417814688;34980.82509587452;39010;39086.21827563451;39100.21799564007;39309;40097.39853902194;40606.7969660152;40644.59355406445;40696.59303406972;40858;7804.609769511516;7888.2506161914525;7953.920460795376;7973.362131029533;7976.760697179094;7980.042394912619;8028.157043510435;8041.798955026142;8050.677972881107;8073.233042860918;7983.680652773882;8012.078610959756;8013.799655008643;8026.6789328427085;8047.879281810778;7933.16701746317;7982.0820605630515;8009.95995020027;8024.317932975646;8059.113497515239;7743.186965368612;7828.452008359417;7883.3299169570655;7919.406044546682;7924.286814186693;38544.614553854386;38925.221495570215;38960.61039389598;39025;39048.414273785915;3149.7480201583817;3242.2542815152647;3434.6565343465454;3612.313660404515;3851.7111216658864;28506.287342816388;30292.277243557757;30652.233694157603;31227.687723122704;31236.062918112493;3485.4074807282823;3533;3568.8215589220695;3594.2991116732246;3695.0947017980766;250820;251587;251972;253451.73274133695;253814;31723.682763172535;55888.72055639777;56905.71547142321;56964.14553781696;57058.7147064262;31540.526892096637;33103.83448082744;33112.834435827666;33297.83351083229;33462.498062529085;12901.935490322488;12974.156679815782;13116.344182790823;13131.671708207181;13151.868481315256;10148.949255253676;10241.692749217535;10272.229582781321;10303.948480257552;10308.433036182932;7812.648430820626;7903.091144518339;7926.920730792734;7938.404619653549;7992.320652744582;3105.4410206163107;3122.344307695364;3128.87484500622;3149.0710240479116;3197.088829683529;871.2768402226137;887.9334049946151;927.5408672706918;945.4752612300102;961.5480724059681;27470;27476.58785118225;27562.310942226606;28201.71798282032;28364.574531382044;928.7956649537226;990.5726187391562;1013.7414959185508;1019.0064686930332;1096.786126705285;14774.852251477347;14829.703405932038;15049.322780474906;15129.211726578333;15138.772918406232;285728;286374;290781;290784;291068;47420.76289618599;47758.522414775405;47903.5209647899;48005.279920801215;48168.5183148164;1141.8230174322994;1153.031453578981;1163.2322667039714;1167.854018247719;1184.431472892999;25772.355691107874;27415.17754467369;27495;27563;27564.586531201647;42007.369889451074;42403;42520.57479425166;42567.57432425636;42603.14793704171;247466;250740;252545;252600;252667;25069.87465062663;25301.493970120864;25328.240152795446;25472.363190919634;25579.23262302134;40973.1805363897;41069.38395924004;41375;42100;42420.36369454522;35229.295414092085;35454.290914182086;35686;36168.819155905105;36211.27577448489;35387.29225415529;35900.64099358973;35939;35990.640093598726;36478.45282320714;34697.306053879285;34731.30537389289;35489.82255088708;35596.644033559336;35600.64399356077;31064.068077957698;32225.838870805495;32451.837740811145;33076;34932.47601286034;26216.8689156553;26398.86800565985;26666.86666566655;26924.192274231806;26939 18:25:01 INFO - PROCESS | 22571 | |2;jquery.html;361.4776647744011;368.9372806622873;371.270453558757;374.69275194340617;382.3232877806281;15840.841591584081;16516.58708532286;16915.238814253356;17348.56628584286;17366.218520166592;14952.775708364356;16393.83606163938;17040.829591704078;17177.656446871057;17253.137343132857;5012.649114561982;5180.8704782380555;5221.895562088756;5225.529702326789;5229.450907654698;5084.135696931512;5089.389273287192;5163.406208286039;5177.45636708144;5210.140326846066;5407.107827208506;5425.701586412746;5469.343678758554;5474.671519708811;5476.123820188771;5291.100512912814;5321.388040375348;5406.135018397038;5447.863803404926;5461.044317244467;4476.216662084123;4791.496892826272;5243.292155558989;5295.179247216687;5322.361316641989;11568.421578921021;11886.940565297205;11914.940425297904;11937.641870743892;12117.151799374047;1835.6879330513846;1845.169673646851;1889.2726300374318;1902.6194761047846;1921.7597800274966;8855.734327970107;9086.954565227132;9299.442033478013;9631.71104866855;13051;1570.89789163704;1670.4988503449038;1715.905625190614;1727.3349760342232;1768.3722278591044;53839.730801345744;62737.266707401315;63566.364336356506;66427.66786166039;71253.64373178153;9663.27525435595;9669.564869580885;9897.901020989842;9946.254030947708;10047.346922450017;13759.38082786277;13791.51729689456;13813.792793108112;13816.723665526633;13884.791728124086;7060.082189315356;7148.92851071493;7179.389751871045;7259.20148783644;7267.709291628275;6591.274959754423;6745.0556922030955;6745.831354216184;6808.557443766238;7006.719731210695;4313;4323.589259020388;4355.934660980088;4379.277419225792;4429.180601588717;1842.9354972575895;1857.247814635075;1875.2311552263507;1878.5397577593303;1891.5743957609427;864.4035615425337;876.369014309696;881.9073997230198;917.6237742525533;957.1577012229333;16619.91690041542;17177.398791042255;17482.388116415863;17609.383671571435;17720.20259088344;845.8308338332358;862.8101817600118;881.9514926679094;904.1997831918799;918.912703293186;7989.720359787496;9566.712998610054;9622.13400793932;9653;9680.27397945143;430568.3970633038;431809;434352;434532;437153;84177.15822841693;84416;84571;85124.57437712896;87260.5636971811;801.6312496251672;858.1632907914733;895.0423047339284;912.8402529557301;916.8578870275118;15693.450729224878;16164.110973896548;16169.110698911672;16175.352985880432;16371.263293151598;35588.28823423569;37385.626143739304;37993.4300985491;38240.80879595584;38427.23145537129;46739.065218694755;48606.513934860195;48938.51061489339;55129.448705512434;55445.72277138589;7231.3853322466075;7594;7624.656890439833;7692.846143076995;7757.806054848674;9825.194699001666;11453.541858325574;11574.594889178838;11761.588344408246;12018.639440816789;9942.453165075991;10377.169826413718;10409.219308552041;10505.264631475724;10529.526171322463;9770.16953558927;9771.804563908825;9811.55847986828;9833.16418104469;9846.655367062118;5397.59518036141;5500.642458240201;9718.562664679965;10135.54390052464;10350.948245259027;7417.1841097478155;7471.925280747123;7519.285667861544;7645.732399365996;7859.646315915912;9937.850932236166;10088.949555252177;10141.645042423479;10149.23880708935;10426.843597346198 18:25:01 INFO - PROCESS | 22571 | |3;mootools.html;1380;1396.965075873103;1481.2297605245274;1680.5042512458815;1684.7978242610889;6960.895586566193;7576.280253375928;7685.846283074349;8175.754727358174;8189.836203275939;7733.5746533940755;8031.558264295461;8036.316913062396;8070.515769053846;8074.313683336909;2230.542738738555;2233.530958498717;2249.1228420915854;2261.898214580348;2263.230501629446;2221.8222542196586;2229.4537838229576;2244.6408574628063;2272.761360057195;2275.340151356113;2215.944601384962;2230.2863083813186;2233;2236.4856083100894;2237.664350347453;2127.0853532980814;2130.648443006902;2132.4668832791804;2138.98930505348;2154.256781410411;2156.762756096828;2182.705334779816;2191.1345018717675;2195.0451553574085;2198.8131008864266;7952.840943181104;7994.2405471480115;8048.114707382183;8064.637091330904;8065.3144482719035;4632.884177895579;4680.4149481314835;4745.193317136095;4753.524647535253;4757.333973243714;2912.7378535931657;2997.310618557722;3108.518179682153;3113.7664675149454;3131.2015436063884;1000.3697670467559;1002.458672316948;1015.6496008877;1019.0879163149015;1022.0699163760942;10933.617323393642;10934.125269978382;10975.560977560968;10983.725406864813;11003.119750419946;4178.8328466860985;4335.3063509838275;4382.517923028464;4421.513633500313;4422.469303683579;9314.86027709585;9319.627214911328;9324.673636422693;9336.90663093374;9364.765880852898;4720.244760838249;4736.407949006374;4744.95255047452;4746.762661866893;4761.928571071437;3744.1949980753725;4130.876073717794;4159.83360665576;4270.701050926467;4277.893052673708;3695.3902606070237;3724.6089160638285;3837.7889216092826;3870.43878637596;3891.747036442622;1205.7106294489258;1233.3956361382857;1245.5951815659766;1252.812078188279;1270.1934271737327;821.9424640275242;852.6120615120105;859.0250066174812;862.1163307609625;865.3336930563431;5182.378114626194;5303.231031500411;5399.757010934517;5524.088525393265;5544.833654990357;387.2951228763623;402.0110528100846;413.3345314044386;421.4163383713579;421.62475396896787;3595.676389124991;3634.509341238952;3660.7254455915913;3687.483752274684;3695.0577602711137;9473.66842160521;9729;9738.853917191247;9827.459489728133;9899.80200395988;9667.081627245401;9724.513774311255;9758.9024109758;9780;9780.755481112887;7482.476226664081;7517.361024312885;7526.962365188139;7538.585377804164;7545.094588649397;5253.711045892513;5319.0691628964005;5413.350397952271;5427.99582077317;5441.564674826083;8963.103689630985;9025.548722563713;9034.232090272137;9160.221381182408;9232.538373081185;9080.500572468578;9123.178913897516;9123.224525915371;9191.540422978958;9323.160915517636;5962.25471816023;6363.172787537591;6660.966695166493;6709;6765.966170169315;7072.68172932209;7103.715851365888;7150.64246787669;7174.2108368078425;7192.42460603161;6591.934080659132;6686.364795344434;6748.055272261985;6755.763548275787;6837.965810170917;6480;6485.0596663484475;6513.706883190173;6557.803265902031;6617.040529123154;6332.588381755171;6393.584417012879;6407.070974708734;6431.485481161402;6550.934490655223;4506.233940230234;4583.2666773316105;4616.099860527165;4631.814727410866;4647.628189744744;4579.221532339444;4590.954090459053;4622.791974361095;4650.279206722963;4691.249400095969 18:25:01 INFO - PROCESS | 22571 | |4;prototype.html;287.70797640395;292.6648986909988;294.0604767766984;296.90146458105016;296.973954985525;5050.520200580943;5068.645194836363;5074.771635276416;5091.134507133787;5093.465186155456;4996.750162491879;5009.448960614339;5027.622928280375;5030.622703297258;5051.419086805018;3726.832292546842;3794.7912864792424;3908.8045597720143;3921.274564205617;3968.6825053995753;3896.123372241251;3911.256861196378;3923.077691930962;3923.3330333843314;3937.4290727844445;3874.6900247980093;3899.9665088751503;3905.941410878839;3909.2963266612;3910.198409326081;3809;3891.1050458394443;3898.376259798447;3913.5695073541888;3918.275119102975;3400.2179498715436;3405.540252065964;3416.777909435879;3420.435628121357;3425.6745609167338;4870.51449307961;4871.585915197213;4890.266460030988;4915.58217551505;4947.851564453073;4317.438732964726;4406.083353741101;4460.149038149197;4463.933041004388;4468.910621787546;5261.737183076034;5262.0791361511965;5268.157094864803;5270.262163297141;5302.73486325686;3705.1285253928154;3708.42519409492;3709.9055778545126;3711.962880371189;3713.461548075541;5451.945480545183;5454.59090568205;5468.179773034037;5480.08197131001;5519.227308176858;3441.896743097711;3649.4525821127045;3654.4152935530187;3658.8657516170074;3661.5606127264364;3699.8705045323286;3706.184639379332;3709.276691045274;3716.758410703296;3738.158914244273;4328.913421731548;4357.302831547001;4365.4761428628135;4368.9951311197965;4384.290126850531;5197.908439227805;5218.347706536683;5262;5275.973620131875;5276.551493123127;1282.5190553542423;1284.6017734502334;1290.1549485087282;1291.8449786025737;1293.8641442648575;793.7936136604409;807.1807115777491;808.6482380164647;820.3888103363033;826.6238861318086;4469.776511174428;4541.6593755468475;4589.655775816827;4599.241125214369;4610.76946152691;5400.810971616053;5422.8373148805595;5425.647332923348;5432.619716619799;5435.565154787608;4518.593326600556;4583.304177454296;4589.977050114728;4615.23848564983;4674.719516829001;2897.2322334581354;2898;2905.564165375168;2915.985420072886;2929.765618750495;5006.322881834626;5072.365954255718;5077.822276220315;5087.847364579068;5090.720010399464;5366.9534440784455;5475.123980163155;5502.2296878437055;5509.696966666792;5659.320881494247;4967.3542439482635;5004.9499505005215;5009.824656137018;5019.573336266385;5081.466446023188;5418.972905135449;5430.723779911797;5449.809256676156;5471.179323101566;5474.616776825584;5184.896302074013;5338.706371149468;5344.866378340573;5363.731813409392;5378.381486129145;4976.531923082772;5003;5007.499250074963;5010.098182327216;5029.899402012013;3738.7569807962395;3740.7007439405324;3744.943825842669;3748.076500329747;3748.175401411685;5237.580993520585;5419.376771671308;5449.7275136243825;5454.881749241316;5456.03582710232;5225.268462415341;5242.423333433396;5248.527632513092;5257.158854583248;5307.150855863198;5227.895442091213;5235.895282094413;5260.0531904257605;5307.531997621125;5309.495597918191;4850.708957462563;5142.460041695568;5156.1492353761205;5165.9483405166975;5178.637495375438;5023.572996295356;5119.235612769063;5257.556300623098;5260.473952604709;5291.973540132429;5095.490450954875;5128.333316668809;5179.92230116541;5205.271262023244;5247.606429517878 18:25:01 INFO - PROCESS | 22571 | |5;yui.html;598.064029793373;602.1990752299445;650.4633677216298;652.6443088516759;750.0961341583393;12209.206401583902;12818;12975.67560810979;13304.933475332635;13309.401076951537;12232.327222002787;12302.138850280484;12329.951954083914;12342.444589993449;12343.938280308608;629.7764293675748;774.1175060431113;784.5998540744233;800.2437696376926;801.6272433318505;796.0924546017544;802.1497212954258;803.9758807235763;804.0311424733212;805.8066064144622;834.9707760228393;838.6100463284572;840.6805413942693;840.9621567029499;841.9621117049747;842.1157784326433;842.3387640702019;842.7471758472465;842.9283510901612;844.3161039557978;613.3804857094367;613.6962203709193;614.443928244939;615.0313256620796;615.8737458820975;12615.936920315431;12633.368331583384;12648.494060237672;12660.430280637302;12691.873081269161;12693.682657933534;12955.805662915063;13084.280364579945;13208.537701180509;13554.983376246722;10445.9477702611;10549.9472502637;10660.307080039773;10815;10866.185036122244;3337.466005439118;3351.497275408708;3368.6126095499085;3400.931981360359;3427.4002049641213;66076.66961665209;66952.6652366735;67930.66034669794;68166;68201;10321.380717156995;10325.432101234504;10396.324238924597;10499.372597247439;10531.104856087166;16170.736096946437;16697.33210671584;16789.91605041967;17718.202680879393;20066.398008059783;6439.323870993572;6895.310468953065;6987.860242795217;7125.964370178116;7139.428845692332;3744.2324323513612;3828.425736139601;3832.559255685576;3903.6096390360735;3951.802409879552;2938.162623652249;3060.969390306113;3062.5865508156558;3090.3664748726455;3093.3503964167326;515.8865049689063;518.4608007672055;524.0462358507556;528.7409169506991;532.278762277109;530.8195213627386;544.5453046705966;548.3940246028081;548.9451054894479;549.8542886135177;7166.068411106523;7266.346028857322;7298.70805167798;7412.407007439392;7434.36807871337;423.8241129931106;533.218834407587;558.8239704493071;561.7752898840497;603.6921170203141;3287.967120328814;3301.9339613207603;3441.1225137589754;3471.0975146461597;3484.790912545255;90462;90494;93539;93804;95535;80320;80509.59745201237;80674;81211;81273.59363203146;54970.72514637481;71026;72583.63708181425;73401.29286424955;75200.24799752042;6466.353364663496;6624.205095388585;6658.966705166443;6693.330666933268;6741.089952856301;31058.844705776326;35809.82095089508;44034.77982610066;44933.550664492934;45306.54693453023;39935.2012959745;44578;61470;62389;62724.68637656783;9786.85319720219;9851.507424628597;9873.358231714916;10234.157851586786;10266.948665256627;11442.94278528602;11469.254498457574;11586;11809.763804723685;12149.878501215228;9810.705678829647;10014.449205293779;10100.49497525106;10103.141232994984;10180.287379883337;8734.737957861274;9027.45835249887;9077.409968352036;9213.078692130734;9475.24198064167;9072;9149.085091490797;9152.313576481924;9318.767030824283;9366.531673416439;8274.710385136732;8687.739367818975;8922.910770892207;9056;9113.498757568397;7784.727534536264;8295.751127466187;8558.614862331086;8562.143785621389;8682.565871706263 18:25:01 INFO - PROCESS | 22571 | __end_tp_report 18:25:01 INFO - PROCESS | 22571 | __start_cc_report 18:25:01 INFO - PROCESS | 22571 | _x_x_mozilla_cycle_collect,398 18:25:01 INFO - PROCESS | 22571 | __end_cc_report 18:25:01 INFO - PROCESS | 22571 | __startTimestamp1465953901081__endTimestamp 18:25:01 INFO - PROCESS | 22571 | 18:25:01 INFO - PROCESS | 22571 | ------- Summary: start ------- 18:25:01 INFO - PROCESS | 22571 | Number of tests: 6 18:25:01 INFO - PROCESS | 22571 | 18:25:01 INFO - PROCESS | 22571 | [#0] dojo.html Cycles:165 Average:4735.28 Median:4886.46 stddev:611.87 (12.5%) stddev-sans-first:590.20 18:25:01 INFO - PROCESS | 22571 | Values: 2592.0 2641.9 2646.0 2650.4 2664.2 4683.8 4712.0 4730.6 4776.8 4812.4 4604.4 4771.7 4815.0 4840.0 4842.8 4442.1 4462.9 4513.1 4527.9 4536.5 525.1 4294.9 4386.9 4409.3 4410.0 4405.4 4433.2 4444.3 4447.9 4488.9 4421.3 4443.5 4456.5 4460.9 4490.4 4299.2 4344.5 4353.7 4365.0 4372.7 4731.9 4741.3 4747.2 4753.2 4754.1 4916.5 5088.9 5095.8 5099.5 5153.0 5195.6 5204.3 5209.7 5210.2 5258.3 4810.1 4834.5 4840.0 4847.0 4887.6 5106.3 5284.1 5306.9 5313.4 5345.1 5094.9 5098.9 5121.7 5129.4 5174.2 5213.7 5214.7 5220.8 5234.6 5270.0 4919.3 4967.1 5046.3 5059.1 5179.4 5045.9 5060.5 5073.6 5076.1 5099.3 4334.6 4347.9 4352.7 4368.8 4385.6 3687.7 3700.2 3777.2 3784.3 3811.2 4379.3 4385.0 4395.7 4426.1 4439.5 5173.1 5183.6 5187.1 5204.9 5232.9 4317.1 4425.0 4440.6 4453.5 4471.4 4675.9 4680.9 4683.8 4708.2 4719.0 4834.3 4903.6 4914.2 4920.0 4932.4 4949.4 5175.6 5179.6 5218.4 5251.7 4832.3 4844.4 4864.5 4881.5 4886.5 5131.7 5135.8 5138.2 5142.6 5187.5 5008.9 5163.5 5180.2 5213.6 5240.9 5059.7 5088.5 5088.5 5102.7 5165.8 4878.2 5082.9 5098.6 5100.5 5101.9 4882.1 4885.0 4902.7 4909.5 4912.6 5069.8 5092.7 5093.8 5094.1 5143.8 4830.5 4921.9 4969.1 4994.9 5034.1 18:25:01 INFO - PROCESS | 22571 | 18:25:01 INFO - PROCESS | 22571 | [#1] ext.html Cycles:180 Average:41767.04 Median:35543.23 stddev:69187.25 (194.7%) stddev-sans-first:69312.05 18:25:01 INFO - PROCESS | 22571 | Values: 528.0 571.7 585.2 596.1 599.6 34980.8 39010.0 39086.2 39100.2 39309.0 40097.4 40606.8 40644.6 40696.6 40858.0 7804.6 7888.3 7953.9 7973.4 7976.8 7980.0 8028.2 8041.8 8050.7 8073.2 7983.7 8012.1 8013.8 8026.7 8047.9 7933.2 7982.1 8010.0 8024.3 8059.1 7743.2 7828.5 7883.3 7919.4 7924.3 38544.6 38925.2 38960.6 39025.0 39048.4 3149.7 3242.3 3434.7 3612.3 3851.7 28506.3 30292.3 30652.2 31227.7 31236.1 3485.4 3533.0 3568.8 3594.3 3695.1 250820.0 251587.0 251972.0 253451.7 253814.0 31723.7 55888.7 56905.7 56964.1 57058.7 31540.5 33103.8 33112.8 33297.8 33462.5 12901.9 12974.2 13116.3 13131.7 13151.9 10148.9 10241.7 10272.2 10303.9 10308.4 7812.6 7903.1 7926.9 7938.4 7992.3 3105.4 3122.3 3128.9 3149.1 3197.1 871.3 887.9 927.5 945.5 961.5 27470.0 27476.6 27562.3 28201.7 28364.6 928.8 990.6 1013.7 1019.0 1096.8 14774.9 14829.7 15049.3 15129.2 15138.8 285728.0 286374.0 290781.0 290784.0 291068.0 47420.8 47758.5 47903.5 48005.3 48168.5 1141.8 1153.0 1163.2 1167.9 1184.4 25772.4 27415.2 27495.0 27563.0 27564.6 42007.4 42403.0 42520.6 42567.6 42603.1 247466.0 250740.0 252545.0 252600.0 252667.0 25069.9 25301.5 25328.2 25472.4 25579.2 40973.2 41069.4 41375.0 42100.0 42420.4 35229.3 35454.3 35686.0 36168.8 36211.3 35387.3 35900.6 35939.0 35990.6 36478.5 34697.3 34731.3 35489.8 35596.6 35600.6 31064.1 32225.8 32451.8 33076.0 34932.5 26216.9 26398.9 26666.9 26924.2 26939.0 18:25:01 INFO - PROCESS | 22571 | 18:25:01 INFO - PROCESS | 22571 | [#2] jquery.html Cycles:180 Average:25291.01 Median:5179.16 stddev:71500.81 (1380.5%) stddev-sans-first:71676.89 18:25:01 INFO - PROCESS | 22571 | Values: 361.5 368.9 371.3 374.7 382.3 15840.8 16516.6 16915.2 17348.6 17366.2 14952.8 16393.8 17040.8 17177.7 17253.1 5012.6 5180.9 5221.9 5225.5 5229.5 5084.1 5089.4 5163.4 5177.5 5210.1 5407.1 5425.7 5469.3 5474.7 5476.1 5291.1 5321.4 5406.1 5447.9 5461.0 4476.2 4791.5 5243.3 5295.2 5322.4 11568.4 11886.9 11914.9 11937.6 12117.2 1835.7 1845.2 1889.3 1902.6 1921.8 8855.7 9087.0 9299.4 9631.7 13051.0 1570.9 1670.5 1715.9 1727.3 1768.4 53839.7 62737.3 63566.4 66427.7 71253.6 9663.3 9669.6 9897.9 9946.3 10047.3 13759.4 13791.5 13813.8 13816.7 13884.8 7060.1 7148.9 7179.4 7259.2 7267.7 6591.3 6745.1 6745.8 6808.6 7006.7 4313.0 4323.6 4355.9 4379.3 4429.2 1842.9 1857.2 1875.2 1878.5 1891.6 864.4 876.4 881.9 917.6 957.2 16619.9 17177.4 17482.4 17609.4 17720.2 845.8 862.8 882.0 904.2 918.9 7989.7 9566.7 9622.1 9653.0 9680.3 430568.4 431809.0 434352.0 434532.0 437153.0 84177.2 84416.0 84571.0 85124.6 87260.6 801.6 858.2 895.0 912.8 916.9 15693.5 16164.1 16169.1 16175.4 16371.3 35588.3 37385.6 37993.4 38240.8 38427.2 46739.1 48606.5 48938.5 55129.4 55445.7 7231.4 7594.0 7624.7 7692.8 7757.8 9825.2 11453.5 11574.6 11761.6 12018.6 9942.5 10377.2 10409.2 10505.3 10529.5 9770.2 9771.8 9811.6 9833.2 9846.7 5397.6 5500.6 9718.6 10135.5 10350.9 7417.2 7471.9 7519.3 7645.7 7859.6 9937.9 10088.9 10141.6 10149.2 10426.8 18:25:01 INFO - PROCESS | 22571 | 18:25:01 INFO - PROCESS | 22571 | [#3] mootools.html Cycles:180 Average:5197.08 Median:4745.98 stddev:2926.80 (61.7%) stddev-sans-first:2920.95 18:25:01 INFO - PROCESS | 22571 | Values: 1380.0 1397.0 1481.2 1680.5 1684.8 6960.9 7576.3 7685.8 8175.8 8189.8 7733.6 8031.6 8036.3 8070.5 8074.3 2230.5 2233.5 2249.1 2261.9 2263.2 2221.8 2229.5 2244.6 2272.8 2275.3 2215.9 2230.3 2233.0 2236.5 2237.7 2127.1 2130.6 2132.5 2139.0 2154.3 2156.8 2182.7 2191.1 2195.0 2198.8 7952.8 7994.2 8048.1 8064.6 8065.3 4632.9 4680.4 4745.2 4753.5 4757.3 2912.7 2997.3 3108.5 3113.8 3131.2 1000.4 1002.5 1015.6 1019.1 1022.1 10933.6 10934.1 10975.6 10983.7 11003.1 4178.8 4335.3 4382.5 4421.5 4422.5 9314.9 9319.6 9324.7 9336.9 9364.8 4720.2 4736.4 4745.0 4746.8 4761.9 3744.2 4130.9 4159.8 4270.7 4277.9 3695.4 3724.6 3837.8 3870.4 3891.7 1205.7 1233.4 1245.6 1252.8 1270.2 821.9 852.6 859.0 862.1 865.3 5182.4 5303.2 5399.8 5524.1 5544.8 387.3 402.0 413.3 421.4 421.6 3595.7 3634.5 3660.7 3687.5 3695.1 9473.7 9729.0 9738.9 9827.5 9899.8 9667.1 9724.5 9758.9 9780.0 9780.8 7482.5 7517.4 7527.0 7538.6 7545.1 5253.7 5319.1 5413.4 5428.0 5441.6 8963.1 9025.5 9034.2 9160.2 9232.5 9080.5 9123.2 9123.2 9191.5 9323.2 5962.3 6363.2 6661.0 6709.0 6766.0 7072.7 7103.7 7150.6 7174.2 7192.4 6591.9 6686.4 6748.1 6755.8 6838.0 6480.0 6485.1 6513.7 6557.8 6617.0 6332.6 6393.6 6407.1 6431.5 6550.9 4506.2 4583.3 4616.1 4631.8 4647.6 4579.2 4591.0 4622.8 4650.3 4691.2 18:25:01 INFO - PROCESS | 22571 | 18:25:01 INFO - PROCESS | 22571 | [#4] prototype.html Cycles:180 Average:4338.22 Median:5005.64 stddev:1283.71 (25.6%) stddev-sans-first:1250.80 18:25:01 INFO - PROCESS | 22571 | Values: 287.7 292.7 294.1 296.9 297.0 5050.5 5068.6 5074.8 5091.1 5093.5 4996.8 5009.4 5027.6 5030.6 5051.4 3726.8 3794.8 3908.8 3921.3 3968.7 3896.1 3911.3 3923.1 3923.3 3937.4 3874.7 3900.0 3905.9 3909.3 3910.2 3809.0 3891.1 3898.4 3913.6 3918.3 3400.2 3405.5 3416.8 3420.4 3425.7 4870.5 4871.6 4890.3 4915.6 4947.9 4317.4 4406.1 4460.1 4463.9 4468.9 5261.7 5262.1 5268.2 5270.3 5302.7 3705.1 3708.4 3709.9 3712.0 3713.5 5451.9 5454.6 5468.2 5480.1 5519.2 3441.9 3649.5 3654.4 3658.9 3661.6 3699.9 3706.2 3709.3 3716.8 3738.2 4328.9 4357.3 4365.5 4369.0 4384.3 5197.9 5218.3 5262.0 5276.0 5276.6 1282.5 1284.6 1290.2 1291.8 1293.9 793.8 807.2 808.6 820.4 826.6 4469.8 4541.7 4589.7 4599.2 4610.8 5400.8 5422.8 5425.6 5432.6 5435.6 4518.6 4583.3 4590.0 4615.2 4674.7 2897.2 2898.0 2905.6 2916.0 2929.8 5006.3 5072.4 5077.8 5087.8 5090.7 5367.0 5475.1 5502.2 5509.7 5659.3 4967.4 5004.9 5009.8 5019.6 5081.5 5419.0 5430.7 5449.8 5471.2 5474.6 5184.9 5338.7 5344.9 5363.7 5378.4 4976.5 5003.0 5007.5 5010.1 5029.9 3738.8 3740.7 3744.9 3748.1 3748.2 5237.6 5419.4 5449.7 5454.9 5456.0 5225.3 5242.4 5248.5 5257.2 5307.2 5227.9 5235.9 5260.1 5307.5 5309.5 4850.7 5142.5 5156.1 5165.9 5178.6 5023.6 5119.2 5257.6 5260.5 5292.0 5095.5 5128.3 5179.9 5205.3 5247.6 18:25:01 INFO - PROCESS | 22571 | 18:25:01 INFO - PROCESS | 22571 | [#5] yui.html Cycles:180 Average:16732.43 Median:608.54 stddev:24338.59 (3999.5%) stddev-sans-first:24376.71 18:25:01 INFO - PROCESS | 22571 | Values: 598.1 602.2 650.5 652.6 750.1 12209.2 12818.0 12975.7 13304.9 13309.4 12232.3 12302.1 12330.0 12342.4 12343.9 629.8 774.1 784.6 800.2 801.6 796.1 802.1 804.0 804.0 805.8 835.0 838.6 840.7 841.0 842.0 842.1 842.3 842.7 842.9 844.3 613.4 613.7 614.4 615.0 615.9 12615.9 12633.4 12648.5 12660.4 12691.9 12693.7 12955.8 13084.3 13208.5 13555.0 10445.9 10549.9 10660.3 10815.0 10866.2 3337.5 3351.5 3368.6 3400.9 3427.4 66076.7 66952.7 67930.7 68166.0 68201.0 10321.4 10325.4 10396.3 10499.4 10531.1 16170.7 16697.3 16789.9 17718.2 20066.4 6439.3 6895.3 6987.9 7126.0 7139.4 3744.2 3828.4 3832.6 3903.6 3951.8 2938.2 3061.0 3062.6 3090.4 3093.4 515.9 518.5 524.0 528.7 532.3 530.8 544.5 548.4 548.9 549.9 7166.1 7266.3 7298.7 7412.4 7434.4 423.8 533.2 558.8 561.8 603.7 3288.0 3301.9 3441.1 3471.1 3484.8 90462.0 90494.0 93539.0 93804.0 95535.0 80320.0 80509.6 80674.0 81211.0 81273.6 54970.7 71026.0 72583.6 73401.3 75200.2 6466.4 6624.2 6659.0 6693.3 6741.1 31058.8 35809.8 44034.8 44933.6 45306.5 39935.2 44578.0 61470.0 62389.0 62724.7 9786.9 9851.5 9873.4 10234.2 10266.9 11442.9 11469.3 11586.0 11809.8 12149.9 9810.7 10014.4 10100.5 10103.1 10180.3 8734.7 9027.5 9077.4 9213.1 9475.2 9072.0 9149.1 9152.3 9318.8 9366.5 8274.7 8687.7 8922.9 9056.0 9113.5 7784.7 8295.8 8558.6 8562.1 8682.6 18:25:01 INFO - PROCESS | 22571 | -------- Summary: end -------- 18:25:01 INFO - PROCESS | 22571 | 18:25:01 INFO - TEST-INFO | 22571: exit 0 18:25:01 INFO - TEST-OK | dromaeo_css | took 1116232ms 18:25:01 INFO - TEST-START | kraken 18:25:01 INFO - operating with platform_type : linux_ 18:25:01 INFO - Initialising browser for kraken test... 18:25:01 INFO - TEST-INFO | started process 22810 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4V8ypm/profile http://localhost:59397/getInfo.html) 18:25:06 INFO - PROCESS | 22810 | __metrics Screen width/height:1600/1200 18:25:06 INFO - PROCESS | 22810 | colorDepth:24 18:25:06 INFO - PROCESS | 22810 | Browser inner width/height: 1024/697 18:25:06 INFO - PROCESS | 22810 | __metrics 18:25:07 ERROR - PROCESS | 22810 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2947: TypeError: this.tabs is undefined 18:25:08 INFO - TEST-INFO | 22810: exit 0 18:25:08 INFO - Browser initialized. 18:25:08 INFO - Running cycle 1/1 for kraken test... 18:25:08 INFO - TEST-INFO | started process 22928 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4V8ypm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:25:20 INFO - PROCESS | 22928 | RSS: Main: 186097664 18:25:20 INFO - PROCESS | 22928 | 18:25:23 INFO - PROCESS | 22928 | Cycle 1(1): loaded http://localhost:59397/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59397/tests/kraken/driver.html?testName=audio-beat-detection) 18:25:24 INFO - PROCESS | 22928 | RSS: Main: 190750720 18:25:24 INFO - PROCESS | 22928 | 18:25:26 INFO - PROCESS | 22928 | Cycle 1(1): loaded http://localhost:59397/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59397/tests/kraken/driver.html?testName=audio-dft) 18:25:27 INFO - PROCESS | 22928 | RSS: Main: 190423040 18:25:27 INFO - PROCESS | 22928 | 18:25:30 INFO - PROCESS | 22928 | Cycle 1(1): loaded http://localhost:59397/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59397/tests/kraken/driver.html?testName=audio-fft) 18:25:31 INFO - PROCESS | 22928 | RSS: Main: 186572800 18:25:31 INFO - PROCESS | 22928 | 18:25:33 INFO - PROCESS | 22928 | Cycle 1(1): loaded http://localhost:59397/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59397/tests/kraken/driver.html?testName=audio-oscillator) 18:25:33 INFO - PROCESS | 22928 | RSS: Main: 186707968 18:25:33 INFO - PROCESS | 22928 | 18:25:36 INFO - PROCESS | 22928 | Cycle 1(1): loaded http://localhost:59397/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59397/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:25:36 INFO - PROCESS | 22928 | RSS: Main: 187752448 18:25:36 INFO - PROCESS | 22928 | 18:25:39 INFO - PROCESS | 22928 | Cycle 1(1): loaded http://localhost:59397/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59397/tests/kraken/driver.html?testName=imaging-darkroom) 18:25:40 INFO - PROCESS | 22928 | RSS: Main: 187686912 18:25:40 INFO - PROCESS | 22928 | 18:25:44 INFO - PROCESS | 22928 | Cycle 1(1): loaded http://localhost:59397/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59397/tests/kraken/driver.html?testName=imaging-desaturate) 18:25:44 INFO - PROCESS | 22928 | RSS: Main: 184586240 18:25:44 INFO - PROCESS | 22928 | 18:25:47 INFO - PROCESS | 22928 | Cycle 1(1): loaded http://localhost:59397/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59397/tests/kraken/driver.html?testName=json-parse-financial) 18:25:48 INFO - PROCESS | 22928 | RSS: Main: 186114048 18:25:48 INFO - PROCESS | 22928 | 18:25:50 INFO - PROCESS | 22928 | Cycle 1(1): loaded http://localhost:59397/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59397/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:25:50 INFO - PROCESS | 22928 | RSS: Main: 186421248 18:25:50 INFO - PROCESS | 22928 | 18:25:52 INFO - PROCESS | 22928 | Cycle 1(1): loaded http://localhost:59397/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59397/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:25:53 INFO - PROCESS | 22928 | RSS: Main: 186339328 18:25:53 INFO - PROCESS | 22928 | 18:25:55 INFO - PROCESS | 22928 | Cycle 1(1): loaded http://localhost:59397/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59397/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:25:56 INFO - PROCESS | 22928 | RSS: Main: 186843136 18:25:56 INFO - PROCESS | 22928 | 18:25:59 INFO - PROCESS | 22928 | Cycle 1(1): loaded http://localhost:59397/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59397/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:25:59 INFO - PROCESS | 22928 | RSS: Main: 187179008 18:25:59 INFO - PROCESS | 22928 | 18:26:02 INFO - PROCESS | 22928 | Cycle 1(1): loaded http://localhost:59397/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59397/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:26:03 INFO - PROCESS | 22928 | RSS: Main: 187101184 18:26:03 INFO - PROCESS | 22928 | 18:26:04 INFO - PROCESS | 22928 | Cycle 1(1): loaded http://localhost:59397/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59397/tests/kraken/driver.html?testName=ai-astar) 18:26:05 INFO - PROCESS | 22928 | RSS: Main: 188366848 18:26:05 INFO - PROCESS | 22928 | 18:26:05 INFO - PROCESS | 22928 | __start_tp_report 18:26:05 INFO - PROCESS | 22928 | _x_x_mozilla_page_load 18:26:05 INFO - PROCESS | 22928 | _x_x_mozilla_page_load_details 18:26:05 INFO - PROCESS | 22928 | |i|pagename|runs| 18:26:05 INFO - PROCESS | 22928 | |0;ai-astar;115;108;115;112;114;115;113;113;113;114 18:26:05 INFO - PROCESS | 22928 | |1;audio-beat-detection;150;132;161;189;142;163;161;135;186;133 18:26:05 INFO - PROCESS | 22928 | |2;audio-dft;162;164;172;167;164;164;162;164;164;163 18:26:05 INFO - PROCESS | 22928 | |3;audio-fft;77;75;76;163;76;77;76;76;162;76 18:26:05 INFO - PROCESS | 22928 | |4;audio-oscillator;87;87;148;87;87;88;145;86;87;154 18:26:05 INFO - PROCESS | 22928 | |5;imaging-gaussian-blur;117;118;117;117;117;117;118;118;119;117 18:26:05 INFO - PROCESS | 22928 | |6;imaging-darkroom;150;147;149;148;147;147;148;147;149;146 18:26:05 INFO - PROCESS | 22928 | |7;imaging-desaturate;96;96;96;96;97;95;96;97;96;96 18:26:05 INFO - PROCESS | 22928 | |8;json-parse-financial;64;64;64;66;63;64;68;65;65;64 18:26:05 INFO - PROCESS | 22928 | |9;json-stringify-tinderbox;71;66;65;66;66;66;65;65;66;65 18:26:05 INFO - PROCESS | 22928 | |10;stanford-crypto-aes;84;85;84;83;87;85;90;86;111;91 18:26:05 INFO - PROCESS | 22928 | |11;stanford-crypto-ccm;139;142;130;128;130;136;142;133;146;145 18:26:05 INFO - PROCESS | 22928 | |12;stanford-crypto-pbkdf2;171;170;173;171;165;171;165;170;176;173 18:26:05 INFO - PROCESS | 22928 | |13;stanford-crypto-sha256-iterative;67;69;69;66;67;72;67;68;70;66 18:26:05 INFO - PROCESS | 22928 | __end_tp_report 18:26:05 INFO - PROCESS | 22928 | __start_cc_report 18:26:05 INFO - PROCESS | 22928 | _x_x_mozilla_cycle_collect,1000 18:26:05 INFO - PROCESS | 22928 | __end_cc_report 18:26:05 INFO - PROCESS | 22928 | __startTimestamp1465953965216__endTimestamp 18:26:05 INFO - PROCESS | 22928 | 18:26:05 INFO - PROCESS | 22928 | ------- Summary: start ------- 18:26:05 INFO - PROCESS | 22928 | Number of tests: 14 18:26:05 INFO - PROCESS | 22928 | 18:26:05 INFO - PROCESS | 22928 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.00 stddev:2.10 (1.8%) stddev-sans-first:2.12 18:26:05 INFO - PROCESS | 22928 | Values: 115.0 108.0 115.0 112.0 114.0 115.0 113.0 113.0 113.0 114.0 18:26:05 INFO - PROCESS | 22928 | 18:26:05 INFO - PROCESS | 22928 | [#1] audio-beat-detection Cycles:10 Average:155.20 Median:161.00 stddev:20.71 (12.9%) stddev-sans-first:21.88 18:26:05 INFO - PROCESS | 22928 | Values: 150.0 132.0 161.0 189.0 142.0 163.0 161.0 135.0 186.0 133.0 18:26:05 INFO - PROCESS | 22928 | 18:26:05 INFO - PROCESS | 22928 | [#2] audio-dft Cycles:10 Average:164.60 Median:164.00 stddev:2.95 (1.8%) stddev-sans-first:2.98 18:26:05 INFO - PROCESS | 22928 | Values: 162.0 164.0 172.0 167.0 164.0 164.0 162.0 164.0 164.0 163.0 18:26:05 INFO - PROCESS | 22928 | 18:26:05 INFO - PROCESS | 22928 | [#3] audio-fft Cycles:10 Average:93.40 Median:76.00 stddev:36.42 (47.9%) stddev-sans-first:38.15 18:26:05 INFO - PROCESS | 22928 | Values: 77.0 75.0 76.0 163.0 76.0 77.0 76.0 76.0 162.0 76.0 18:26:05 INFO - PROCESS | 22928 | 18:26:05 INFO - PROCESS | 22928 | [#4] audio-oscillator Cycles:10 Average:105.60 Median:87.00 stddev:30.03 (34.5%) stddev-sans-first:31.09 18:26:05 INFO - PROCESS | 22928 | Values: 87.0 87.0 148.0 87.0 87.0 88.0 145.0 86.0 87.0 154.0 18:26:05 INFO - PROCESS | 22928 | 18:26:05 INFO - PROCESS | 22928 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:117.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 18:26:05 INFO - PROCESS | 22928 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 118.0 118.0 119.0 117.0 18:26:05 INFO - PROCESS | 22928 | 18:26:05 INFO - PROCESS | 22928 | [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:1.23 (0.8%) stddev-sans-first:1.01 18:26:05 INFO - PROCESS | 22928 | Values: 150.0 147.0 149.0 148.0 147.0 147.0 148.0 147.0 149.0 146.0 18:26:05 INFO - PROCESS | 22928 | 18:26:05 INFO - PROCESS | 22928 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 18:26:05 INFO - PROCESS | 22928 | Values: 96.0 96.0 96.0 96.0 97.0 95.0 96.0 97.0 96.0 96.0 18:26:05 INFO - PROCESS | 22928 | 18:26:05 INFO - PROCESS | 22928 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:64.50 stddev:1.42 (2.2%) stddev-sans-first:1.48 18:26:05 INFO - PROCESS | 22928 | Values: 64.0 64.0 64.0 66.0 63.0 64.0 68.0 65.0 65.0 64.0 18:26:05 INFO - PROCESS | 22928 | 18:26:05 INFO - PROCESS | 22928 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:66.00 stddev:1.79 (2.7%) stddev-sans-first:0.53 18:26:05 INFO - PROCESS | 22928 | Values: 71.0 66.0 65.0 66.0 66.0 66.0 65.0 65.0 66.0 65.0 18:26:05 INFO - PROCESS | 22928 | 18:26:05 INFO - PROCESS | 22928 | [#10] stanford-crypto-aes Cycles:10 Average:88.60 Median:85.50 stddev:8.29 (9.7%) stddev-sans-first:8.62 18:26:05 INFO - PROCESS | 22928 | Values: 84.0 85.0 84.0 83.0 87.0 85.0 90.0 86.0 111.0 91.0 18:26:05 INFO - PROCESS | 22928 | 18:26:05 INFO - PROCESS | 22928 | [#11] stanford-crypto-ccm Cycles:10 Average:137.10 Median:140.50 stddev:6.62 (4.7%) stddev-sans-first:6.99 18:26:05 INFO - PROCESS | 22928 | Values: 139.0 142.0 130.0 128.0 130.0 136.0 142.0 133.0 146.0 145.0 18:26:05 INFO - PROCESS | 22928 | 18:26:05 INFO - PROCESS | 22928 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.50 Median:171.00 stddev:3.41 (2.0%) stddev-sans-first:3.61 18:26:05 INFO - PROCESS | 22928 | Values: 171.0 170.0 173.0 171.0 165.0 171.0 165.0 170.0 176.0 173.0 18:26:05 INFO - PROCESS | 22928 | 18:26:05 INFO - PROCESS | 22928 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.50 stddev:1.91 (2.8%) stddev-sans-first:1.99 18:26:05 INFO - PROCESS | 22928 | Values: 67.0 69.0 69.0 66.0 67.0 72.0 67.0 68.0 70.0 66.0 18:26:05 INFO - PROCESS | 22928 | -------- Summary: end -------- 18:26:05 INFO - PROCESS | 22928 | 18:26:06 INFO - TEST-INFO | 22928: exit 0 18:26:06 INFO - TEST-OK | kraken | took 64439ms 18:26:06 INFO - SUITE-END | took 1180s 18:26:06 INFO - Completed test suite (00:19:40) 18:26:06 INFO - javascript benchmark 18:26:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6286.916845810974, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2592.0, 2641.8811153498086, 2645.96031059534, 2650.416908280179, 2664.214056853228, 4683.765811709413, 4712.0, 4730.621550275978, 4776.808927642899, 4812.374391329131, 4604.378408914796, 4771.689840160388, 4814.9759251203695, 4839.951600483994, 4842.782074806633, 4442.133783912146, 4462.910741785162, 4513.097380523908, 4527.864164075067, 4536.500984891659, 525.0975132131261, 4294.892627684318, 4386.890327741816, 4409.29451287794, 4410.0, 4405.449318835145, 4433.157700037015, 4444.333349997526, 4447.888802779924, 4488.887777805581, 4421.314696222059, 4443.488998765151, 4456.46522417312, 4460.93308600368, 4490.39379683745, 4299.183155200533, 4344.45694288214, 4353.673474489426, 4365.017870979036, 4372.650187984954, 4731.889380794513, 4741.312509686076, 4747.169245382044, 4753.239481682948, 4754.072955773626, 4916.532929371704, 5088.898222035538, 5095.79616815327, 5099.541041306263, 5152.9742351288005, 5195.636305458619, 5204.3234379530795, 5209.661372010807, 5210.166373380279, 5258.290130832327, 4810.134175848346, 4834.516548345207, 4839.975800120977, 4846.951530484721, 4887.608991280725, 5106.259592359087, 5284.128118860345, 5306.946930530646, 5313.362396512443, 5345.1447768356875, 5094.949050509447, 5098.898022039539, 5121.692698438105, 5129.435762066169, 5174.24973378858, 5213.687178769286, 5214.6610470320065, 5220.791168353299, 5234.633575649744, 5269.998700246941, 4919.311296418504, 4967.08108999829, 5046.26829109777, 5059.06407314648, 5179.352580927384, 5045.8738531537, 5060.507150390644, 5073.568746656501, 5076.060928728197, 5099.260607211933, 4334.609885110355, 4347.934780978288, 4352.673549483801, 4368.7815609219415, 4385.605295523418, 3687.6681098701247, 3700.20445604191, 3777.1879046005242, 3784.299904517673, 3811.19964807388, 4379.299312110047, 4384.956150438518, 4395.692301538926, 4426.092651006536, 4439.489458712224, 5173.120569503193, 5183.585313174937, 5187.118189907649, 5204.8959020819375, 5232.92150617741, 4317.0934103838545, 4425.048614547888, 4440.644748420119, 4453.465584129925, 4471.441069866267, 4675.859724208279, 4680.882977925579, 4683.8360657376825, 4708.223143181405, 4718.976405117953, 4834.323194752737, 4903.583195428429, 4914.189158788903, 4919.975400123048, 4932.383452068491, 4949.406071271326, 5175.585953123817, 5179.559737422362, 5218.3737951444555, 5251.711155886463, 4832.34763306949, 4844.370231869834, 4864.537868902448, 4881.487443818348, 4886.462489126265, 5131.666441681279, 5135.768890400016, 5138.152204886152, 5142.562882155059, 5187.507186817246, 5008.899822003613, 5163.509466600667, 5180.197069454241, 5213.6350455467755, 5240.895182096413, 5059.671121377099, 5088.465711100281, 5088.465711100429, 5102.744862756922, 5165.819196328111, 4878.243872199815, 5082.949170508247, 5098.592112631055, 5100.5409513143995, 5101.897962040664, 4882.072406242803, 4885.0, 4902.730349830794, 4909.5090490950615, 4912.582430493448, 5069.797208111634, 5092.668976516515, 5093.847184584469, 5094.133997220407, 5143.819966301162, 4830.541098595628, 4921.876953076058, 4969.0807200668, 4994.925076123788, 5034.0938631046765], "value": 4696.712429164096, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [528.0018175621947, 571.7255717255717, 585.1515302810923, 596.1385797522577, 599.6192417814688, 34980.82509587452, 39010.0, 39086.21827563451, 39100.21799564007, 39309.0, 40097.39853902194, 40606.7969660152, 40644.59355406445, 40696.59303406972, 40858.0, 7804.609769511516, 7888.2506161914525, 7953.920460795376, 7973.362131029533, 7976.760697179094, 7980.042394912619, 8028.157043510435, 8041.798955026142, 8050.677972881107, 8073.233042860918, 7983.680652773882, 8012.078610959756, 8013.799655008643, 8026.6789328427085, 8047.879281810778, 7933.16701746317, 7982.0820605630515, 8009.95995020027, 8024.317932975646, 8059.113497515239, 7743.186965368612, 7828.452008359417, 7883.3299169570655, 7919.406044546682, 7924.286814186693, 38544.614553854386, 38925.221495570215, 38960.61039389598, 39025.0, 39048.414273785915, 3149.7480201583817, 3242.2542815152647, 3434.6565343465454, 3612.313660404515, 3851.7111216658864, 28506.287342816388, 30292.277243557757, 30652.233694157603, 31227.687723122704, 31236.062918112493, 3485.4074807282823, 3533.0, 3568.8215589220695, 3594.2991116732246, 3695.0947017980766, 250820.0, 251587.0, 251972.0, 253451.73274133695, 253814.0, 31723.682763172535, 55888.72055639777, 56905.71547142321, 56964.14553781696, 57058.7147064262, 31540.526892096637, 33103.83448082744, 33112.834435827666, 33297.83351083229, 33462.498062529085, 12901.935490322488, 12974.156679815782, 13116.344182790823, 13131.671708207181, 13151.868481315256, 10148.949255253676, 10241.692749217535, 10272.229582781321, 10303.948480257552, 10308.433036182932, 7812.648430820626, 7903.091144518339, 7926.920730792734, 7938.404619653549, 7992.320652744582, 3105.4410206163107, 3122.344307695364, 3128.87484500622, 3149.0710240479116, 3197.088829683529, 871.2768402226137, 887.9334049946151, 927.5408672706918, 945.4752612300102, 961.5480724059681, 27470.0, 27476.58785118225, 27562.310942226606, 28201.71798282032, 28364.574531382044, 928.7956649537226, 990.5726187391562, 1013.7414959185508, 1019.0064686930332, 1096.786126705285, 14774.852251477347, 14829.703405932038, 15049.322780474906, 15129.211726578333, 15138.772918406232, 285728.0, 286374.0, 290781.0, 290784.0, 291068.0, 47420.76289618599, 47758.522414775405, 47903.5209647899, 48005.279920801215, 48168.5183148164, 1141.8230174322994, 1153.031453578981, 1163.2322667039714, 1167.854018247719, 1184.431472892999, 25772.355691107874, 27415.17754467369, 27495.0, 27563.0, 27564.586531201647, 42007.369889451074, 42403.0, 42520.57479425166, 42567.57432425636, 42603.14793704171, 247466.0, 250740.0, 252545.0, 252600.0, 252667.0, 25069.87465062663, 25301.493970120864, 25328.240152795446, 25472.363190919634, 25579.23262302134, 40973.1805363897, 41069.38395924004, 41375.0, 42100.0, 42420.36369454522, 35229.295414092085, 35454.290914182086, 35686.0, 36168.819155905105, 36211.27577448489, 35387.29225415529, 35900.64099358973, 35939.0, 35990.640093598726, 36478.45282320714, 34697.306053879285, 34731.30537389289, 35489.82255088708, 35596.644033559336, 35600.64399356077, 31064.068077957698, 32225.838870805495, 32451.837740811145, 33076.0, 34932.47601286034, 26216.8689156553, 26398.86800565985, 26666.86666566655, 26924.192274231806, 26939.0], "value": 16315.993947548644, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.4776647744011, 368.9372806622873, 371.270453558757, 374.69275194340617, 382.3232877806281, 15840.841591584081, 16516.58708532286, 16915.238814253356, 17348.56628584286, 17366.218520166592, 14952.775708364356, 16393.83606163938, 17040.829591704078, 17177.656446871057, 17253.137343132857, 5012.649114561982, 5180.8704782380555, 5221.895562088756, 5225.529702326789, 5229.450907654698, 5084.135696931512, 5089.389273287192, 5163.406208286039, 5177.45636708144, 5210.140326846066, 5407.107827208506, 5425.701586412746, 5469.343678758554, 5474.671519708811, 5476.123820188771, 5291.100512912814, 5321.388040375348, 5406.135018397038, 5447.863803404926, 5461.044317244467, 4476.216662084123, 4791.496892826272, 5243.292155558989, 5295.179247216687, 5322.361316641989, 11568.421578921021, 11886.940565297205, 11914.940425297904, 11937.641870743892, 12117.151799374047, 1835.6879330513846, 1845.169673646851, 1889.2726300374318, 1902.6194761047846, 1921.7597800274966, 8855.734327970107, 9086.954565227132, 9299.442033478013, 9631.71104866855, 13051.0, 1570.89789163704, 1670.4988503449038, 1715.905625190614, 1727.3349760342232, 1768.3722278591044, 53839.730801345744, 62737.266707401315, 63566.364336356506, 66427.66786166039, 71253.64373178153, 9663.27525435595, 9669.564869580885, 9897.901020989842, 9946.254030947708, 10047.346922450017, 13759.38082786277, 13791.51729689456, 13813.792793108112, 13816.723665526633, 13884.791728124086, 7060.082189315356, 7148.92851071493, 7179.389751871045, 7259.20148783644, 7267.709291628275, 6591.274959754423, 6745.0556922030955, 6745.831354216184, 6808.557443766238, 7006.719731210695, 4313.0, 4323.589259020388, 4355.934660980088, 4379.277419225792, 4429.180601588717, 1842.9354972575895, 1857.247814635075, 1875.2311552263507, 1878.5397577593303, 1891.5743957609427, 864.4035615425337, 876.369014309696, 881.9073997230198, 917.6237742525533, 957.1577012229333, 16619.91690041542, 17177.398791042255, 17482.388116415863, 17609.383671571435, 17720.20259088344, 845.8308338332358, 862.8101817600118, 881.9514926679094, 904.1997831918799, 918.912703293186, 7989.720359787496, 9566.712998610054, 9622.13400793932, 9653.0, 9680.27397945143, 430568.3970633038, 431809.0, 434352.0, 434532.0, 437153.0, 84177.15822841693, 84416.0, 84571.0, 85124.57437712896, 87260.5636971811, 801.6312496251672, 858.1632907914733, 895.0423047339284, 912.8402529557301, 916.8578870275118, 15693.450729224878, 16164.110973896548, 16169.110698911672, 16175.352985880432, 16371.263293151598, 35588.28823423569, 37385.626143739304, 37993.4300985491, 38240.80879595584, 38427.23145537129, 46739.065218694755, 48606.513934860195, 48938.51061489339, 55129.448705512434, 55445.72277138589, 7231.3853322466075, 7594.0, 7624.656890439833, 7692.846143076995, 7757.806054848674, 9825.194699001666, 11453.541858325574, 11574.594889178838, 11761.588344408246, 12018.639440816789, 9942.453165075991, 10377.169826413718, 10409.219308552041, 10505.264631475724, 10529.526171322463, 9770.16953558927, 9771.804563908825, 9811.55847986828, 9833.16418104469, 9846.655367062118, 5397.59518036141, 5500.642458240201, 9718.562664679965, 10135.54390052464, 10350.948245259027, 7417.1841097478155, 7471.925280747123, 7519.285667861544, 7645.732399365996, 7859.646315915912, 9937.850932236166, 10088.949555252177, 10141.645042423479, 10149.23880708935, 10426.843597346198], "value": 8030.448541593351, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1380.0, 1396.965075873103, 1481.2297605245274, 1680.5042512458815, 1684.7978242610889, 6960.895586566193, 7576.280253375928, 7685.846283074349, 8175.754727358174, 8189.836203275939, 7733.5746533940755, 8031.558264295461, 8036.316913062396, 8070.515769053846, 8074.313683336909, 2230.542738738555, 2233.530958498717, 2249.1228420915854, 2261.898214580348, 2263.230501629446, 2221.8222542196586, 2229.4537838229576, 2244.6408574628063, 2272.761360057195, 2275.340151356113, 2215.944601384962, 2230.2863083813186, 2233.0, 2236.4856083100894, 2237.664350347453, 2127.0853532980814, 2130.648443006902, 2132.4668832791804, 2138.98930505348, 2154.256781410411, 2156.762756096828, 2182.705334779816, 2191.1345018717675, 2195.0451553574085, 2198.8131008864266, 7952.840943181104, 7994.2405471480115, 8048.114707382183, 8064.637091330904, 8065.3144482719035, 4632.884177895579, 4680.4149481314835, 4745.193317136095, 4753.524647535253, 4757.333973243714, 2912.7378535931657, 2997.310618557722, 3108.518179682153, 3113.7664675149454, 3131.2015436063884, 1000.3697670467559, 1002.458672316948, 1015.6496008877, 1019.0879163149015, 1022.0699163760942, 10933.617323393642, 10934.125269978382, 10975.560977560968, 10983.725406864813, 11003.119750419946, 4178.8328466860985, 4335.3063509838275, 4382.517923028464, 4421.513633500313, 4422.469303683579, 9314.86027709585, 9319.627214911328, 9324.673636422693, 9336.90663093374, 9364.765880852898, 4720.244760838249, 4736.407949006374, 4744.95255047452, 4746.762661866893, 4761.928571071437, 3744.1949980753725, 4130.876073717794, 4159.83360665576, 4270.701050926467, 4277.893052673708, 3695.3902606070237, 3724.6089160638285, 3837.7889216092826, 3870.43878637596, 3891.747036442622, 1205.7106294489258, 1233.3956361382857, 1245.5951815659766, 1252.812078188279, 1270.1934271737327, 821.9424640275242, 852.6120615120105, 859.0250066174812, 862.1163307609625, 865.3336930563431, 5182.378114626194, 5303.231031500411, 5399.757010934517, 5524.088525393265, 5544.833654990357, 387.2951228763623, 402.0110528100846, 413.3345314044386, 421.4163383713579, 421.62475396896787, 3595.676389124991, 3634.509341238952, 3660.7254455915913, 3687.483752274684, 3695.0577602711137, 9473.66842160521, 9729.0, 9738.853917191247, 9827.459489728133, 9899.80200395988, 9667.081627245401, 9724.513774311255, 9758.9024109758, 9780.0, 9780.755481112887, 7482.476226664081, 7517.361024312885, 7526.962365188139, 7538.585377804164, 7545.094588649397, 5253.711045892513, 5319.0691628964005, 5413.350397952271, 5427.99582077317, 5441.564674826083, 8963.103689630985, 9025.548722563713, 9034.232090272137, 9160.221381182408, 9232.538373081185, 9080.500572468578, 9123.178913897516, 9123.224525915371, 9191.540422978958, 9323.160915517636, 5962.25471816023, 6363.172787537591, 6660.966695166493, 6709.0, 6765.966170169315, 7072.68172932209, 7103.715851365888, 7150.64246787669, 7174.2108368078425, 7192.42460603161, 6591.934080659132, 6686.364795344434, 6748.055272261985, 6755.763548275787, 6837.965810170917, 6480.0, 6485.0596663484475, 6513.706883190173, 6557.803265902031, 6617.040529123154, 6332.588381755171, 6393.584417012879, 6407.070974708734, 6431.485481161402, 6550.934490655223, 4506.233940230234, 4583.2666773316105, 4616.099860527165, 4631.814727410866, 4647.628189744744, 4579.221532339444, 4590.954090459053, 4622.791974361095, 4650.279206722963, 4691.249400095969], "value": 4126.739362612227, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.70797640395, 292.6648986909988, 294.0604767766984, 296.90146458105016, 296.973954985525, 5050.520200580943, 5068.645194836363, 5074.771635276416, 5091.134507133787, 5093.465186155456, 4996.750162491879, 5009.448960614339, 5027.622928280375, 5030.622703297258, 5051.419086805018, 3726.832292546842, 3794.7912864792424, 3908.8045597720143, 3921.274564205617, 3968.6825053995753, 3896.123372241251, 3911.256861196378, 3923.077691930962, 3923.3330333843314, 3937.4290727844445, 3874.6900247980093, 3899.9665088751503, 3905.941410878839, 3909.2963266612, 3910.198409326081, 3809.0, 3891.1050458394443, 3898.376259798447, 3913.5695073541888, 3918.275119102975, 3400.2179498715436, 3405.540252065964, 3416.777909435879, 3420.435628121357, 3425.6745609167338, 4870.51449307961, 4871.585915197213, 4890.266460030988, 4915.58217551505, 4947.851564453073, 4317.438732964726, 4406.083353741101, 4460.149038149197, 4463.933041004388, 4468.910621787546, 5261.737183076034, 5262.0791361511965, 5268.157094864803, 5270.262163297141, 5302.73486325686, 3705.1285253928154, 3708.42519409492, 3709.9055778545126, 3711.962880371189, 3713.461548075541, 5451.945480545183, 5454.59090568205, 5468.179773034037, 5480.08197131001, 5519.227308176858, 3441.896743097711, 3649.4525821127045, 3654.4152935530187, 3658.8657516170074, 3661.5606127264364, 3699.8705045323286, 3706.184639379332, 3709.276691045274, 3716.758410703296, 3738.158914244273, 4328.913421731548, 4357.302831547001, 4365.4761428628135, 4368.9951311197965, 4384.290126850531, 5197.908439227805, 5218.347706536683, 5262.0, 5275.973620131875, 5276.551493123127, 1282.5190553542423, 1284.6017734502334, 1290.1549485087282, 1291.8449786025737, 1293.8641442648575, 793.7936136604409, 807.1807115777491, 808.6482380164647, 820.3888103363033, 826.6238861318086, 4469.776511174428, 4541.6593755468475, 4589.655775816827, 4599.241125214369, 4610.76946152691, 5400.810971616053, 5422.8373148805595, 5425.647332923348, 5432.619716619799, 5435.565154787608, 4518.593326600556, 4583.304177454296, 4589.977050114728, 4615.23848564983, 4674.719516829001, 2897.2322334581354, 2898.0, 2905.564165375168, 2915.985420072886, 2929.765618750495, 5006.322881834626, 5072.365954255718, 5077.822276220315, 5087.847364579068, 5090.720010399464, 5366.9534440784455, 5475.123980163155, 5502.2296878437055, 5509.696966666792, 5659.320881494247, 4967.3542439482635, 5004.9499505005215, 5009.824656137018, 5019.573336266385, 5081.466446023188, 5418.972905135449, 5430.723779911797, 5449.809256676156, 5471.179323101566, 5474.616776825584, 5184.896302074013, 5338.706371149468, 5344.866378340573, 5363.731813409392, 5378.381486129145, 4976.531923082772, 5003.0, 5007.499250074963, 5010.098182327216, 5029.899402012013, 3738.7569807962395, 3740.7007439405324, 3744.943825842669, 3748.076500329747, 3748.175401411685, 5237.580993520585, 5419.376771671308, 5449.7275136243825, 5454.881749241316, 5456.03582710232, 5225.268462415341, 5242.423333433396, 5248.527632513092, 5257.158854583248, 5307.150855863198, 5227.895442091213, 5235.895282094413, 5260.0531904257605, 5307.531997621125, 5309.495597918191, 4850.708957462563, 5142.460041695568, 5156.1492353761205, 5165.9483405166975, 5178.637495375438, 5023.572996295356, 5119.235612769063, 5257.556300623098, 5260.473952604709, 5291.973540132429, 5095.490450954875, 5128.333316668809, 5179.92230116541, 5205.271262023244, 5247.606429517878], "value": 3918.8701132659476, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.064029793373, 602.1990752299445, 650.4633677216298, 652.6443088516759, 750.0961341583393, 12209.206401583902, 12818.0, 12975.67560810979, 13304.933475332635, 13309.401076951537, 12232.327222002787, 12302.138850280484, 12329.951954083914, 12342.444589993449, 12343.938280308608, 629.7764293675748, 774.1175060431113, 784.5998540744233, 800.2437696376926, 801.6272433318505, 796.0924546017544, 802.1497212954258, 803.9758807235763, 804.0311424733212, 805.8066064144622, 834.9707760228393, 838.6100463284572, 840.6805413942693, 840.9621567029499, 841.9621117049747, 842.1157784326433, 842.3387640702019, 842.7471758472465, 842.9283510901612, 844.3161039557978, 613.3804857094367, 613.6962203709193, 614.443928244939, 615.0313256620796, 615.8737458820975, 12615.936920315431, 12633.368331583384, 12648.494060237672, 12660.430280637302, 12691.873081269161, 12693.682657933534, 12955.805662915063, 13084.280364579945, 13208.537701180509, 13554.983376246722, 10445.9477702611, 10549.9472502637, 10660.307080039773, 10815.0, 10866.185036122244, 3337.466005439118, 3351.497275408708, 3368.6126095499085, 3400.931981360359, 3427.4002049641213, 66076.66961665209, 66952.6652366735, 67930.66034669794, 68166.0, 68201.0, 10321.380717156995, 10325.432101234504, 10396.324238924597, 10499.372597247439, 10531.104856087166, 16170.736096946437, 16697.33210671584, 16789.91605041967, 17718.202680879393, 20066.398008059783, 6439.323870993572, 6895.310468953065, 6987.860242795217, 7125.964370178116, 7139.428845692332, 3744.2324323513612, 3828.425736139601, 3832.559255685576, 3903.6096390360735, 3951.802409879552, 2938.162623652249, 3060.969390306113, 3062.5865508156558, 3090.3664748726455, 3093.3503964167326, 515.8865049689063, 518.4608007672055, 524.0462358507556, 528.7409169506991, 532.278762277109, 530.8195213627386, 544.5453046705966, 548.3940246028081, 548.9451054894479, 549.8542886135177, 7166.068411106523, 7266.346028857322, 7298.70805167798, 7412.407007439392, 7434.36807871337, 423.8241129931106, 533.218834407587, 558.8239704493071, 561.7752898840497, 603.6921170203141, 3287.967120328814, 3301.9339613207603, 3441.1225137589754, 3471.0975146461597, 3484.790912545255, 90462.0, 90494.0, 93539.0, 93804.0, 95535.0, 80320.0, 80509.59745201237, 80674.0, 81211.0, 81273.59363203146, 54970.72514637481, 71026.0, 72583.63708181425, 73401.29286424955, 75200.24799752042, 6466.353364663496, 6624.205095388585, 6658.966705166443, 6693.330666933268, 6741.089952856301, 31058.844705776326, 35809.82095089508, 44034.77982610066, 44933.550664492934, 45306.54693453023, 39935.2012959745, 44578.0, 61470.0, 62389.0, 62724.68637656783, 9786.85319720219, 9851.507424628597, 9873.358231714916, 10234.157851586786, 10266.948665256627, 11442.94278528602, 11469.254498457574, 11586.0, 11809.763804723685, 12149.878501215228, 9810.705678829647, 10014.449205293779, 10100.49497525106, 10103.141232994984, 10180.287379883337, 8734.737957861274, 9027.45835249887, 9077.409968352036, 9213.078692130734, 9475.24198064167, 9072.0, 9149.085091490797, 9152.313576481924, 9318.767030824283, 9366.531673416439, 8274.710385136732, 8687.739367818975, 8922.910770892207, 9056.0, 9113.498757568397, 7784.727534536264, 8295.751127466187, 8558.614862331086, 8562.143785621389, 8682.565871706263], "value": 6203.92210093459, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1588.4999999999995, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 108.0, 115.0, 112.0, 114.0, 115.0, 113.0, 113.0, 113.0, 114.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 132.0, 161.0, 189.0, 142.0, 163.0, 161.0, 135.0, 186.0, 133.0], "value": 155.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 172.0, 167.0, 164.0, 164.0, 162.0, 164.0, 164.0, 163.0], "value": 164.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 76.0, 163.0, 76.0, 77.0, 76.0, 76.0, 162.0, 76.0], "value": 93.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 148.0, 87.0, 87.0, 88.0, 145.0, 86.0, 87.0, 154.0], "value": 105.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 119.0, 117.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 147.0, 149.0, 148.0, 147.0, 147.0, 148.0, 147.0, 149.0, 146.0], "value": 147.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 97.0, 95.0, 96.0, 97.0, 96.0, 96.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 66.0, 63.0, 64.0, 68.0, 65.0, 65.0, 64.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 66.0, 65.0, 66.0, 66.0, 66.0, 65.0, 65.0, 66.0, 65.0], "value": 66.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 83.0, 87.0, 85.0, 90.0, 86.0, 111.0, 91.0], "value": 88.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 142.0, 130.0, 128.0, 130.0, 136.0, 142.0, 133.0, 146.0, 145.0], "value": 137.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 170.0, 173.0, 171.0, 165.0, 171.0, 165.0, 170.0, 176.0, 173.0], "value": 170.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 69.0, 66.0, 67.0, 72.0, 67.0, 68.0, 70.0, 66.0], "value": 68.1, "unit": "score"}]}]} 18:26:06 INFO - Return code: 0 18:26:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:26:06 ERROR - # TBPL SUCCESS # 18:26:06 INFO - Running post-action listener: _resource_record_post_action 18:26:06 INFO - ##### 18:26:06 INFO - ##### Finished run-tests step (success) 18:26:06 INFO - ##### 18:26:06 INFO - Running post-run listener: _resource_record_post_run 18:26:06 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 544468992; Read time: 8; Write time: 532852 18:26:06 INFO - TinderboxPrint: CPU usage
13.4% 18:26:06 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 18:26:06 INFO - TinderboxPrint: I/O write bytes / time
544,468,992 / 532,852 18:26:06 INFO - TinderboxPrint: CPU idle
8,271.0 (86.5%) 18:26:06 INFO - TinderboxPrint: CPU user
1,229.9 (12.9%) 18:26:06 INFO - TinderboxPrint: Swap in / out
0 / 0 18:26:06 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 245121024; Read time: 0; Write time: 301012 18:26:06 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 4096; Write bytes: 299347968; Read time: 8; Write time: 231840 18:26:07 INFO - Running post-run listener: _upload_blobber_files 18:26:07 INFO - Blob upload gear active. 18:26:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:26:07 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 18:26:07 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'] 18:26:07 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 18:26:07 INFO - (blobuploader) - INFO - Open directory for files ... 18:26:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:26:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:26:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:26:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:26:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:26:08 INFO - (blobuploader) - INFO - Done attempting. 18:26:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:26:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:26:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:26:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:26:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:26:09 INFO - (blobuploader) - INFO - Done attempting. 18:26:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:26:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:26:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:26:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:26:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:26:14 INFO - (blobuploader) - INFO - Done attempting. 18:26:14 INFO - (blobuploader) - INFO - Iteration through files over. 18:26:14 INFO - Return code: 0 18:26:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:26:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:26:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f935f2b8396da70f0fd5b3e2afa366a7e7d1e053bd076d32f7c4f7d26eb992a62ba6c69c8f0b3926e96381c47a647025b6d01db561e458362f67c1ba84c3f066", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c966e82f01d9b50887606cb81bffdc09a0578e4dec5f161bc92e710e8653f5cc4b91dbba80e1d42065e47b5253162b56f2683d0d2e634cafe018b7d708a75aad", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/26be38179edf85a06f1bc7e4dd957e233324a3fe42e835422df85fc9d774dbb7ee7f64c5fcb748052bcbc07ef27819d7c1952ccdf03d612fe7075a6c8a68852b"} 18:26:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:26:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:26:14 INFO - Contents: 18:26:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f935f2b8396da70f0fd5b3e2afa366a7e7d1e053bd076d32f7c4f7d26eb992a62ba6c69c8f0b3926e96381c47a647025b6d01db561e458362f67c1ba84c3f066", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c966e82f01d9b50887606cb81bffdc09a0578e4dec5f161bc92e710e8653f5cc4b91dbba80e1d42065e47b5253162b56f2683d0d2e634cafe018b7d708a75aad", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/26be38179edf85a06f1bc7e4dd957e233324a3fe42e835422df85fc9d774dbb7ee7f64c5fcb748052bcbc07ef27819d7c1952ccdf03d612fe7075a6c8a68852b"} 18:26:14 INFO - Running post-run listener: copy_logs_to_upload_dir 18:26:14 INFO - Copying logs to upload dir... 18:26:14 INFO - mkdir: /builds/slave/test/build/upload/logs 18:26:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.897903 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-06-14 18:26:14.401093) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-14 18:26:14.404908) ========= 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=62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f935f2b8396da70f0fd5b3e2afa366a7e7d1e053bd076d32f7c4f7d26eb992a62ba6c69c8f0b3926e96381c47a647025b6d01db561e458362f67c1ba84c3f066", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c966e82f01d9b50887606cb81bffdc09a0578e4dec5f161bc92e710e8653f5cc4b91dbba80e1d42065e47b5253162b56f2683d0d2e634cafe018b7d708a75aad", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/26be38179edf85a06f1bc7e4dd957e233324a3fe42e835422df85fc9d774dbb7ee7f64c5fcb748052bcbc07ef27819d7c1952ccdf03d612fe7075a6c8a68852b"} build_url:https://queue.taskcluster.net/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011416 build_url: 'https://queue.taskcluster.net/v1/task/Q1i0hytxRvO49n2Lq05SNA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f935f2b8396da70f0fd5b3e2afa366a7e7d1e053bd076d32f7c4f7d26eb992a62ba6c69c8f0b3926e96381c47a647025b6d01db561e458362f67c1ba84c3f066", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c966e82f01d9b50887606cb81bffdc09a0578e4dec5f161bc92e710e8653f5cc4b91dbba80e1d42065e47b5253162b56f2683d0d2e634cafe018b7d708a75aad", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/26be38179edf85a06f1bc7e4dd957e233324a3fe42e835422df85fc9d774dbb7ee7f64c5fcb748052bcbc07ef27819d7c1952ccdf03d612fe7075a6c8a68852b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-14 18:26:14.433564) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 18:26:14.434131) ========= 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=62c568580acbfdaac3eec7be000001dc-1465952730.140197-2058615769 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004470 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 18:26:14.518477) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-14 18:26:14.518810) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-14 18:26:14.519135) ========= ========= Total master_lag: 0.19 =========