builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-057 starttime: 1467973871.68 results: success (0) buildid: 20160708004052 builduid: d3a4d8a905324d4583946257d3fa4057 revision: 00e9101b9ed0353afd4e7dd956a063d7ac511c91 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 03:31:11.678422) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 03:31:11.678865) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 03:31:11.679151) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 03:31:11.693865) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 03:31:11.694140) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-08 03:31:12-- 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.5M=0s 2016-07-08 03:31:12 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.107355 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 03:31:11.815449) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 03:31:11.815775) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018782 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 03:31:11.864675) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 03:31:11.865011) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 00e9101b9ed0353afd4e7dd956a063d7ac511c91 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 00e9101b9ed0353afd4e7dd956a063d7ac511c91 --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=934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-08 03:31:12,293 truncating revision to first 12 chars 2016-07-08 03:31:12,293 Setting DEBUG logging. 2016-07-08 03:31:12,293 attempt 1/10 2016-07-08 03:31:12,293 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/00e9101b9ed0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-08 03:31:13,305 unpacking tar archive at: mozilla-aurora-00e9101b9ed0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.338428 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 03:31:13.217166) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 03:31:13.217542) ========= script_repo_revision: 00e9101b9ed0353afd4e7dd956a063d7ac511c91 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 03:31:13.218056) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 03:31:13.218380) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 03:31:13.227707) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-07-08 03:31:13.228098) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:31:13 INFO - MultiFileLogger online at 20160708 03:31:13 in /builds/slave/test 03:31:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:31:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:31:13 INFO - {'append_to_log': False, 03:31:13 INFO - 'base_work_dir': '/builds/slave/test', 03:31:13 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:31:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:31:13 INFO - 'branch': 'Mozilla-Aurora', 03:31:13 INFO - 'buildbot_json_path': 'buildprops.json', 03:31:13 INFO - 'config_files': ('talos/linux_config.py',), 03:31:13 INFO - 'default_actions': ('clobber', 03:31:13 INFO - 'read-buildbot-config', 03:31:13 INFO - 'download-and-extract', 03:31:13 INFO - 'populate-webroot', 03:31:13 INFO - 'create-virtualenv', 03:31:13 INFO - 'install', 03:31:13 INFO - 'run-tests'), 03:31:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:31:13 INFO - 'download_minidump_stackwalk': True, 03:31:13 INFO - 'download_symbols': 'ondemand', 03:31:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:31:13 INFO - 'tooltool.py': '/tools/tooltool.py', 03:31:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:31:13 INFO - '/tools/misc-python/virtualenv.py')}, 03:31:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:31:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:31:13 INFO - 'installer_path': 'installer.exe', 03:31:13 INFO - 'log_level': 'info', 03:31:13 INFO - 'log_name': 'talos', 03:31:13 INFO - 'log_to_console': True, 03:31:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:31:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:31:13 INFO - 'opt_config_files': (), 03:31:13 INFO - 'pip_index': False, 03:31:13 INFO - 'sps_profile': False, 03:31:13 INFO - 'sps_profile_interval': 0, 03:31:13 INFO - 'suite': 'dromaeojs', 03:31:13 INFO - 'system_bits': '32', 03:31:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:31:13 INFO - 'title': 'talos-linux64-ix-057', 03:31:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:31:13 INFO - 'use_talos_json': True, 03:31:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:31:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:31:13 INFO - 'work_dir': 'build'} 03:31:13 INFO - ##### 03:31:13 INFO - ##### Running clobber step. 03:31:13 INFO - ##### 03:31:13 INFO - Running pre-action listener: _resource_record_pre_action 03:31:13 INFO - Running main action method: clobber 03:31:13 INFO - rmtree: /builds/slave/test/build 03:31:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:31:14 INFO - Running post-action listener: _resource_record_post_action 03:31:14 INFO - ##### 03:31:14 INFO - ##### Finished clobber step (success) 03:31:14 INFO - ##### 03:31:14 INFO - ##### 03:31:14 INFO - ##### Running read-buildbot-config step. 03:31:14 INFO - ##### 03:31:14 INFO - Running pre-action listener: _resource_record_pre_action 03:31:14 INFO - Running main action method: read_buildbot_config 03:31:14 INFO - Using buildbot properties: 03:31:14 INFO - { 03:31:14 INFO - "project": "", 03:31:14 INFO - "product": "firefox", 03:31:14 INFO - "script_repo_revision": "production", 03:31:14 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:31:14 INFO - "repository": "", 03:31:14 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:31:14 INFO - "buildid": "20160708004052", 03:31:14 INFO - "stage_platform": "linux64-pgo", 03:31:14 INFO - "basedir": "/builds/slave/test", 03:31:14 INFO - "nightly_build": "True", 03:31:14 INFO - "buildnumber": 598, 03:31:14 INFO - "slavename": "talos-linux64-ix-057", 03:31:14 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:31:14 INFO - "platform": "ubuntu64_hw", 03:31:14 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:31:14 INFO - "branch": "mozilla-aurora", 03:31:14 INFO - "repo_path": "releases/mozilla-aurora", 03:31:14 INFO - "revision": "00e9101b9ed0353afd4e7dd956a063d7ac511c91", 03:31:14 INFO - "pgo_build": "True", 03:31:14 INFO - "builduid": "d3a4d8a905324d4583946257d3fa4057", 03:31:14 INFO - "slavebuilddir": "test" 03:31:14 INFO - } 03:31:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 03:31:14 INFO - Running post-action listener: _resource_record_post_action 03:31:14 INFO - ##### 03:31:14 INFO - ##### Finished read-buildbot-config step (success) 03:31:14 INFO - ##### 03:31:14 INFO - ##### 03:31:14 INFO - ##### Running download-and-extract step. 03:31:14 INFO - ##### 03:31:14 INFO - Running pre-action listener: _resource_record_pre_action 03:31:14 INFO - Running main action method: download_and_extract 03:31:14 INFO - mkdir: /builds/slave/test/build/tests 03:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:14 INFO - https://queue.taskcluster.net/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:31:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:31:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:31:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:31:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:31:14 INFO - Downloaded 1361 bytes. 03:31:14 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:31:14 INFO - Using the following test package requirements: 03:31:14 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:31:14 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:14 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:31:14 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:14 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:31:14 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:14 INFO - u'jsshell-linux-x86_64.zip'], 03:31:14 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:14 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:31:14 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:31:14 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:14 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:31:14 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:14 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:31:14 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:14 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:31:14 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:14 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:31:14 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:14 INFO - https://queue.taskcluster.net/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:31:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:31:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:31:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:31:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:31:15 INFO - Downloaded 24525620 bytes. 03:31:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:31:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:31:17 INFO - Return code: 0 03:31:17 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:17 INFO - https://queue.taskcluster.net/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:31:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:31:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:31:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:31:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:31:18 INFO - Downloaded 24525620 bytes. 03:31:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:31:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:31:19 INFO - Return code: 0 03:31:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:19 INFO - https://queue.taskcluster.net/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:31:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:31:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:31:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:31:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:31:20 INFO - Downloaded 10833369 bytes. 03:31:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:31:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:31:20 INFO - Return code: 0 03:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:20 INFO - https://queue.taskcluster.net/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:31:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:31:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:31:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:31:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:31:22 INFO - Downloaded 63637316 bytes. 03:31:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:31:22 INFO - mkdir: /builds/slave/test/properties 03:31:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:31:22 INFO - Writing to file /builds/slave/test/properties/build_url 03:31:22 INFO - Contents: 03:31:22 INFO - build_url:https://queue.taskcluster.net/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:31:23 INFO - Running post-action listener: _resource_record_post_action 03:31:23 INFO - Running post-action listener: set_extra_try_arguments 03:31:23 INFO - ##### 03:31:23 INFO - ##### Finished download-and-extract step (success) 03:31:23 INFO - ##### 03:31:23 INFO - ##### 03:31:23 INFO - ##### Running populate-webroot step. 03:31:23 INFO - ##### 03:31:23 INFO - Running pre-action listener: _resource_record_pre_action 03:31:23 INFO - Running main action method: populate_webroot 03:31:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:31:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:31:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:31:23 INFO - '--tppagecycles', 03:31:23 INFO - '7'], 03:31:23 INFO - 'tests': ['tsvgm']}}, 03:31:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:31:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:31:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:31:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:31:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:31:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:31:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:23 INFO - 'talos_options': ['--disable-e10s'], 03:31:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:31:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:31:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:23 INFO - 'talos_options': ['--disable-e10s'], 03:31:23 INFO - 'tests': ['damp', 'tps']}, 03:31:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:23 INFO - 'tests': ['damp', 'tps']}, 03:31:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:31:23 INFO - 'tests': ['dromaeo_dom']}, 03:31:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:31:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:31:23 INFO - 'tests': ['a11yr', 03:31:23 INFO - 'ts_paint', 03:31:23 INFO - 'tpaint', 03:31:23 INFO - 'sessionrestore', 03:31:23 INFO - 'sessionrestore_no_auto_restore', 03:31:23 INFO - 'tabpaint']}, 03:31:23 INFO - 'other-e10s': {'tests': ['a11yr', 03:31:23 INFO - 'ts_paint', 03:31:23 INFO - 'tpaint', 03:31:23 INFO - 'sessionrestore', 03:31:23 INFO - 'sessionrestore_no_auto_restore', 03:31:23 INFO - 'tabpaint']}, 03:31:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:31:23 INFO - 'ts_paint', 03:31:23 INFO - 'tpaint', 03:31:23 INFO - 'sessionrestore', 03:31:23 INFO - 'sessionrestore_no_auto_restore', 03:31:23 INFO - 'tabpaint']}, 03:31:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:31:23 INFO - 'ts_paint', 03:31:23 INFO - 'tpaint', 03:31:23 INFO - 'sessionrestore', 03:31:23 INFO - 'sessionrestore_no_auto_restore', 03:31:23 INFO - 'tabpaint']}, 03:31:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:31:23 INFO - 'tests': ['a11yr', 03:31:23 INFO - 'ts_paint', 03:31:23 INFO - 'tpaint', 03:31:23 INFO - 'sessionrestore', 03:31:23 INFO - 'sessionrestore_no_auto_restore', 03:31:23 INFO - 'tabpaint']}, 03:31:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:31:23 INFO - 'tests': ['a11yr', 03:31:23 INFO - 'ts_paint', 03:31:23 INFO - 'tpaint', 03:31:23 INFO - 'sessionrestore', 03:31:23 INFO - 'sessionrestore_no_auto_restore', 03:31:23 INFO - 'tabpaint']}, 03:31:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:31:23 INFO - 'tests': ['tsvgx', 03:31:23 INFO - 'tsvgr_opacity', 03:31:23 INFO - 'tart', 03:31:23 INFO - 'tscrollx', 03:31:23 INFO - 'cart']}, 03:31:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:31:23 INFO - 'tsvgr_opacity', 03:31:23 INFO - 'tart', 03:31:23 INFO - 'tscrollx', 03:31:23 INFO - 'cart']}, 03:31:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:23 INFO - 'talos_options': ['--disable-e10s'], 03:31:23 INFO - 'tests': ['tp5o']}, 03:31:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:23 INFO - 'tests': ['tp5o']}, 03:31:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:23 INFO - 'talos_options': ['--disable-e10s', 03:31:23 INFO - '--xperf_path', 03:31:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:31:23 INFO - 'tests': ['tp5n']}, 03:31:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:23 INFO - 'talos_options': ['--xperf_path', 03:31:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:31:23 INFO - 'tests': ['tp5n']}}, 03:31:23 INFO - 'talos.zip': {'path': '', 03:31:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:31:23 INFO - Running post-action listener: _resource_record_post_action 03:31:23 INFO - ##### 03:31:23 INFO - ##### Finished populate-webroot step (success) 03:31:23 INFO - ##### 03:31:23 INFO - ##### 03:31:23 INFO - ##### Running create-virtualenv step. 03:31:23 INFO - ##### 03:31:23 INFO - Running pre-action listener: _resource_record_pre_action 03:31:23 INFO - Running main action method: create_virtualenv 03:31:23 INFO - Creating virtualenv /builds/slave/test/build/venv 03:31:23 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 03:31:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:31:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:31:23 INFO - Using real prefix '/usr' 03:31:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:31:23 INFO - Installing distribute.............................................................................................................................................................................................done. 03:31:25 INFO - Installing pip.................done. 03:31:25 INFO - Return code: 0 03:31:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:31:25 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')]} 03:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:25 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')]} 03:31:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:25 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 0x7fc8ae3e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aac020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3e2e0>, '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 0x1aaa140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa5d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', '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 03:31:25 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 03:31:25 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 03:31:25 INFO - Using env: {'DISPLAY': ':0', 03:31:25 INFO - 'HOME': '/home/cltbld', 03:31:25 INFO - 'LANG': 'en_US.UTF-8', 03:31:25 INFO - 'LANGUAGE': 'en_US:en', 03:31:25 INFO - 'LOGNAME': 'cltbld', 03:31:25 INFO - 'MAIL': '/var/mail/cltbld', 03:31:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:25 INFO - 'MOZ_NO_REMOTE': '1', 03:31:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:25 INFO - 'NO_EM_RESTART': '1', 03:31:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:25 INFO - 'PWD': '/builds/slave/test', 03:31:25 INFO - 'SHELL': '/bin/bash', 03:31:25 INFO - 'SHLVL': '1', 03:31:25 INFO - 'TERM': 'linux', 03:31:25 INFO - 'TMOUT': '86400', 03:31:25 INFO - 'USER': 'cltbld', 03:31:25 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', 03:31:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:25 INFO - '_': '/tools/buildbot/bin/python'} 03:31:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:25 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 03:31:25 INFO - Cleaning up... 03:31:25 INFO - Return code: 0 03:31:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:31:25 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')]} 03:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:25 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')]} 03:31:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:25 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 0x7fc8ae3e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aac020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3e2e0>, '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 0x1aaa140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa5d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', '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 03:31:25 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 03:31:25 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 03:31:25 INFO - Using env: {'DISPLAY': ':0', 03:31:25 INFO - 'HOME': '/home/cltbld', 03:31:25 INFO - 'LANG': 'en_US.UTF-8', 03:31:25 INFO - 'LANGUAGE': 'en_US:en', 03:31:25 INFO - 'LOGNAME': 'cltbld', 03:31:25 INFO - 'MAIL': '/var/mail/cltbld', 03:31:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:25 INFO - 'MOZ_NO_REMOTE': '1', 03:31:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:25 INFO - 'NO_EM_RESTART': '1', 03:31:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:25 INFO - 'PWD': '/builds/slave/test', 03:31:25 INFO - 'SHELL': '/bin/bash', 03:31:25 INFO - 'SHLVL': '1', 03:31:25 INFO - 'TERM': 'linux', 03:31:25 INFO - 'TMOUT': '86400', 03:31:25 INFO - 'USER': 'cltbld', 03:31:25 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', 03:31:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:25 INFO - '_': '/tools/buildbot/bin/python'} 03:31:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:26 INFO - Downloading/unpacking psutil>=3.1.1 03:31:26 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 03:31:26 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 03:31:26 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 03:31:26 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 03:31:26 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 03:31:26 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 03:31:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:31:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:31:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:31:28 INFO - Installing collected packages: psutil 03:31:28 INFO - Running setup.py install for psutil 03:31:28 INFO - building 'psutil._psutil_linux' extension 03:31:28 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 03:31:28 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 03:31:28 INFO - building 'psutil._psutil_posix' extension 03:31:28 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 03:31:28 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 03:31:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:31:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:31:28 INFO - Successfully installed psutil 03:31:28 INFO - Cleaning up... 03:31:28 INFO - Return code: 0 03:31:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:31:28 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')]} 03:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:28 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')]} 03:31:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:28 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 0x7fc8ae3e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aac020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3e2e0>, '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 0x1aaa140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa5d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', '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 03:31:28 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 03:31:28 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 03:31:28 INFO - Using env: {'DISPLAY': ':0', 03:31:28 INFO - 'HOME': '/home/cltbld', 03:31:28 INFO - 'LANG': 'en_US.UTF-8', 03:31:28 INFO - 'LANGUAGE': 'en_US:en', 03:31:28 INFO - 'LOGNAME': 'cltbld', 03:31:28 INFO - 'MAIL': '/var/mail/cltbld', 03:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:28 INFO - 'MOZ_NO_REMOTE': '1', 03:31:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:28 INFO - 'NO_EM_RESTART': '1', 03:31:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:28 INFO - 'PWD': '/builds/slave/test', 03:31:28 INFO - 'SHELL': '/bin/bash', 03:31:28 INFO - 'SHLVL': '1', 03:31:28 INFO - 'TERM': 'linux', 03:31:28 INFO - 'TMOUT': '86400', 03:31:28 INFO - 'USER': 'cltbld', 03:31:28 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', 03:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:28 INFO - '_': '/tools/buildbot/bin/python'} 03:31:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:31:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:31:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:31:30 INFO - Installing collected packages: mozsystemmonitor 03:31:30 INFO - Running setup.py install for mozsystemmonitor 03:31:30 INFO - Successfully installed mozsystemmonitor 03:31:30 INFO - Cleaning up... 03:31:31 INFO - Return code: 0 03:31:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:31:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:31 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 0x7fc8ae3e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aac020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3e2e0>, '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 0x1aaa140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa5d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', '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 03:31:31 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 03:31:31 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 03:31:31 INFO - Using env: {'DISPLAY': ':0', 03:31:31 INFO - 'HOME': '/home/cltbld', 03:31:31 INFO - 'LANG': 'en_US.UTF-8', 03:31:31 INFO - 'LANGUAGE': 'en_US:en', 03:31:31 INFO - 'LOGNAME': 'cltbld', 03:31:31 INFO - 'MAIL': '/var/mail/cltbld', 03:31:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:31 INFO - 'MOZ_NO_REMOTE': '1', 03:31:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:31 INFO - 'NO_EM_RESTART': '1', 03:31:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:31 INFO - 'PWD': '/builds/slave/test', 03:31:31 INFO - 'SHELL': '/bin/bash', 03:31:31 INFO - 'SHLVL': '1', 03:31:31 INFO - 'TERM': 'linux', 03:31:31 INFO - 'TMOUT': '86400', 03:31:31 INFO - 'USER': 'cltbld', 03:31:31 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', 03:31:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:31 INFO - '_': '/tools/buildbot/bin/python'} 03:31:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:31 INFO - Downloading/unpacking jsonschema==2.5.1 03:31:31 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 03:31:31 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 03:31:31 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 03:31:31 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 03:31:31 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 03:31:31 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 03:31:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:31:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:31:33 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 03:31:33 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 03:31:33 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 03:31:33 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 03:31:33 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 03:31:33 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 03:31:33 INFO - Downloading functools32-3.2.3-2.tar.gz 03:31:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:31:33 INFO - warning: no files found matching '*.txt' 03:31:33 INFO - no previously-included directories found matching 'build' 03:31:33 INFO - no previously-included directories found matching 'dist' 03:31:33 INFO - no previously-included directories found matching '.git*' 03:31:33 INFO - Installing collected packages: jsonschema, functools32 03:31:33 INFO - Running setup.py install for functools32 03:31:33 INFO - warning: no files found matching '*.txt' 03:31:33 INFO - no previously-included directories found matching 'build' 03:31:33 INFO - no previously-included directories found matching 'dist' 03:31:33 INFO - no previously-included directories found matching '.git*' 03:31:33 INFO - Successfully installed jsonschema functools32 03:31:33 INFO - Cleaning up... 03:31:33 INFO - Return code: 0 03:31:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:31:33 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')]} 03:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:33 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')]} 03:31:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:33 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 0x7fc8ae3e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aac020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3e2e0>, '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 0x1aaa140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa5d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', '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 03:31:33 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 03:31:33 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 03:31:33 INFO - Using env: {'DISPLAY': ':0', 03:31:33 INFO - 'HOME': '/home/cltbld', 03:31:33 INFO - 'LANG': 'en_US.UTF-8', 03:31:33 INFO - 'LANGUAGE': 'en_US:en', 03:31:33 INFO - 'LOGNAME': 'cltbld', 03:31:33 INFO - 'MAIL': '/var/mail/cltbld', 03:31:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:33 INFO - 'MOZ_NO_REMOTE': '1', 03:31:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:33 INFO - 'NO_EM_RESTART': '1', 03:31:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:33 INFO - 'PWD': '/builds/slave/test', 03:31:33 INFO - 'SHELL': '/bin/bash', 03:31:33 INFO - 'SHLVL': '1', 03:31:33 INFO - 'TERM': 'linux', 03:31:33 INFO - 'TMOUT': '86400', 03:31:33 INFO - 'USER': 'cltbld', 03:31:33 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', 03:31:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:33 INFO - '_': '/tools/buildbot/bin/python'} 03:31:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:33 INFO - Downloading/unpacking blobuploader==1.2.4 03:31:33 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 03:31:33 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 03:31:33 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 03:31:33 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 03:31:33 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 03:31:33 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 03:31:35 INFO - Downloading blobuploader-1.2.4.tar.gz 03:31:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:31:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:31:35 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 03:31:35 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 03:31:35 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 03:31:35 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 03:31:35 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 03:31:35 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 03:31:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:31:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:31:36 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 03:31:36 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 03:31:36 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 03:31:36 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 03:31:36 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 03:31:36 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 03:31:36 INFO - Downloading docopt-0.6.1.tar.gz 03:31:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:31:36 INFO - Installing collected packages: blobuploader, requests, docopt 03:31:36 INFO - Running setup.py install for blobuploader 03:31:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:31:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:31:36 INFO - Running setup.py install for requests 03:31:36 INFO - Running setup.py install for docopt 03:31:36 INFO - Successfully installed blobuploader requests docopt 03:31:36 INFO - Cleaning up... 03:31:37 INFO - Return code: 0 03:31:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:31:37 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')]} 03:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:37 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')]} 03:31:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:37 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 0x7fc8ae3e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aac020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3e2e0>, '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 0x1aaa140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa5d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', '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 03:31:37 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 03:31:37 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 03:31:37 INFO - Using env: {'DISPLAY': ':0', 03:31:37 INFO - 'HOME': '/home/cltbld', 03:31:37 INFO - 'LANG': 'en_US.UTF-8', 03:31:37 INFO - 'LANGUAGE': 'en_US:en', 03:31:37 INFO - 'LOGNAME': 'cltbld', 03:31:37 INFO - 'MAIL': '/var/mail/cltbld', 03:31:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:37 INFO - 'MOZ_NO_REMOTE': '1', 03:31:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:37 INFO - 'NO_EM_RESTART': '1', 03:31:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:37 INFO - 'PWD': '/builds/slave/test', 03:31:37 INFO - 'SHELL': '/bin/bash', 03:31:37 INFO - 'SHLVL': '1', 03:31:37 INFO - 'TERM': 'linux', 03:31:37 INFO - 'TMOUT': '86400', 03:31:37 INFO - 'USER': 'cltbld', 03:31:37 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', 03:31:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:37 INFO - '_': '/tools/buildbot/bin/python'} 03:31:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:31:37 INFO - Running setup.py (path:/tmp/pip-j4fyRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:31:37 INFO - Running setup.py (path:/tmp/pip-EtNFbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:31:37 INFO - Running setup.py (path:/tmp/pip-LsVgZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:31:37 INFO - Running setup.py (path:/tmp/pip-jxhiLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:31:37 INFO - Running setup.py (path:/tmp/pip-MsWRnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:31:37 INFO - Running setup.py (path:/tmp/pip-qYodX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:31:37 INFO - Running setup.py (path:/tmp/pip-oyBz4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:31:37 INFO - Running setup.py (path:/tmp/pip-vnRgdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:31:37 INFO - Running setup.py (path:/tmp/pip-8QxUla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:31:37 INFO - Running setup.py (path:/tmp/pip-xFttde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:31:37 INFO - Running setup.py (path:/tmp/pip-5UmLCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:31:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:31:38 INFO - Running setup.py (path:/tmp/pip-EMf_kK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:31:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:31:38 INFO - Running setup.py (path:/tmp/pip-1jatAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:31:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:31:38 INFO - Running setup.py (path:/tmp/pip-zQoVhq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:31:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:38 INFO - Running setup.py (path:/tmp/pip-ghBQMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:31:38 INFO - Running setup.py (path:/tmp/pip-b_ts86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:31:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:31:38 INFO - Running setup.py (path:/tmp/pip-nxaAkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:31:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:31:38 INFO - Running setup.py install for manifestparser 03:31:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:31:38 INFO - Running setup.py install for mozcrash 03:31:38 INFO - Running setup.py install for mozdebug 03:31:38 INFO - Running setup.py install for mozdevice 03:31:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:31:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:31:38 INFO - Running setup.py install for mozfile 03:31:39 INFO - Running setup.py install for mozhttpd 03:31:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:31:39 INFO - Running setup.py install for mozinfo 03:31:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:31:39 INFO - Running setup.py install for mozInstall 03:31:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:31:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:31:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:31:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:31:39 INFO - Running setup.py install for mozleak 03:31:39 INFO - Running setup.py install for mozlog 03:31:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:31:39 INFO - Running setup.py install for moznetwork 03:31:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:31:39 INFO - Running setup.py install for mozprocess 03:31:39 INFO - Running setup.py install for mozprofile 03:31:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:31:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:31:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:31:39 INFO - Running setup.py install for mozrunner 03:31:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:31:39 INFO - Running setup.py install for mozscreenshot 03:31:40 INFO - Running setup.py install for moztest 03:31:40 INFO - Running setup.py install for mozversion 03:31:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:31:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:31:40 INFO - Cleaning up... 03:31:40 INFO - Return code: 0 03:31:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:31: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')]} 03:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31: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')]} 03:31:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:40 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 0x7fc8ae3e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aac020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3e2e0>, '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 0x1aaa140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa5d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', '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 03:31:40 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 03:31:40 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 03:31:40 INFO - Using env: {'DISPLAY': ':0', 03:31:40 INFO - 'HOME': '/home/cltbld', 03:31:40 INFO - 'LANG': 'en_US.UTF-8', 03:31:40 INFO - 'LANGUAGE': 'en_US:en', 03:31:40 INFO - 'LOGNAME': 'cltbld', 03:31:40 INFO - 'MAIL': '/var/mail/cltbld', 03:31:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:40 INFO - 'MOZ_NO_REMOTE': '1', 03:31:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:40 INFO - 'NO_EM_RESTART': '1', 03:31:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:40 INFO - 'PWD': '/builds/slave/test', 03:31:40 INFO - 'SHELL': '/bin/bash', 03:31:40 INFO - 'SHLVL': '1', 03:31:40 INFO - 'TERM': 'linux', 03:31:40 INFO - 'TMOUT': '86400', 03:31:40 INFO - 'USER': 'cltbld', 03:31:40 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', 03:31:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:40 INFO - '_': '/tools/buildbot/bin/python'} 03:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:31:40 INFO - Running setup.py (path:/tmp/pip-uvXG37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:31:40 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)) 03:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:31:40 INFO - Running setup.py (path:/tmp/pip-loqyUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:31:40 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)) 03:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:31:40 INFO - Running setup.py (path:/tmp/pip-Zz3AIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:31:40 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)) 03:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:31:40 INFO - Running setup.py (path:/tmp/pip-OMmugV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:31:40 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)) 03:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:31:40 INFO - Running setup.py (path:/tmp/pip-OhdqXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:31:40 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)) 03:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:31:40 INFO - Running setup.py (path:/tmp/pip-Cfkn5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:31:40 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)) 03:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:31:40 INFO - Running setup.py (path:/tmp/pip-KYcRpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:31:40 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)) 03:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:31:40 INFO - Running setup.py (path:/tmp/pip-QtqzRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:31:41 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)) 03:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:31:41 INFO - Running setup.py (path:/tmp/pip-pvbUGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:31:41 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)) 03:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:31:41 INFO - Running setup.py (path:/tmp/pip-CnXAr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:31:41 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)) 03:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:31:41 INFO - Running setup.py (path:/tmp/pip-REt22J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:31:41 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)) 03:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:31:41 INFO - Running setup.py (path:/tmp/pip-326GWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:31:41 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)) 03:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:31:41 INFO - Running setup.py (path:/tmp/pip-yUTO6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:31:41 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)) 03:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:31:41 INFO - Running setup.py (path:/tmp/pip-Jmi4TK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:31:41 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)) 03:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:41 INFO - Running setup.py (path:/tmp/pip-ngmrOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:41 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)) 03:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:31:41 INFO - Running setup.py (path:/tmp/pip-pS3Wo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:31:41 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)) 03:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:31:41 INFO - Running setup.py (path:/tmp/pip-MjVwVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:31:41 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)) 03:31:41 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)) 03:31:41 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)) 03:31:41 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)) 03:31:41 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)) 03:31:41 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)) 03:31:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:31:41 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 03:31:41 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 03:31:41 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 03:31:41 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 03:31:41 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 03:31:41 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 03:31:43 INFO - Downloading blessings-1.6.tar.gz 03:31:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:31:43 INFO - Installing collected packages: blessings 03:31:43 INFO - Running setup.py install for blessings 03:31:43 INFO - Successfully installed blessings 03:31:43 INFO - Cleaning up... 03:31:43 INFO - Return code: 0 03:31:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:31:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:31:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:31:44 INFO - Reading from file tmpfile_stdout 03:31:44 INFO - Current package versions: 03:31:44 INFO - argparse == 1.2.1 03:31:44 INFO - blessings == 1.6 03:31:44 INFO - blobuploader == 1.2.4 03:31:44 INFO - docopt == 0.6.1 03:31:44 INFO - functools32 == 3.2.3-2 03:31:44 INFO - jsonschema == 2.5.1 03:31:44 INFO - manifestparser == 1.1 03:31:44 INFO - mozInstall == 1.12 03:31:44 INFO - mozcrash == 0.17 03:31:44 INFO - mozdebug == 0.1 03:31:44 INFO - mozdevice == 0.48 03:31:44 INFO - mozfile == 1.2 03:31:44 INFO - mozhttpd == 0.7 03:31:44 INFO - mozinfo == 0.9 03:31:44 INFO - mozleak == 0.1 03:31:44 INFO - mozlog == 3.2 03:31:44 INFO - moznetwork == 0.27 03:31:44 INFO - mozprocess == 0.22 03:31:44 INFO - mozprofile == 0.28 03:31:44 INFO - mozrunner == 6.11 03:31:44 INFO - mozscreenshot == 0.1 03:31:44 INFO - mozsystemmonitor == 0.3 03:31:44 INFO - moztest == 0.7 03:31:44 INFO - mozversion == 1.4 03:31:44 INFO - psutil == 3.1.1 03:31:44 INFO - requests == 1.2.3 03:31:44 INFO - wsgiref == 0.1.2 03:31:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:31:44 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')]} 03:31:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:44 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')]} 03:31:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:44 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 0x7fc8ae3e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aac020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3e2e0>, '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 0x1aaa140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa5d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', '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 03:31:44 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 03:31:44 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 03:31:44 INFO - Using env: {'DISPLAY': ':0', 03:31:44 INFO - 'HOME': '/home/cltbld', 03:31:44 INFO - 'LANG': 'en_US.UTF-8', 03:31:44 INFO - 'LANGUAGE': 'en_US:en', 03:31:44 INFO - 'LOGNAME': 'cltbld', 03:31:44 INFO - 'MAIL': '/var/mail/cltbld', 03:31:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:44 INFO - 'MOZ_NO_REMOTE': '1', 03:31:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:44 INFO - 'NO_EM_RESTART': '1', 03:31:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:44 INFO - 'PWD': '/builds/slave/test', 03:31:44 INFO - 'SHELL': '/bin/bash', 03:31:44 INFO - 'SHLVL': '1', 03:31:44 INFO - 'TERM': 'linux', 03:31:44 INFO - 'TMOUT': '86400', 03:31:44 INFO - 'USER': 'cltbld', 03:31:44 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', 03:31:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:44 INFO - '_': '/tools/buildbot/bin/python'} 03:31:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:44 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)) 03:31:44 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)) 03:31:44 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)) 03:31:44 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)) 03:31:44 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)) 03:31:44 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)) 03:31:44 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)) 03:31:44 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)) 03:31:44 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)) 03:31:44 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)) 03:31:44 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)) 03:31:44 INFO - Cleaning up... 03:31:44 INFO - Return code: 0 03:31:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:31:44 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')]} 03:31:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:44 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')]} 03:31:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:44 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 0x7fc8ae3e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aac020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3e2e0>, '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 0x1aaa140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa5d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', '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 03:31:44 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 03:31:44 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 03:31:44 INFO - Using env: {'DISPLAY': ':0', 03:31:44 INFO - 'HOME': '/home/cltbld', 03:31:44 INFO - 'LANG': 'en_US.UTF-8', 03:31:44 INFO - 'LANGUAGE': 'en_US:en', 03:31:44 INFO - 'LOGNAME': 'cltbld', 03:31:44 INFO - 'MAIL': '/var/mail/cltbld', 03:31:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:44 INFO - 'MOZ_NO_REMOTE': '1', 03:31:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:44 INFO - 'NO_EM_RESTART': '1', 03:31:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:44 INFO - 'PWD': '/builds/slave/test', 03:31:44 INFO - 'SHELL': '/bin/bash', 03:31:44 INFO - 'SHLVL': '1', 03:31:44 INFO - 'TERM': 'linux', 03:31:44 INFO - 'TMOUT': '86400', 03:31:44 INFO - 'USER': 'cltbld', 03:31:44 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', 03:31:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:44 INFO - '_': '/tools/buildbot/bin/python'} 03:31:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:31:44 INFO - Cleaning up... 03:31:44 INFO - Return code: 0 03:31:44 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:31:44 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')]} 03:31:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:44 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')]} 03:31:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:44 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 0x7fc8ae3e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aac020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3e2e0>, '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 0x1aaa140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa5d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', '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 03:31:44 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 03:31:44 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 03:31:44 INFO - Using env: {'DISPLAY': ':0', 03:31:44 INFO - 'HOME': '/home/cltbld', 03:31:44 INFO - 'LANG': 'en_US.UTF-8', 03:31:44 INFO - 'LANGUAGE': 'en_US:en', 03:31:44 INFO - 'LOGNAME': 'cltbld', 03:31:44 INFO - 'MAIL': '/var/mail/cltbld', 03:31:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:44 INFO - 'MOZ_NO_REMOTE': '1', 03:31:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:44 INFO - 'NO_EM_RESTART': '1', 03:31:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:44 INFO - 'PWD': '/builds/slave/test', 03:31:44 INFO - 'SHELL': '/bin/bash', 03:31:44 INFO - 'SHLVL': '1', 03:31:44 INFO - 'TERM': 'linux', 03:31:44 INFO - 'TMOUT': '86400', 03:31:44 INFO - 'USER': 'cltbld', 03:31:44 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', 03:31:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:44 INFO - '_': '/tools/buildbot/bin/python'} 03:31:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:44 INFO - Downloading/unpacking flake8 03:31:44 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 03:31:44 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 03:31:44 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 03:31:44 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 03:31:44 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 03:31:44 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 03:31:46 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:31:46 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:31: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 03:31: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 03:31: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 03:31: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 03:31: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 03:31: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 03:31:46 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:31:46 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:31: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 03:31: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 03:31: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 03:31: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 03:31: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 03:31: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 03:31:47 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:31:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:31:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:31:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:31:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:31:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:31:47 INFO - no previously-included directories found matching 'docs/_build' 03:31:47 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:31:47 INFO - Running setup.py install for pep8 03:31:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:31:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:31:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:31:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:31:47 INFO - no previously-included directories found matching 'docs/_build' 03:31:47 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:31:47 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:31:47 INFO - Cleaning up... 03:31:47 INFO - Return code: 0 03:31:47 INFO - Running post-action listener: _resource_record_post_action 03:31:47 INFO - Running post-action listener: _start_resource_monitoring 03:31:47 INFO - Starting resource monitoring. 03:31:47 INFO - ##### 03:31:47 INFO - ##### Finished create-virtualenv step (success) 03:31:47 INFO - ##### 03:31:47 INFO - ##### 03:31:47 INFO - ##### Running install step. 03:31:47 INFO - ##### 03:31:47 INFO - Running pre-action listener: _resource_record_pre_action 03:31:47 INFO - Running main action method: install 03:31:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:31:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:31:47 INFO - Reading from file tmpfile_stdout 03:31:47 INFO - Detecting whether we're running mozinstall >=1.0... 03:31:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:31:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:31:48 INFO - Reading from file tmpfile_stdout 03:31:48 INFO - Output received: 03:31:48 INFO - Usage: mozinstall [options] installer 03:31:48 INFO - Options: 03:31:48 INFO - -h, --help show this help message and exit 03:31:48 INFO - -d DEST, --destination=DEST 03:31:48 INFO - Directory to install application into. [default: 03:31:48 INFO - "/builds/slave/test"] 03:31:48 INFO - --app=APP Application being installed. [default: firefox] 03:31:48 INFO - mkdir: /builds/slave/test/build/application 03:31:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:31:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:32:03 INFO - Reading from file tmpfile_stdout 03:32:03 INFO - Output received: 03:32:03 INFO - /builds/slave/test/build/application/firefox/firefox 03:32:03 INFO - Running post-action listener: _resource_record_post_action 03:32:03 INFO - ##### 03:32:03 INFO - ##### Finished install step (success) 03:32:03 INFO - ##### 03:32:03 INFO - ##### 03:32:03 INFO - ##### Running run-tests step. 03:32:03 INFO - ##### 03:32:03 INFO - Running pre-action listener: _resource_record_pre_action 03:32:03 INFO - Running main action method: run_tests 03:32:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:32:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:32:03 INFO - Python 2.7.3 03:32:03 INFO - Return code: 0 03:32:03 INFO - grabbing minidump binary from tooltool 03:32:03 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')]} 03:32:03 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 0x1b3e2e0>, '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 0x1aaa140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa5d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:32:03 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 03:32:03 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 03:32:03 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 03:32:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:32:03 INFO - Return code: 0 03:32:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:32:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:32:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:32:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:32:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:32:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:32:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:32:04 INFO - Return code: 0 03:32:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:32:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-057 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:32:04 INFO - Using env: {'DISPLAY': ':0', 03:32:04 INFO - 'HOME': '/home/cltbld', 03:32:04 INFO - 'LANG': 'en_US.UTF-8', 03:32:04 INFO - 'LANGUAGE': 'en_US:en', 03:32:04 INFO - 'LOGNAME': 'cltbld', 03:32:04 INFO - 'MAIL': '/var/mail/cltbld', 03:32:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:32:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:04 INFO - 'MOZ_NO_REMOTE': '1', 03:32:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:32:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:04 INFO - 'NO_EM_RESTART': '1', 03:32:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:04 INFO - 'PWD': '/builds/slave/test', 03:32:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:32:04 INFO - 'SHELL': '/bin/bash', 03:32:04 INFO - 'SHLVL': '1', 03:32:04 INFO - 'TERM': 'linux', 03:32:04 INFO - 'TMOUT': '86400', 03:32:04 INFO - 'USER': 'cltbld', 03:32:04 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340', 03:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:04 INFO - '_': '/tools/buildbot/bin/python'} 03:32:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:32:05 INFO - mozversion application_buildid: 20160708004052 03:32:05 INFO - mozversion application_changeset: 00e9101b9ed0353afd4e7dd956a063d7ac511c91 03:32:05 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:32:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:32:05 INFO - mozversion application_name: Firefox 03:32:05 INFO - mozversion application_remotingname: firefox-dev 03:32:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:32:05 INFO - mozversion application_vendor: Mozilla 03:32:05 INFO - mozversion application_version: 49.0a2 03:32:05 INFO - mozversion platform_buildid: 20160708004052 03:32:05 INFO - mozversion platform_changeset: 00e9101b9ed0353afd4e7dd956a063d7ac511c91 03:32:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:32:05 INFO - mozversion platform_version: 49.0a2 03:32:05 INFO - using testdate: 1467973925 03:32:05 INFO - actual date: 1467973925 03:32:05 INFO - starting webserver on 'localhost:52015' 03:32:05 INFO - SUITE-START | Running 2 tests 03:32:05 INFO - TEST-START | dromaeo_css 03:32:05 INFO - operating with platform_type : linux_ 03:32:05 INFO - Initialising browser for dromaeo_css test... 03:32:05 INFO - TEST-INFO | started process 2956 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqRvxk8/profile http://localhost:52015/getInfo.html) 03:32:09 INFO - PROCESS | 2956 | __metrics Screen width/height:1600/1200 03:32:09 INFO - PROCESS | 2956 | colorDepth:24 03:32:09 INFO - PROCESS | 2956 | Browser inner width/height: 1024/697 03:32:09 INFO - PROCESS | 2956 | __metrics 03:32:10 ERROR - PROCESS | 2956 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:32:11 INFO - TEST-INFO | 2956: exit 0 03:32:11 INFO - Browser initialized. 03:32:11 INFO - Running cycle 1/1 for dromaeo_css test... 03:32:11 INFO - TEST-INFO | started process 3050 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqRvxk8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:32:23 INFO - PROCESS | 3050 | RSS: Main: 145760256 03:32:23 INFO - PROCESS | 3050 | 03:33:14 INFO - PROCESS | 3050 | 1467973994397 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] 03:35:22 INFO - PROCESS | 3050 | Cycle 1(1): loaded http://localhost:52015/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52015/tests/dromaeo/cssquery-ext.html) 03:35:23 INFO - PROCESS | 3050 | RSS: Main: 253964288 03:35:23 INFO - PROCESS | 3050 | 03:38:25 INFO - PROCESS | 3050 | Cycle 1(1): loaded http://localhost:52015/tests/dromaeo/cssquery-ext.html (next: http://localhost:52015/tests/dromaeo/cssquery-jquery.html) 03:38:26 INFO - PROCESS | 3050 | RSS: Main: 235487232 03:38:26 INFO - PROCESS | 3050 | 03:41:28 INFO - PROCESS | 3050 | Cycle 1(1): loaded http://localhost:52015/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52015/tests/dromaeo/cssquery-mootools.html) 03:41:28 INFO - PROCESS | 3050 | RSS: Main: 237416448 03:41:28 INFO - PROCESS | 3050 | 03:44:30 INFO - PROCESS | 3050 | Cycle 1(1): loaded http://localhost:52015/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52015/tests/dromaeo/cssquery-prototype.html) 03:44:31 INFO - PROCESS | 3050 | RSS: Main: 219688960 03:44:31 INFO - PROCESS | 3050 | 03:47:33 INFO - PROCESS | 3050 | Cycle 1(1): loaded http://localhost:52015/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52015/tests/dromaeo/cssquery-yui.html) 03:47:34 INFO - PROCESS | 3050 | RSS: Main: 202842112 03:47:34 INFO - PROCESS | 3050 | 03:50:36 INFO - PROCESS | 3050 | Cycle 1(1): loaded http://localhost:52015/tests/dromaeo/cssquery-yui.html (next: http://localhost:52015/tests/dromaeo/cssquery-dojo.html) 03:50:37 INFO - PROCESS | 3050 | RSS: Main: 257675264 03:50:37 INFO - PROCESS | 3050 | 03:50:37 INFO - PROCESS | 3050 | __start_tp_report 03:50:37 INFO - PROCESS | 3050 | _x_x_mozilla_page_load 03:50:37 INFO - PROCESS | 3050 | _x_x_mozilla_page_load_details 03:50:37 INFO - PROCESS | 3050 | |i|pagename|runs| 03:50:37 INFO - PROCESS | 3050 | |0;dojo.html;3283.9835800821;3306.570145881035;3314.453115235987;3324;3363.2768954674757;5388.811391601294;5422.213779002042;5469.835904922848;5490.69801160936;5510.531604813596;5457.045017121997;5538.529225015868;5539.362973258077;5549.944500555003;5565.0817615093565;4938.555530002299;5041.017001684682;5056.646034777567;5094.77073531692;5137.820176293831;4920.3111564381015;5058.671186372892;5091.923621145667;5094.796208151688;5095.949040509603;5018.924716129242;5058.494150584949;5086.033653605803;5086.593072554206;5086.847394578169;5157.8452646420665;5158.277841102249;5170.146925757245;5188.610854185913;5213.1919552469235;4861.1006963711825;4928.704277743347;5007.749612519359;5043.773030213649;5058.342415486;5342.786288548454;5364.195370694388;5385.111456609655;5413.729313534308;5414.620976531644;5905.616134951258;5941.019731744257;5947.970260148715;5949;5950.345461999176;5839.8540036499;5907.940920590782;5910.34986151523;5962.642241465526;5973.790917317917;5498.725063746837;5528.419515950848;5544.085225937756;5544.279243698294;5548.361938377098;5928.199693041471;6055.183589070685;6057.848553786191;6067.059605761114;6070.878582428327;5735.082386818089;5769.826905192851;5786.94213057864;5875.9118613220835;5879.147523609138;5918.822435326861;5922.703864806743;5922.970385148046;5948.226730525091;5953.672548009901;5710.600257981984;5788.53691704671;5885.440883116097;5895.20414744004;5897.410258974069;5830.970845145747;5886.087656413263;5891.293044834561;5892.263467066617;5934.050551911673;4591.035882464653;4593.678442508993;4602.88492787683;4605.884852878705;4626.930596041062;4034.8991275218355;4038.071243613985;4078.2455245779624;4116.958830411718;4162.666986641061;5569.331680198402;5590.8043218487155;5596.356419011784;5598.964191624563;5620.831375058755;5925.466707996301;5935.7625694972585;5937.435943585352;5940.811837632491;5972.880542389128;5421.2139239810795;5500.779968801283;5540.639858409271;5554.1391084381985;5575.52608028314;5088.618353623493;5098.337216161875;5105.668131571436;5111.156659151198;5113.386393632788;6037.818865434044;6040.969795151084;6045.183900173508;6060.696965151725;6078.878422431527;5681.9147712783515;5772.797952071657;5808.6224395414165;5847.678377689268;5899.056151015817;6021.458068773832;6027.849303767441;6031.60794548353;6037.45662890342;6039.909401359071;5636.267285252891;5797.8550536236935;5803;5807.332156802022;5808.186853840381;5799.8550036249435;5818.185454036354;5838.715482593822;5883.823235352861;5888.3228428731245;5727.570432217517;5770.336411312753;5773.653580785166;5774.595778295479;5775.075987842093;5448.891022179455;5588.86027849307;5723.112917497794;5725.885482290248;5730.111832665943;5537.1140617501005;5615.2700148982;5623.8875222496135;5627.324721033502;5660.9150862737915;5800;5815.854603634943;5833.620814647034;5837;5842.7078646066675;5532.059549876451;5543.473370029841;5610.046292130266;5673.886522269614;5699.344575373885 03:50:37 INFO - PROCESS | 3050 | |1;ext.html;600.9909851352228;639.0765344077807;639.6641763074385;656.7865443730789;658.3219284137339;32513.02460926173;35754.642453575456;35759.10602234946;35761;45235;48776.04895804173;49396.50603493964;49599.752001240035;49617.50382496174;49632.2555161672;9054.637814487412;9265.907340926571;9269.443833369985;9270.07299270071;9273.304502162331;8016.318612917909;9086.182243598076;10815.513301901434;11053.060489858372;11058.502367393487;11174.1060715143;11227.32636041836;11230.382328971904;11234.32594044356;11242.213045086806;8342.708005219849;10984.890151098467;11059.059979901718;11066.170037247239;11076.833847492335;10608.575656973711;10970;11001.339919604769;11002.614908478165;11358.94320528392;41083.178336433404;41558.584414156074;46163.76918115422;46182.30726539105;46200.3069953951;3887.941680874789;3997.480327557428;4002.1595464952393;4065.125997910434;4453.554644535553;34457.13857153566;35551.11122221939;35696.46455303173;35829.10427239314;35862.820685896666;4692.577668009895;4743.976280118612;4751.983292339507;4764.118638051971;4920.040592084545;382887;388507;388851;389112.05443972885;389833;71573;72437.63781181061;72522.63738681379;72593.6370318145;72637;41319.38020929688;42362;42372.15255694869;44573.10853782906;44651.77674111609;18024.549386265186;18032;18055.097245137953;18072.09639518019;18072.548186295448;11030.172737044753;15503.14732689713;15647.68704625901;15781.684366312838;15787.68424631501;22470.550588988128;22687;22810.17518598508;22873.05579025768;22920.885395572914;7890.250426209501;7949.801254968672;8280.58597070144;8282.751517454486;8320.791980200422;1052.4632437456953;1059.7933402986498;1094.4527736131934;1171.5255321594598;1181.6868529839599;33786;33895.49156762651;33955.15112122167;34382.312353752786;34403.139921501665;1177.2053863642006;1180.1797750563449;1211.666791632308;1216.793145165324;1251.455616806692;17690.823091769176;18094;18128.818711812975;18342.358017469593;18345.449636510926;655935;657731;658640;658871;660665;66290.66854665792;67641;67664;67808.32191678119;67833.66083169651;1344.6974430753003;1463.875570576513;1494.5740463967934;1514;1528.4268399350244;34999.30001400009;36940.26119477542;37023;37320;37433.62566374301;56118.43881561132;56908.71545642411;56935.43064569301;57044;57058.7147064262;444291;445559;447316;448402;449439;24317.513649726552;30713.53929691101;31919.36161276808;32032.51951220687;33796.831015844764;52938;53102.73448632887;53338.19992700035;53857.46142538681;54994.17508737292;43896.34155487734;44117;44655;45271.320930185415;46358.30462542997;43756.423176316355;44403.55596443994;44423;44544.77727611341;45331.773341133085;44809;45582;45906;45929.31106033479;46211.53788462207;37600;38383.42424863573;38638.03404914787;39716.404253936795;39839.40240896331;29528.557071644373;30079.699203008564;30345.52774722656;30548.541771872995;30684.693153069078 03:50:37 INFO - PROCESS | 3050 | |2;jquery.html;423.690705784777;424.25331416706587;426.4030357499501;429.158848656633;437.4772147283997;18588.256469741213;19550.608987820237;20306.289279875207;20562.588748225025;20623.175072997063;19451.610967780634;19621.705674414894;19728.506787330323;20565.28021519247;20745.792542074574;5765.481106700396;5915.497182739472;5958.523318134538;5967.880642387149;5969.164316995624;4168.995528585124;5125.948740512603;5872.236609240793;5933.406659334075;6580.967095164518;6612.966935165317;6627.204735431755;6629.73481060757;6655;6656.467482601404;5511.751971161288;5793.681347525884;5806.970965145189;5840.561957853178;6619.040239165318;3909.960900390988;5474.37044739856;5708.971455142698;5739.282589676291;5757.222774925415;14140.434382624682;14478.638034049127;14622.341994610268;14660.780088298685;14754;2445.1319781477673;2447.632855071736;2450.963235551468;2474.208253358926;2511.6860392450944;10082.848757268646;10494;10513.9474302628;10535.78928421435;10885.727856803564;1956.0024387562303;2038.9796102038938;2110.2614085070254;2266.2747920665392;2284.2690339091496;73537;81215.5939220306;83277;87312.1268787304;87874;11258.380789056517;11556;11747.236429632047;11799.646010619696;11871.525138994517;16408;16487.422940197037;16578.088205148833;16583.005019698856;16628.085455300075;8850.424722393023;8907.797447344525;9051.230645395215;9137.862932056025;9162.22121119699;8912.108789121166;8967.865482017774;8999.100089990949;9036.954815226014;9173.220276276592;5009.574186194214;5023.472535383732;5046.369203849519;5085.7711402986215;5134.562322549692;2045.6985890872768;2046.4576887124924;2055.3525639423533;2073.5438203595186;2111.74659040913;958.3914214473859;982.3418309732497;1050.658535975811;1050.9579616815395;1089.330062011857;19693.40919772409;20225.089870955842;20430.69353959692;20519.8974005132;20575;1022.728976821143;1033.684726158529;1051.059301924773;1075.6450371377427;1083.2038451737967;10579.36523808574;11201.159913006557;11348.375839328915;11367;11407.94296028531;531022;537128;538117;542046;542459;77634.61182694163;103074;103571.48214258881;104005;104536.47731761292;1073.3506228731505;1083.0793825248477;1088.9019988200941;1089.8637670291214;1096.0190629386655;17419.387091935805;17654.646907062044;17665.646687065928;18028.36900708469;18086.638267234845;30997.845010774803;35183.64816351804;40222;43359.56640433555;45872.54127458682;60078;61541.69229153825;61670.69164654148;62002.37997619966;62099.37900620936;8931.19619234272;9105.635774568944;9148.313876459159;9276.489793061446;9280;13600.387982541008;14042.648933776738;14083;14190.077644953046;14280.785788212977;12476.376181191086;12554.309512976508;12557.434915428645;12591.11862169676;12722.554710585086;12267.57063502773;12317.44571494267;12356.320402377956;12497;12588.181768185405;12376.133670642968;12539.310337931136;12673.049521285757;12687.492500299884;12713.364331783188;9137.269018478373;9221.769455763659;9222.723318300461;9280.071992800937;9311.72064838056;11924;11952.701182470508;12353.567625133077;12546.12177147591;12674.239545627292 03:50:37 INFO - PROCESS | 3050 | |3;mootools.html;1378.531299358218;1560.438242232796;1604.7111519926411;1810.9728354074691;1910.4173227165718;8617.095205003478;8919.64321427143;8921.50931698757;9217.400868943514;9229.90770092299;8960.91039089609;9123.042080581543;9157.862632060507;9161.679341223058;9172.862407063883;2433.07543133609;2452.7792498675117;2456.742042085582;2457;2461.692288463942;2387.868667223302;2478.1945867593013;2479.665245191894;2480.503899220163;2480.702315722116;2413.613821788514;2420.9757902421015;2423;2424.745401732819;2428.550718117154;2340.2394221877958;2375.8812059397046;2378.9286321410386;2391.689080419543;2394.305651361112;2324.8721320327377;2372.181597348912;2385.630227314769;2386.4869053153657;2388.4148383645943;7929.484583502054;9017.864732029026;9096.272298216127;9147.085291470867;9152.771180720469;5487.094629386146;5720.427957204288;5722.427757224286;5743.253377060997;5745.741441635137;3582.1223800168864;3624.981875090634;3649.835757390924;3651.1054791575966;3730.9067273318115;1255.7927941889577;1300.4538094000543;1321.4648067532664;1337.9197248165044;1351.9797203041962;13689.794653080211;13748.518801841887;13837.238951857644;13849.515266965709;13869.029167958248;4895.363602731622;4986.650934434628;5070.645054846199;5083.822066227738;5099.031184075014;11837.467313970892;11924.582639607572;11951.342676152895;11953.701157471132;11962.521499140112;5850.414958504116;5856.092305692625;5857.121431785266;5859.94140058594;5860.2381690380835;5096.872578185575;5213.895722085537;5219.739013049332;5274.657147285414;5356.3572371315695;4167.91664166715;4171.165766846643;4224.429701990192;4245.426867372927;4249.745015299092;1245.9688507787196;1271.879171478708;1272.1285919145414;1274.5156840400589;1285.6528737240892;887.2946007923587;910.1762904571374;993.8906720260766;1020.6121673764071;1021.6117875207523;6439.420452159235;6530.771423000172;6533.477321814244;6540.444062254762;6665.366790154928;458.63309352517854;472.41892472259315;479.46300143839125;481.85062630584633;483.6396884321203;4196.160767846443;4369.104333611602;4386.670999675037;4448.466184057869;4459.531749166291;12008.579699710468;12357.011439084852;12484.438200281009;12484.438200281009;12486.188397754117;12277.570285039981;12290.078244131726;12382.938085309515;12399;12415.93792031034;8371.162883711702;8733.170348816851;8798.824023519493;8906.109389061043;8910.86633700495;6170.753169873155;6237.906431403442;6466.967665161644;6674.265830758515;6702.932970670426;11052.889471105185;11162.553497859995;11251.606193783178;11405.087592992706;11502.367369794742;11289;11502.88497115018;11588.47851846652;11662.533498660297;11666.88333116658;8617.439866408664;8684.39209255346;8780.165884240987;8804;8811.471311721061;9175.724728258163;9425.952870235606;9487.905120948702;9500.857487137837;9542.379745316532;7762.3790096793255;8073.19268073188;8121.837563248584;8143.063547692092;8642.56787160652;8392.83214335722;8440.240378365976;8445.07104218523;8482.618282177193;8613.751006104205;7929.365650748042;8007.199280071946;8030.196980301923;8035.397345199017;8039.758807235792;5315.681059136464;5356.678599284055;5409.918851217313;5508.862278443071;5576.665400076008;4694.201985662377;5559.054960656779;5684.8010319638615;5695.145728140812;5816.680082595498 03:50:37 INFO - PROCESS | 3050 | |4;prototype.html;336.971357434618;339.8657530275541;351.12394575534046;352.2848617306867;352.8941317604718;5403.432639572848;5429.375621803494;5446.210299506574;5460.372057213412;5467.179923011546;5324.174752913295;5363.490468405495;5370.677759334442;5381.461853814617;5393.6763794172275;3993.520777506689;4264.616184543391;4279.801655536439;4283.828646854122;4287.1425714857;3957.76253424794;4221.556736542665;4246.087091275375;4254.298040823276;4257.978085259547;4198.3702444633245;4202.390653355262;4223.176480586287;4232.962924083604;4249.851255206068;4021.698372622065;4074.5314288856866;4231.894202644928;4232.195882782277;4233.618974292329;3732.3500314776543;3749.887503374903;3761.799347381785;3776.3391406504024;3780.3573392523335;5117.48825117489;5134.666246693991;5143.537081662668;5160.329157209576;5233.869153271161;4771.5467030632035;4838.129136755382;4844.878878028042;4849.3453383793085;4850.223964165716;5635.126555383922;5657.500762297995;5668.404817494145;5688.97155514224;5704.4865962063195;3926.4110383442426;4021.939670904939;4044.534878488982;4123.9381409278885;4135.6898232632375;5815.476607105338;5897.115432685132;5898.174255604219;5898.321693005317;5899.380565040695;4244.257254980366;4246.108317253412;4255.97872010638;4268.253055715238;4275.315949448073;4184.916301673949;4306.289462238696;4306.698531102856;4344.739315641008;4352.847650332224;4812;4818.204996175662;4822.373091498082;4840.572031250789;4879.146149423907;5602.887942241214;5603;5611.242482264842;5625.493705566437;5673.404292549306;1440.3086518470982;1482.9110253384615;1485.3984136424765;1487.196913666608;1501.8948673592743;961.500059906548;964.5563041001218;975.1905918088112;975.5171190260725;988.3773222869546;4840.903181936412;4895.290182923456;4907.730074845917;4909.582685471703;4957.628177886673;5092.974535127375;5098.796048158107;5140.383154021467;5263.973680131575;5274.892272622792;4555.681102322806;4861.440934292531;4920.876978075506;5025.321581586512;5042.268871013683;2753.5456649652583;2767.533207400081;2771;2777.722227777206;2782.539166937374;5171.905378321786;5195.818146364934;5296.814611488579;5300.031982090042;5320.255164277004;4868.537488938545;5167.622893468972;5217.7912883485;5252.264682944391;5260.842174734764;5239.397469291004;5271.367435907716;5281.55106815925;5314.4951229633125;5325.174597937326;5135.8686145250795;5149.227615857501;5490.258815060076;5689.658620482619;5835.737391817293;5730.111832665943;5734.913976290276;5742.483176514133;5745.540356771532;5748.310202775694;5758.942410575841;5911.674857882857;5916.7041647918295;5937.851553711192;5963.50912271932;4245.299525578245;4257.382679511516;4260.254455470218;4268.125034367885;4299.495176688134;5806.65160090396;5819.563532734977;5826.630741775594;5854.594897224721;5876.236089308362;5470.562355011669;5710.172025056427;5747;5753.309602847685;5772.163036359789;5603.467670571289;5604.215409842707;5651.6043876928225;5652.519535839499;5653.7950199423085;5511.255980442589;5564.860878478071;5602.243697101002;5614.578906581941;5614.747336369792;5508.650380656764;5675.120356344773;5734.5125664319;5745.1956726059225;5750.568707346882;5542.362628297636;5583.637063590853;5597.236870385816;5606.411326810626;5614.2701448811395 03:50:37 INFO - PROCESS | 3050 | |5;yui.html;795.6618437164203;819.9180081991801;820.1511435664087;841.5329492131868;842.5744998775625;15799.447019354326;15820;15836.208189590503;16056.357745690173;17180.055096969663;15737.685246295096;15781.60545986351;15813.60465988351;15878.285477153528;15914.124723140223;719.7336985315427;848.0374774630777;869.6217145541709;872.3631748823362;874.6982291109557;847.817719190374;847.8346722389136;851.0213254757017;851.4550687559966;852.6930305090162;750.4184257200686;918.9494577798218;919.5953780336664;920.7836158502746;921.8110287391103;892.9107089291051;912.4160537256129;913.3150137396952;913.9908600914039;916.1662886773071;669.7488441834312;670.1656437735003;670.4401824476568;670.5004771445256;670.9765158219438;14911.478098266618;14940;14949.327280272306;14956.326965286587;15006.774898376532;14305;14396;14511.492097776527;14781.852181478154;15053.172075535842;12694.11141220115;12800.16798908068;12809.679758006032;12942.482300708054;13044.347782610832;4538.546145385468;4552.226121559343;4581.633469322446;4626.282926146419;4739.502352252998;78419.6079019607;79157;79349;79353;80383.5980820098;11271.38007409584;11344.546218151347;11753.118516111326;12049.15655904096;12392.380380980914;21888.452788680093;22323.44191395228;22329.776702232768;22340;22368.776312236994;9088.681896133603;9165.495897725556;9165.725028249164;9197.448153110834;9237.214836738818;5069.214271787879;5206.479352064839;5231.8168864089575;5258.079836028756;5338.599605029561;3538.9469157962653;3598.982005089958;3615.8011309378235;3633.0190848471293;3636.4181730922924;562.6567793645872;566.6798258983661;635.9586626869238;688.9621070841051;698.1133959870936;735.5108852612952;758.6320634492267;781.1095351299523;786.1823703348567;799.8280369720538;9902.257330700082;10311.432871192006;10448.32085914413;10479.318844275098;10519.789604208027;699.6991293743739;706.0292098364748;755.2593106524249;757.814335487809;766.3639179481017;4687.929681054787;4721.787519561559;4724.503927087675;4747.976260118678;4761.619070474354;123966.80165991664;124075;125827.37086314693;127456;130010.34994824966;112741;114367.84482327718;115203;115457.42271288758;116022.4198879017;64902;65138.67430662911;76321.61839190879;97369.02630973741;100133;9319.720408387759;9343.112404321579;9464.363044484553;9483.24134069259;9618.470984095942;46515.53484465112;47403.52596474129;48115.518844812505;57966.42033579779;58159;73167;73508.26491735014;74473.25526744663;75949.20253987389;76928;12758.234505929673;13735.656608584864;13979.790303145257;14053.789193162314;14066.929665351608;13086.541971030969;14008.299585020504;14097;14221.502247421291;14384.568462946128;9695.854562181714;13648.385822637807;13678.110922789987;13754.381052852446;14473.78289325682;13009.804852927024;13156.671083222613;13293.80059299092;13562.118462299919;13572.93213533926;13658.248796316298;13813.723725525635;13863.168209907439;14079.436822527394;14294.499692510712;11235.213535052468;11285.322880627189;11537.653870383901;11652.650420487398;11888.583899563473;11785.175037747618;11894.643160705193;12043;12151.75696486083;12188.329641869783 03:50:37 INFO - PROCESS | 3050 | __end_tp_report 03:50:37 INFO - PROCESS | 3050 | __start_cc_report 03:50:37 INFO - PROCESS | 3050 | _x_x_mozilla_cycle_collect,920 03:50:37 INFO - PROCESS | 3050 | __end_cc_report 03:50:37 INFO - PROCESS | 3050 | __startTimestamp1467975037113__endTimestamp 03:50:37 INFO - PROCESS | 3050 | 03:50:37 INFO - PROCESS | 3050 | ------- Summary: start ------- 03:50:37 INFO - PROCESS | 3050 | Number of tests: 6 03:50:37 INFO - PROCESS | 3050 | 03:50:37 INFO - PROCESS | 3050 | [#0] dojo.html Cycles:165 Average:5476.42 Median:5623.89 stddev:585.68 (10.4%) stddev-sans-first:561.66 03:50:37 INFO - PROCESS | 3050 | Values: 3284.0 3306.6 3314.5 3324.0 3363.3 5388.8 5422.2 5469.8 5490.7 5510.5 5457.0 5538.5 5539.4 5549.9 5565.1 4938.6 5041.0 5056.6 5094.8 5137.8 4920.3 5058.7 5091.9 5094.8 5095.9 5018.9 5058.5 5086.0 5086.6 5086.8 5157.8 5158.3 5170.1 5188.6 5213.2 4861.1 4928.7 5007.7 5043.8 5058.3 5342.8 5364.2 5385.1 5413.7 5414.6 5905.6 5941.0 5948.0 5949.0 5950.3 5839.9 5907.9 5910.3 5962.6 5973.8 5498.7 5528.4 5544.1 5544.3 5548.4 5928.2 6055.2 6057.8 6067.1 6070.9 5735.1 5769.8 5786.9 5875.9 5879.1 5918.8 5922.7 5923.0 5948.2 5953.7 5710.6 5788.5 5885.4 5895.2 5897.4 5831.0 5886.1 5891.3 5892.3 5934.1 4591.0 4593.7 4602.9 4605.9 4626.9 4034.9 4038.1 4078.2 4117.0 4162.7 5569.3 5590.8 5596.4 5599.0 5620.8 5925.5 5935.8 5937.4 5940.8 5972.9 5421.2 5500.8 5540.6 5554.1 5575.5 5088.6 5098.3 5105.7 5111.2 5113.4 6037.8 6041.0 6045.2 6060.7 6078.9 5681.9 5772.8 5808.6 5847.7 5899.1 6021.5 6027.8 6031.6 6037.5 6039.9 5636.3 5797.9 5803.0 5807.3 5808.2 5799.9 5818.2 5838.7 5883.8 5888.3 5727.6 5770.3 5773.7 5774.6 5775.1 5448.9 5588.9 5723.1 5725.9 5730.1 5537.1 5615.3 5623.9 5627.3 5660.9 5800.0 5815.9 5833.6 5837.0 5842.7 5532.1 5543.5 5610.0 5673.9 5699.3 03:50:37 INFO - PROCESS | 3050 | 03:50:37 INFO - PROCESS | 3050 | [#1] ext.html Cycles:180 Average:66810.97 Median:39777.90 stddev:135963.70 (341.8%) stddev-sans-first:136254.23 03:50:37 INFO - PROCESS | 3050 | Values: 601.0 639.1 639.7 656.8 658.3 32513.0 35754.6 35759.1 35761.0 45235.0 48776.0 49396.5 49599.8 49617.5 49632.3 9054.6 9265.9 9269.4 9270.1 9273.3 8016.3 9086.2 10815.5 11053.1 11058.5 11174.1 11227.3 11230.4 11234.3 11242.2 8342.7 10984.9 11059.1 11066.2 11076.8 10608.6 10970.0 11001.3 11002.6 11358.9 41083.2 41558.6 46163.8 46182.3 46200.3 3887.9 3997.5 4002.2 4065.1 4453.6 34457.1 35551.1 35696.5 35829.1 35862.8 4692.6 4744.0 4752.0 4764.1 4920.0 382887.0 388507.0 388851.0 389112.1 389833.0 71573.0 72437.6 72522.6 72593.6 72637.0 41319.4 42362.0 42372.2 44573.1 44651.8 18024.5 18032.0 18055.1 18072.1 18072.5 11030.2 15503.1 15647.7 15781.7 15787.7 22470.6 22687.0 22810.2 22873.1 22920.9 7890.3 7949.8 8280.6 8282.8 8320.8 1052.5 1059.8 1094.5 1171.5 1181.7 33786.0 33895.5 33955.2 34382.3 34403.1 1177.2 1180.2 1211.7 1216.8 1251.5 17690.8 18094.0 18128.8 18342.4 18345.4 655935.0 657731.0 658640.0 658871.0 660665.0 66290.7 67641.0 67664.0 67808.3 67833.7 1344.7 1463.9 1494.6 1514.0 1528.4 34999.3 36940.3 37023.0 37320.0 37433.6 56118.4 56908.7 56935.4 57044.0 57058.7 444291.0 445559.0 447316.0 448402.0 449439.0 24317.5 30713.5 31919.4 32032.5 33796.8 52938.0 53102.7 53338.2 53857.5 54994.2 43896.3 44117.0 44655.0 45271.3 46358.3 43756.4 44403.6 44423.0 44544.8 45331.8 44809.0 45582.0 45906.0 45929.3 46211.5 37600.0 38383.4 38638.0 39716.4 39839.4 29528.6 30079.7 30345.5 30548.5 30684.7 03:50:37 INFO - PROCESS | 3050 | 03:50:37 INFO - PROCESS | 3050 | [#2] jquery.html Cycles:180 Average:30759.66 Median:11348.36 stddev:88648.91 (781.2%) stddev-sans-first:88868.33 03:50:37 INFO - PROCESS | 3050 | Values: 423.7 424.3 426.4 429.2 437.5 18588.3 19550.6 20306.3 20562.6 20623.2 19451.6 19621.7 19728.5 20565.3 20745.8 5765.5 5915.5 5958.5 5967.9 5969.2 4169.0 5125.9 5872.2 5933.4 6581.0 6613.0 6627.2 6629.7 6655.0 6656.5 5511.8 5793.7 5807.0 5840.6 6619.0 3910.0 5474.4 5709.0 5739.3 5757.2 14140.4 14478.6 14622.3 14660.8 14754.0 2445.1 2447.6 2451.0 2474.2 2511.7 10082.8 10494.0 10513.9 10535.8 10885.7 1956.0 2039.0 2110.3 2266.3 2284.3 73537.0 81215.6 83277.0 87312.1 87874.0 11258.4 11556.0 11747.2 11799.6 11871.5 16408.0 16487.4 16578.1 16583.0 16628.1 8850.4 8907.8 9051.2 9137.9 9162.2 8912.1 8967.9 8999.1 9037.0 9173.2 5009.6 5023.5 5046.4 5085.8 5134.6 2045.7 2046.5 2055.4 2073.5 2111.7 958.4 982.3 1050.7 1051.0 1089.3 19693.4 20225.1 20430.7 20519.9 20575.0 1022.7 1033.7 1051.1 1075.6 1083.2 10579.4 11201.2 11348.4 11367.0 11407.9 531022.0 537128.0 538117.0 542046.0 542459.0 77634.6 103074.0 103571.5 104005.0 104536.5 1073.4 1083.1 1088.9 1089.9 1096.0 17419.4 17654.6 17665.6 18028.4 18086.6 30997.8 35183.6 40222.0 43359.6 45872.5 60078.0 61541.7 61670.7 62002.4 62099.4 8931.2 9105.6 9148.3 9276.5 9280.0 13600.4 14042.6 14083.0 14190.1 14280.8 12476.4 12554.3 12557.4 12591.1 12722.6 12267.6 12317.4 12356.3 12497.0 12588.2 12376.1 12539.3 12673.0 12687.5 12713.4 9137.3 9221.8 9222.7 9280.1 9311.7 11924.0 11952.7 12353.6 12546.1 12674.2 03:50:37 INFO - PROCESS | 3050 | 03:50:37 INFO - PROCESS | 3050 | [#3] mootools.html Cycles:180 Average:6318.76 Median:480.66 stddev:3730.78 (776.2%) stddev-sans-first:3722.78 03:50:37 INFO - PROCESS | 3050 | Values: 1378.5 1560.4 1604.7 1811.0 1910.4 8617.1 8919.6 8921.5 9217.4 9229.9 8960.9 9123.0 9157.9 9161.7 9172.9 2433.1 2452.8 2456.7 2457.0 2461.7 2387.9 2478.2 2479.7 2480.5 2480.7 2413.6 2421.0 2423.0 2424.7 2428.6 2340.2 2375.9 2378.9 2391.7 2394.3 2324.9 2372.2 2385.6 2386.5 2388.4 7929.5 9017.9 9096.3 9147.1 9152.8 5487.1 5720.4 5722.4 5743.3 5745.7 3582.1 3625.0 3649.8 3651.1 3730.9 1255.8 1300.5 1321.5 1337.9 1352.0 13689.8 13748.5 13837.2 13849.5 13869.0 4895.4 4986.7 5070.6 5083.8 5099.0 11837.5 11924.6 11951.3 11953.7 11962.5 5850.4 5856.1 5857.1 5859.9 5860.2 5096.9 5213.9 5219.7 5274.7 5356.4 4167.9 4171.2 4224.4 4245.4 4249.7 1246.0 1271.9 1272.1 1274.5 1285.7 887.3 910.2 993.9 1020.6 1021.6 6439.4 6530.8 6533.5 6540.4 6665.4 458.6 472.4 479.5 481.9 483.6 4196.2 4369.1 4386.7 4448.5 4459.5 12008.6 12357.0 12484.4 12484.4 12486.2 12277.6 12290.1 12382.9 12399.0 12415.9 8371.2 8733.2 8798.8 8906.1 8910.9 6170.8 6237.9 6467.0 6674.3 6702.9 11052.9 11162.6 11251.6 11405.1 11502.4 11289.0 11502.9 11588.5 11662.5 11666.9 8617.4 8684.4 8780.2 8804.0 8811.5 9175.7 9426.0 9487.9 9500.9 9542.4 7762.4 8073.2 8121.8 8143.1 8642.6 8392.8 8440.2 8445.1 8482.6 8613.8 7929.4 8007.2 8030.2 8035.4 8039.8 5315.7 5356.7 5409.9 5508.9 5576.7 4694.2 5559.1 5684.8 5695.1 5816.7 03:50:37 INFO - PROCESS | 3050 | 03:50:37 INFO - PROCESS | 3050 | [#4] prototype.html Cycles:180 Average:4654.62 Median:5169.76 stddev:1340.30 (25.9%) stddev-sans-first:1304.30 03:50:37 INFO - PROCESS | 3050 | Values: 337.0 339.9 351.1 352.3 352.9 5403.4 5429.4 5446.2 5460.4 5467.2 5324.2 5363.5 5370.7 5381.5 5393.7 3993.5 4264.6 4279.8 4283.8 4287.1 3957.8 4221.6 4246.1 4254.3 4258.0 4198.4 4202.4 4223.2 4233.0 4249.9 4021.7 4074.5 4231.9 4232.2 4233.6 3732.4 3749.9 3761.8 3776.3 3780.4 5117.5 5134.7 5143.5 5160.3 5233.9 4771.5 4838.1 4844.9 4849.3 4850.2 5635.1 5657.5 5668.4 5689.0 5704.5 3926.4 4021.9 4044.5 4123.9 4135.7 5815.5 5897.1 5898.2 5898.3 5899.4 4244.3 4246.1 4256.0 4268.3 4275.3 4184.9 4306.3 4306.7 4344.7 4352.8 4812.0 4818.2 4822.4 4840.6 4879.1 5602.9 5603.0 5611.2 5625.5 5673.4 1440.3 1482.9 1485.4 1487.2 1501.9 961.5 964.6 975.2 975.5 988.4 4840.9 4895.3 4907.7 4909.6 4957.6 5093.0 5098.8 5140.4 5264.0 5274.9 4555.7 4861.4 4920.9 5025.3 5042.3 2753.5 2767.5 2771.0 2777.7 2782.5 5171.9 5195.8 5296.8 5300.0 5320.3 4868.5 5167.6 5217.8 5252.3 5260.8 5239.4 5271.4 5281.6 5314.5 5325.2 5135.9 5149.2 5490.3 5689.7 5835.7 5730.1 5734.9 5742.5 5745.5 5748.3 5758.9 5911.7 5916.7 5937.9 5963.5 4245.3 4257.4 4260.3 4268.1 4299.5 5806.7 5819.6 5826.6 5854.6 5876.2 5470.6 5710.2 5747.0 5753.3 5772.2 5603.5 5604.2 5651.6 5652.5 5653.8 5511.3 5564.9 5602.2 5614.6 5614.7 5508.7 5675.1 5734.5 5745.2 5750.6 5542.4 5583.6 5597.2 5606.4 5614.3 03:50:37 INFO - PROCESS | 3050 | 03:50:37 INFO - PROCESS | 3050 | [#5] yui.html Cycles:180 Average:21714.28 Median:4566.93 stddev:32066.07 (702.1%) stddev-sans-first:32117.55 03:50:37 INFO - PROCESS | 3050 | Values: 795.7 819.9 820.2 841.5 842.6 15799.4 15820.0 15836.2 16056.4 17180.1 15737.7 15781.6 15813.6 15878.3 15914.1 719.7 848.0 869.6 872.4 874.7 847.8 847.8 851.0 851.5 852.7 750.4 918.9 919.6 920.8 921.8 892.9 912.4 913.3 914.0 916.2 669.7 670.2 670.4 670.5 671.0 14911.5 14940.0 14949.3 14956.3 15006.8 14305.0 14396.0 14511.5 14781.9 15053.2 12694.1 12800.2 12809.7 12942.5 13044.3 4538.5 4552.2 4581.6 4626.3 4739.5 78419.6 79157.0 79349.0 79353.0 80383.6 11271.4 11344.5 11753.1 12049.2 12392.4 21888.5 22323.4 22329.8 22340.0 22368.8 9088.7 9165.5 9165.7 9197.4 9237.2 5069.2 5206.5 5231.8 5258.1 5338.6 3538.9 3599.0 3615.8 3633.0 3636.4 562.7 566.7 636.0 689.0 698.1 735.5 758.6 781.1 786.2 799.8 9902.3 10311.4 10448.3 10479.3 10519.8 699.7 706.0 755.3 757.8 766.4 4687.9 4721.8 4724.5 4748.0 4761.6 123966.8 124075.0 125827.4 127456.0 130010.3 112741.0 114367.8 115203.0 115457.4 116022.4 64902.0 65138.7 76321.6 97369.0 100133.0 9319.7 9343.1 9464.4 9483.2 9618.5 46515.5 47403.5 48115.5 57966.4 58159.0 73167.0 73508.3 74473.3 75949.2 76928.0 12758.2 13735.7 13979.8 14053.8 14066.9 13086.5 14008.3 14097.0 14221.5 14384.6 9695.9 13648.4 13678.1 13754.4 14473.8 13009.8 13156.7 13293.8 13562.1 13572.9 13658.2 13813.7 13863.2 14079.4 14294.5 11235.2 11285.3 11537.7 11652.7 11888.6 11785.2 11894.6 12043.0 12151.8 12188.3 03:50:37 INFO - PROCESS | 3050 | -------- Summary: end -------- 03:50:37 INFO - PROCESS | 3050 | 03:50:37 INFO - TEST-INFO | 3050: exit 0 03:50:37 INFO - TEST-OK | dromaeo_css | took 1112732ms 03:50:37 INFO - TEST-START | kraken 03:50:37 INFO - operating with platform_type : linux_ 03:50:37 INFO - Initialising browser for kraken test... 03:50:37 INFO - TEST-INFO | started process 3240 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFwcZs3/profile http://localhost:52015/getInfo.html) 03:50:41 INFO - PROCESS | 3240 | __metrics Screen width/height:1600/1200 03:50:41 INFO - PROCESS | 3240 | colorDepth:24 03:50:41 INFO - PROCESS | 3240 | Browser inner width/height: 1024/697 03:50:41 INFO - PROCESS | 3240 | __metrics 03:50:43 ERROR - PROCESS | 3240 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:50:43 INFO - TEST-INFO | 3240: exit 0 03:50:43 INFO - Browser initialized. 03:50:43 INFO - Running cycle 1/1 for kraken test... 03:50:43 INFO - TEST-INFO | started process 3331 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFwcZs3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:50:55 INFO - PROCESS | 3331 | RSS: Main: 145039360 03:50:55 INFO - PROCESS | 3331 | 03:50:58 INFO - PROCESS | 3331 | Cycle 1(1): loaded http://localhost:52015/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52015/tests/kraken/driver.html?testName=audio-beat-detection) 03:50:58 INFO - PROCESS | 3331 | RSS: Main: 194965504 03:50:58 INFO - PROCESS | 3331 | 03:51:01 INFO - PROCESS | 3331 | Cycle 1(1): loaded http://localhost:52015/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52015/tests/kraken/driver.html?testName=audio-dft) 03:51:02 INFO - PROCESS | 3331 | RSS: Main: 191852544 03:51:02 INFO - PROCESS | 3331 | 03:51:04 INFO - PROCESS | 3331 | Cycle 1(1): loaded http://localhost:52015/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52015/tests/kraken/driver.html?testName=audio-fft) 03:51:05 INFO - PROCESS | 3331 | RSS: Main: 277999616 03:51:05 INFO - PROCESS | 3331 | 03:51:07 INFO - PROCESS | 3331 | Cycle 1(1): loaded http://localhost:52015/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52015/tests/kraken/driver.html?testName=audio-oscillator) 03:51:08 INFO - PROCESS | 3331 | RSS: Main: 186290176 03:51:08 INFO - PROCESS | 3331 | 03:51:10 INFO - PROCESS | 3331 | Cycle 1(1): loaded http://localhost:52015/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52015/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:51:10 INFO - PROCESS | 3331 | RSS: Main: 186392576 03:51:10 INFO - PROCESS | 3331 | 03:51:13 INFO - PROCESS | 3331 | Cycle 1(1): loaded http://localhost:52015/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52015/tests/kraken/driver.html?testName=imaging-darkroom) 03:51:14 INFO - PROCESS | 3331 | RSS: Main: 220819456 03:51:14 INFO - PROCESS | 3331 | 03:51:17 INFO - PROCESS | 3331 | Cycle 1(1): loaded http://localhost:52015/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52015/tests/kraken/driver.html?testName=imaging-desaturate) 03:51:18 INFO - PROCESS | 3331 | RSS: Main: 221188096 03:51:18 INFO - PROCESS | 3331 | 03:51:21 INFO - PROCESS | 3331 | Cycle 1(1): loaded http://localhost:52015/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52015/tests/kraken/driver.html?testName=json-parse-financial) 03:51:21 INFO - PROCESS | 3331 | RSS: Main: 221351936 03:51:21 INFO - PROCESS | 3331 | 03:51:23 INFO - PROCESS | 3331 | Cycle 1(1): loaded http://localhost:52015/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52015/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:51:23 INFO - PROCESS | 3331 | RSS: Main: 200114176 03:51:23 INFO - PROCESS | 3331 | 03:51:25 INFO - PROCESS | 3331 | Cycle 1(1): loaded http://localhost:52015/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52015/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:51:26 INFO - PROCESS | 3331 | RSS: Main: 200671232 03:51:26 INFO - PROCESS | 3331 | 03:51:28 INFO - PROCESS | 3331 | Cycle 1(1): loaded http://localhost:52015/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52015/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:51:28 INFO - PROCESS | 3331 | RSS: Main: 209838080 03:51:28 INFO - PROCESS | 3331 | 03:51:31 INFO - PROCESS | 3331 | Cycle 1(1): loaded http://localhost:52015/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52015/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:51:31 INFO - PROCESS | 3331 | RSS: Main: 215425024 03:51:31 INFO - PROCESS | 3331 | 03:51:34 INFO - PROCESS | 3331 | Cycle 1(1): loaded http://localhost:52015/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52015/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:51:35 INFO - PROCESS | 3331 | RSS: Main: 214716416 03:51:35 INFO - PROCESS | 3331 | 03:51:36 INFO - PROCESS | 3331 | Cycle 1(1): loaded http://localhost:52015/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52015/tests/kraken/driver.html?testName=ai-astar) 03:51:37 INFO - PROCESS | 3331 | RSS: Main: 257376256 03:51:37 INFO - PROCESS | 3331 | 03:51:37 INFO - PROCESS | 3331 | __start_tp_report 03:51:37 INFO - PROCESS | 3331 | _x_x_mozilla_page_load 03:51:37 INFO - PROCESS | 3331 | _x_x_mozilla_page_load_details 03:51:37 INFO - PROCESS | 3331 | |i|pagename|runs| 03:51:37 INFO - PROCESS | 3331 | |0;ai-astar;109;103;109;109;111;109;110;110;109;108 03:51:37 INFO - PROCESS | 3331 | |1;audio-beat-detection;150;124;125;181;124;126;150;124;154;124 03:51:37 INFO - PROCESS | 3331 | |2;audio-dft;161;161;159;161;160;167;159;161;159;162 03:51:37 INFO - PROCESS | 3331 | |3;audio-fft;76;75;75;122;76;76;76;75;102;75 03:51:37 INFO - PROCESS | 3331 | |4;audio-oscillator;87;84;116;84;84;85;120;84;84;128 03:51:37 INFO - PROCESS | 3331 | |5;imaging-gaussian-blur;117;117;116;117;117;116;117;116;117;117 03:51:37 INFO - PROCESS | 3331 | |6;imaging-darkroom;146;146;145;146;146;146;146;147;146;147 03:51:37 INFO - PROCESS | 3331 | |7;imaging-desaturate;95;95;96;95;95;95;95;95;95;95 03:51:37 INFO - PROCESS | 3331 | |8;json-parse-financial;53;51;52;52;52;53;54;53;53;52 03:51:37 INFO - PROCESS | 3331 | |9;json-stringify-tinderbox;60;60;59;60;60;60;59;60;60;60 03:51:37 INFO - PROCESS | 3331 | |10;stanford-crypto-aes;76;76;76;81;77;78;82;82;103;76 03:51:37 INFO - PROCESS | 3331 | |11;stanford-crypto-ccm;127;128;121;120;119;117;117;119;120;120 03:51:37 INFO - PROCESS | 3331 | |12;stanford-crypto-pbkdf2;157;155;162;160;152;159;156;153;154;154 03:51:37 INFO - PROCESS | 3331 | |13;stanford-crypto-sha256-iterative;62;62;62;61;62;61;61;61;60;60 03:51:37 INFO - PROCESS | 3331 | __end_tp_report 03:51:37 INFO - PROCESS | 3331 | __start_cc_report 03:51:37 INFO - PROCESS | 3331 | _x_x_mozilla_cycle_collect,1545 03:51:37 INFO - PROCESS | 3331 | __end_cc_report 03:51:37 INFO - PROCESS | 3331 | __startTimestamp1467975097111__endTimestamp 03:51:37 INFO - PROCESS | 3331 | 03:51:37 INFO - PROCESS | 3331 | ------- Summary: start ------- 03:51:37 INFO - PROCESS | 3331 | Number of tests: 14 03:51:37 INFO - PROCESS | 3331 | 03:51:37 INFO - PROCESS | 3331 | [#0] ai-astar Cycles:10 Average:108.70 Median:109.00 stddev:2.16 (2.0%) stddev-sans-first:2.29 03:51:37 INFO - PROCESS | 3331 | Values: 109.0 103.0 109.0 109.0 111.0 109.0 110.0 110.0 109.0 108.0 03:51:37 INFO - PROCESS | 3331 | 03:51:37 INFO - PROCESS | 3331 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:138.00 stddev:19.69 (14.3%) stddev-sans-first:20.42 03:51:37 INFO - PROCESS | 3331 | Values: 150.0 124.0 125.0 181.0 124.0 126.0 150.0 124.0 154.0 124.0 03:51:37 INFO - PROCESS | 3331 | 03:51:37 INFO - PROCESS | 3331 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:2.36 (1.5%) stddev-sans-first:2.50 03:51:37 INFO - PROCESS | 3331 | Values: 161.0 161.0 159.0 161.0 160.0 167.0 159.0 161.0 159.0 162.0 03:51:37 INFO - PROCESS | 3331 | 03:51:37 INFO - PROCESS | 3331 | [#3] audio-fft Cycles:10 Average:82.80 Median:75.50 stddev:16.10 (21.3%) stddev-sans-first:16.89 03:51:37 INFO - PROCESS | 3331 | Values: 76.0 75.0 75.0 122.0 76.0 76.0 76.0 75.0 102.0 75.0 03:51:37 INFO - PROCESS | 3331 | 03:51:37 INFO - PROCESS | 3331 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:84.00 stddev:18.01 (21.4%) stddev-sans-first:18.84 03:51:37 INFO - PROCESS | 3331 | Values: 87.0 84.0 116.0 84.0 84.0 85.0 120.0 84.0 84.0 128.0 03:51:37 INFO - PROCESS | 3331 | 03:51:37 INFO - PROCESS | 3331 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 03:51:37 INFO - PROCESS | 3331 | Values: 117.0 117.0 116.0 117.0 117.0 116.0 117.0 116.0 117.0 117.0 03:51:37 INFO - PROCESS | 3331 | 03:51:37 INFO - PROCESS | 3331 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 03:51:37 INFO - PROCESS | 3331 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 146.0 147.0 146.0 147.0 03:51:37 INFO - PROCESS | 3331 | 03:51:37 INFO - PROCESS | 3331 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 03:51:37 INFO - PROCESS | 3331 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 03:51:37 INFO - PROCESS | 3331 | 03:51:37 INFO - PROCESS | 3331 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:53.00 stddev:0.85 (1.6%) stddev-sans-first:0.88 03:51:37 INFO - PROCESS | 3331 | Values: 53.0 51.0 52.0 52.0 52.0 53.0 54.0 53.0 53.0 52.0 03:51:37 INFO - PROCESS | 3331 | 03:51:37 INFO - PROCESS | 3331 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 03:51:37 INFO - PROCESS | 3331 | Values: 60.0 60.0 59.0 60.0 60.0 60.0 59.0 60.0 60.0 60.0 03:51:37 INFO - PROCESS | 3331 | 03:51:37 INFO - PROCESS | 3331 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:77.50 stddev:8.23 (10.6%) stddev-sans-first:8.56 03:51:37 INFO - PROCESS | 3331 | Values: 76.0 76.0 76.0 81.0 77.0 78.0 82.0 82.0 103.0 76.0 03:51:37 INFO - PROCESS | 3331 | 03:51:37 INFO - PROCESS | 3331 | [#11] stanford-crypto-ccm Cycles:10 Average:120.80 Median:120.00 stddev:3.77 (3.1%) stddev-sans-first:3.26 03:51:37 INFO - PROCESS | 3331 | Values: 127.0 128.0 121.0 120.0 119.0 117.0 117.0 119.0 120.0 120.0 03:51:37 INFO - PROCESS | 3331 | 03:51:37 INFO - PROCESS | 3331 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.20 Median:156.50 stddev:3.26 (2.1%) stddev-sans-first:3.44 03:51:37 INFO - PROCESS | 3331 | Values: 157.0 155.0 162.0 160.0 152.0 159.0 156.0 153.0 154.0 154.0 03:51:37 INFO - PROCESS | 3331 | 03:51:37 INFO - PROCESS | 3331 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.50 stddev:0.79 (1.3%) stddev-sans-first:0.78 03:51:37 INFO - PROCESS | 3331 | Values: 62.0 62.0 62.0 61.0 62.0 61.0 61.0 61.0 60.0 60.0 03:51:37 INFO - PROCESS | 3331 | -------- Summary: end -------- 03:51:37 INFO - PROCESS | 3331 | 03:51:38 INFO - TEST-INFO | 3331: exit 0 03:51:38 INFO - TEST-OK | kraken | took 60244ms 03:51:38 INFO - SUITE-END | took 1172s 03:51:38 INFO - Completed test suite (00:19:33) 03:51:38 INFO - javascript benchmark 03:51:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3283.9835800821, 3306.570145881035, 3314.453115235987, 3324.0, 3363.2768954674757, 5388.811391601294, 5422.213779002042, 5469.835904922848, 5490.69801160936, 5510.531604813596, 5457.045017121997, 5538.529225015868, 5539.362973258077, 5549.944500555003, 5565.0817615093565, 4938.555530002299, 5041.017001684682, 5056.646034777567, 5094.77073531692, 5137.820176293831, 4920.3111564381015, 5058.671186372892, 5091.923621145667, 5094.796208151688, 5095.949040509603, 5018.924716129242, 5058.494150584949, 5086.033653605803, 5086.593072554206, 5086.847394578169, 5157.8452646420665, 5158.277841102249, 5170.146925757245, 5188.610854185913, 5213.1919552469235, 4861.1006963711825, 4928.704277743347, 5007.749612519359, 5043.773030213649, 5058.342415486, 5342.786288548454, 5364.195370694388, 5385.111456609655, 5413.729313534308, 5414.620976531644, 5905.616134951258, 5941.019731744257, 5947.970260148715, 5949.0, 5950.345461999176, 5839.8540036499, 5907.940920590782, 5910.34986151523, 5962.642241465526, 5973.790917317917, 5498.725063746837, 5528.419515950848, 5544.085225937756, 5544.279243698294, 5548.361938377098, 5928.199693041471, 6055.183589070685, 6057.848553786191, 6067.059605761114, 6070.878582428327, 5735.082386818089, 5769.826905192851, 5786.94213057864, 5875.9118613220835, 5879.147523609138, 5918.822435326861, 5922.703864806743, 5922.970385148046, 5948.226730525091, 5953.672548009901, 5710.600257981984, 5788.53691704671, 5885.440883116097, 5895.20414744004, 5897.410258974069, 5830.970845145747, 5886.087656413263, 5891.293044834561, 5892.263467066617, 5934.050551911673, 4591.035882464653, 4593.678442508993, 4602.88492787683, 4605.884852878705, 4626.930596041062, 4034.8991275218355, 4038.071243613985, 4078.2455245779624, 4116.958830411718, 4162.666986641061, 5569.331680198402, 5590.8043218487155, 5596.356419011784, 5598.964191624563, 5620.831375058755, 5925.466707996301, 5935.7625694972585, 5937.435943585352, 5940.811837632491, 5972.880542389128, 5421.2139239810795, 5500.779968801283, 5540.639858409271, 5554.1391084381985, 5575.52608028314, 5088.618353623493, 5098.337216161875, 5105.668131571436, 5111.156659151198, 5113.386393632788, 6037.818865434044, 6040.969795151084, 6045.183900173508, 6060.696965151725, 6078.878422431527, 5681.9147712783515, 5772.797952071657, 5808.6224395414165, 5847.678377689268, 5899.056151015817, 6021.458068773832, 6027.849303767441, 6031.60794548353, 6037.45662890342, 6039.909401359071, 5636.267285252891, 5797.8550536236935, 5803.0, 5807.332156802022, 5808.186853840381, 5799.8550036249435, 5818.185454036354, 5838.715482593822, 5883.823235352861, 5888.3228428731245, 5727.570432217517, 5770.336411312753, 5773.653580785166, 5774.595778295479, 5775.075987842093, 5448.891022179455, 5588.86027849307, 5723.112917497794, 5725.885482290248, 5730.111832665943, 5537.1140617501005, 5615.2700148982, 5623.8875222496135, 5627.324721033502, 5660.9150862737915, 5800.0, 5815.854603634943, 5833.620814647034, 5837.0, 5842.7078646066675, 5532.059549876451, 5543.473370029841, 5610.046292130266, 5673.886522269614, 5699.344575373885], "value": 5439.645424401201, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.9909851352228, 639.0765344077807, 639.6641763074385, 656.7865443730789, 658.3219284137339, 32513.02460926173, 35754.642453575456, 35759.10602234946, 35761.0, 45235.0, 48776.04895804173, 49396.50603493964, 49599.752001240035, 49617.50382496174, 49632.2555161672, 9054.637814487412, 9265.907340926571, 9269.443833369985, 9270.07299270071, 9273.304502162331, 8016.318612917909, 9086.182243598076, 10815.513301901434, 11053.060489858372, 11058.502367393487, 11174.1060715143, 11227.32636041836, 11230.382328971904, 11234.32594044356, 11242.213045086806, 8342.708005219849, 10984.890151098467, 11059.059979901718, 11066.170037247239, 11076.833847492335, 10608.575656973711, 10970.0, 11001.339919604769, 11002.614908478165, 11358.94320528392, 41083.178336433404, 41558.584414156074, 46163.76918115422, 46182.30726539105, 46200.3069953951, 3887.941680874789, 3997.480327557428, 4002.1595464952393, 4065.125997910434, 4453.554644535553, 34457.13857153566, 35551.11122221939, 35696.46455303173, 35829.10427239314, 35862.820685896666, 4692.577668009895, 4743.976280118612, 4751.983292339507, 4764.118638051971, 4920.040592084545, 382887.0, 388507.0, 388851.0, 389112.05443972885, 389833.0, 71573.0, 72437.63781181061, 72522.63738681379, 72593.6370318145, 72637.0, 41319.38020929688, 42362.0, 42372.15255694869, 44573.10853782906, 44651.77674111609, 18024.549386265186, 18032.0, 18055.097245137953, 18072.09639518019, 18072.548186295448, 11030.172737044753, 15503.14732689713, 15647.68704625901, 15781.684366312838, 15787.68424631501, 22470.550588988128, 22687.0, 22810.17518598508, 22873.05579025768, 22920.885395572914, 7890.250426209501, 7949.801254968672, 8280.58597070144, 8282.751517454486, 8320.791980200422, 1052.4632437456953, 1059.7933402986498, 1094.4527736131934, 1171.5255321594598, 1181.6868529839599, 33786.0, 33895.49156762651, 33955.15112122167, 34382.312353752786, 34403.139921501665, 1177.2053863642006, 1180.1797750563449, 1211.666791632308, 1216.793145165324, 1251.455616806692, 17690.823091769176, 18094.0, 18128.818711812975, 18342.358017469593, 18345.449636510926, 655935.0, 657731.0, 658640.0, 658871.0, 660665.0, 66290.66854665792, 67641.0, 67664.0, 67808.32191678119, 67833.66083169651, 1344.6974430753003, 1463.875570576513, 1494.5740463967934, 1514.0, 1528.4268399350244, 34999.30001400009, 36940.26119477542, 37023.0, 37320.0, 37433.62566374301, 56118.43881561132, 56908.71545642411, 56935.43064569301, 57044.0, 57058.7147064262, 444291.0, 445559.0, 447316.0, 448402.0, 449439.0, 24317.513649726552, 30713.53929691101, 31919.36161276808, 32032.51951220687, 33796.831015844764, 52938.0, 53102.73448632887, 53338.19992700035, 53857.46142538681, 54994.17508737292, 43896.34155487734, 44117.0, 44655.0, 45271.320930185415, 46358.30462542997, 43756.423176316355, 44403.55596443994, 44423.0, 44544.77727611341, 45331.773341133085, 44809.0, 45582.0, 45906.0, 45929.31106033479, 46211.53788462207, 37600.0, 38383.42424863573, 38638.03404914787, 39716.404253936795, 39839.40240896331, 29528.557071644373, 30079.699203008564, 30345.52774722656, 30548.541771872995, 30684.693153069078], "value": 22039.512151559706, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.690705784777, 424.25331416706587, 426.4030357499501, 429.158848656633, 437.4772147283997, 18588.256469741213, 19550.608987820237, 20306.289279875207, 20562.588748225025, 20623.175072997063, 19451.610967780634, 19621.705674414894, 19728.506787330323, 20565.28021519247, 20745.792542074574, 5765.481106700396, 5915.497182739472, 5958.523318134538, 5967.880642387149, 5969.164316995624, 4168.995528585124, 5125.948740512603, 5872.236609240793, 5933.406659334075, 6580.967095164518, 6612.966935165317, 6627.204735431755, 6629.73481060757, 6655.0, 6656.467482601404, 5511.751971161288, 5793.681347525884, 5806.970965145189, 5840.561957853178, 6619.040239165318, 3909.960900390988, 5474.37044739856, 5708.971455142698, 5739.282589676291, 5757.222774925415, 14140.434382624682, 14478.638034049127, 14622.341994610268, 14660.780088298685, 14754.0, 2445.1319781477673, 2447.632855071736, 2450.963235551468, 2474.208253358926, 2511.6860392450944, 10082.848757268646, 10494.0, 10513.9474302628, 10535.78928421435, 10885.727856803564, 1956.0024387562303, 2038.9796102038938, 2110.2614085070254, 2266.2747920665392, 2284.2690339091496, 73537.0, 81215.5939220306, 83277.0, 87312.1268787304, 87874.0, 11258.380789056517, 11556.0, 11747.236429632047, 11799.646010619696, 11871.525138994517, 16408.0, 16487.422940197037, 16578.088205148833, 16583.005019698856, 16628.085455300075, 8850.424722393023, 8907.797447344525, 9051.230645395215, 9137.862932056025, 9162.22121119699, 8912.108789121166, 8967.865482017774, 8999.100089990949, 9036.954815226014, 9173.220276276592, 5009.574186194214, 5023.472535383732, 5046.369203849519, 5085.7711402986215, 5134.562322549692, 2045.6985890872768, 2046.4576887124924, 2055.3525639423533, 2073.5438203595186, 2111.74659040913, 958.3914214473859, 982.3418309732497, 1050.658535975811, 1050.9579616815395, 1089.330062011857, 19693.40919772409, 20225.089870955842, 20430.69353959692, 20519.8974005132, 20575.0, 1022.728976821143, 1033.684726158529, 1051.059301924773, 1075.6450371377427, 1083.2038451737967, 10579.36523808574, 11201.159913006557, 11348.375839328915, 11367.0, 11407.94296028531, 531022.0, 537128.0, 538117.0, 542046.0, 542459.0, 77634.61182694163, 103074.0, 103571.48214258881, 104005.0, 104536.47731761292, 1073.3506228731505, 1083.0793825248477, 1088.9019988200941, 1089.8637670291214, 1096.0190629386655, 17419.387091935805, 17654.646907062044, 17665.646687065928, 18028.36900708469, 18086.638267234845, 30997.845010774803, 35183.64816351804, 40222.0, 43359.56640433555, 45872.54127458682, 60078.0, 61541.69229153825, 61670.69164654148, 62002.37997619966, 62099.37900620936, 8931.19619234272, 9105.635774568944, 9148.313876459159, 9276.489793061446, 9280.0, 13600.387982541008, 14042.648933776738, 14083.0, 14190.077644953046, 14280.785788212977, 12476.376181191086, 12554.309512976508, 12557.434915428645, 12591.11862169676, 12722.554710585086, 12267.57063502773, 12317.44571494267, 12356.320402377956, 12497.0, 12588.181768185405, 12376.133670642968, 12539.310337931136, 12673.049521285757, 12687.492500299884, 12713.364331783188, 9137.269018478373, 9221.769455763659, 9222.723318300461, 9280.071992800937, 9311.72064838056, 11924.0, 11952.701182470508, 12353.567625133077, 12546.12177147591, 12674.239545627292], "value": 9588.984527459263, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1378.531299358218, 1560.438242232796, 1604.7111519926411, 1810.9728354074691, 1910.4173227165718, 8617.095205003478, 8919.64321427143, 8921.50931698757, 9217.400868943514, 9229.90770092299, 8960.91039089609, 9123.042080581543, 9157.862632060507, 9161.679341223058, 9172.862407063883, 2433.07543133609, 2452.7792498675117, 2456.742042085582, 2457.0, 2461.692288463942, 2387.868667223302, 2478.1945867593013, 2479.665245191894, 2480.503899220163, 2480.702315722116, 2413.613821788514, 2420.9757902421015, 2423.0, 2424.745401732819, 2428.550718117154, 2340.2394221877958, 2375.8812059397046, 2378.9286321410386, 2391.689080419543, 2394.305651361112, 2324.8721320327377, 2372.181597348912, 2385.630227314769, 2386.4869053153657, 2388.4148383645943, 7929.484583502054, 9017.864732029026, 9096.272298216127, 9147.085291470867, 9152.771180720469, 5487.094629386146, 5720.427957204288, 5722.427757224286, 5743.253377060997, 5745.741441635137, 3582.1223800168864, 3624.981875090634, 3649.835757390924, 3651.1054791575966, 3730.9067273318115, 1255.7927941889577, 1300.4538094000543, 1321.4648067532664, 1337.9197248165044, 1351.9797203041962, 13689.794653080211, 13748.518801841887, 13837.238951857644, 13849.515266965709, 13869.029167958248, 4895.363602731622, 4986.650934434628, 5070.645054846199, 5083.822066227738, 5099.031184075014, 11837.467313970892, 11924.582639607572, 11951.342676152895, 11953.701157471132, 11962.521499140112, 5850.414958504116, 5856.092305692625, 5857.121431785266, 5859.94140058594, 5860.2381690380835, 5096.872578185575, 5213.895722085537, 5219.739013049332, 5274.657147285414, 5356.3572371315695, 4167.91664166715, 4171.165766846643, 4224.429701990192, 4245.426867372927, 4249.745015299092, 1245.9688507787196, 1271.879171478708, 1272.1285919145414, 1274.5156840400589, 1285.6528737240892, 887.2946007923587, 910.1762904571374, 993.8906720260766, 1020.6121673764071, 1021.6117875207523, 6439.420452159235, 6530.771423000172, 6533.477321814244, 6540.444062254762, 6665.366790154928, 458.63309352517854, 472.41892472259315, 479.46300143839125, 481.85062630584633, 483.6396884321203, 4196.160767846443, 4369.104333611602, 4386.670999675037, 4448.466184057869, 4459.531749166291, 12008.579699710468, 12357.011439084852, 12484.438200281009, 12484.438200281009, 12486.188397754117, 12277.570285039981, 12290.078244131726, 12382.938085309515, 12399.0, 12415.93792031034, 8371.162883711702, 8733.170348816851, 8798.824023519493, 8906.109389061043, 8910.86633700495, 6170.753169873155, 6237.906431403442, 6466.967665161644, 6674.265830758515, 6702.932970670426, 11052.889471105185, 11162.553497859995, 11251.606193783178, 11405.087592992706, 11502.367369794742, 11289.0, 11502.88497115018, 11588.47851846652, 11662.533498660297, 11666.88333116658, 8617.439866408664, 8684.39209255346, 8780.165884240987, 8804.0, 8811.471311721061, 9175.724728258163, 9425.952870235606, 9487.905120948702, 9500.857487137837, 9542.379745316532, 7762.3790096793255, 8073.19268073188, 8121.837563248584, 8143.063547692092, 8642.56787160652, 8392.83214335722, 8440.240378365976, 8445.07104218523, 8482.618282177193, 8613.751006104205, 7929.365650748042, 8007.199280071946, 8030.196980301923, 8035.397345199017, 8039.758807235792, 5315.681059136464, 5356.678599284055, 5409.918851217313, 5508.862278443071, 5576.665400076008, 4694.201985662377, 5559.054960656779, 5684.8010319638615, 5695.145728140812, 5816.680082595498], "value": 4905.797353591645, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.971357434618, 339.8657530275541, 351.12394575534046, 352.2848617306867, 352.8941317604718, 5403.432639572848, 5429.375621803494, 5446.210299506574, 5460.372057213412, 5467.179923011546, 5324.174752913295, 5363.490468405495, 5370.677759334442, 5381.461853814617, 5393.6763794172275, 3993.520777506689, 4264.616184543391, 4279.801655536439, 4283.828646854122, 4287.1425714857, 3957.76253424794, 4221.556736542665, 4246.087091275375, 4254.298040823276, 4257.978085259547, 4198.3702444633245, 4202.390653355262, 4223.176480586287, 4232.962924083604, 4249.851255206068, 4021.698372622065, 4074.5314288856866, 4231.894202644928, 4232.195882782277, 4233.618974292329, 3732.3500314776543, 3749.887503374903, 3761.799347381785, 3776.3391406504024, 3780.3573392523335, 5117.48825117489, 5134.666246693991, 5143.537081662668, 5160.329157209576, 5233.869153271161, 4771.5467030632035, 4838.129136755382, 4844.878878028042, 4849.3453383793085, 4850.223964165716, 5635.126555383922, 5657.500762297995, 5668.404817494145, 5688.97155514224, 5704.4865962063195, 3926.4110383442426, 4021.939670904939, 4044.534878488982, 4123.9381409278885, 4135.6898232632375, 5815.476607105338, 5897.115432685132, 5898.174255604219, 5898.321693005317, 5899.380565040695, 4244.257254980366, 4246.108317253412, 4255.97872010638, 4268.253055715238, 4275.315949448073, 4184.916301673949, 4306.289462238696, 4306.698531102856, 4344.739315641008, 4352.847650332224, 4812.0, 4818.204996175662, 4822.373091498082, 4840.572031250789, 4879.146149423907, 5602.887942241214, 5603.0, 5611.242482264842, 5625.493705566437, 5673.404292549306, 1440.3086518470982, 1482.9110253384615, 1485.3984136424765, 1487.196913666608, 1501.8948673592743, 961.500059906548, 964.5563041001218, 975.1905918088112, 975.5171190260725, 988.3773222869546, 4840.903181936412, 4895.290182923456, 4907.730074845917, 4909.582685471703, 4957.628177886673, 5092.974535127375, 5098.796048158107, 5140.383154021467, 5263.973680131575, 5274.892272622792, 4555.681102322806, 4861.440934292531, 4920.876978075506, 5025.321581586512, 5042.268871013683, 2753.5456649652583, 2767.533207400081, 2771.0, 2777.722227777206, 2782.539166937374, 5171.905378321786, 5195.818146364934, 5296.814611488579, 5300.031982090042, 5320.255164277004, 4868.537488938545, 5167.622893468972, 5217.7912883485, 5252.264682944391, 5260.842174734764, 5239.397469291004, 5271.367435907716, 5281.55106815925, 5314.4951229633125, 5325.174597937326, 5135.8686145250795, 5149.227615857501, 5490.258815060076, 5689.658620482619, 5835.737391817293, 5730.111832665943, 5734.913976290276, 5742.483176514133, 5745.540356771532, 5748.310202775694, 5758.942410575841, 5911.674857882857, 5916.7041647918295, 5937.851553711192, 5963.50912271932, 4245.299525578245, 4257.382679511516, 4260.254455470218, 4268.125034367885, 4299.495176688134, 5806.65160090396, 5819.563532734977, 5826.630741775594, 5854.594897224721, 5876.236089308362, 5470.562355011669, 5710.172025056427, 5747.0, 5753.309602847685, 5772.163036359789, 5603.467670571289, 5604.215409842707, 5651.6043876928225, 5652.519535839499, 5653.7950199423085, 5511.255980442589, 5564.860878478071, 5602.243697101002, 5614.578906581941, 5614.747336369792, 5508.650380656764, 5675.120356344773, 5734.5125664319, 5745.1956726059225, 5750.568707346882, 5542.362628297636, 5583.637063590853, 5597.236870385816, 5606.411326810626, 5614.2701448811395], "value": 4234.478629408517, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.6618437164203, 819.9180081991801, 820.1511435664087, 841.5329492131868, 842.5744998775625, 15799.447019354326, 15820.0, 15836.208189590503, 16056.357745690173, 17180.055096969663, 15737.685246295096, 15781.60545986351, 15813.60465988351, 15878.285477153528, 15914.124723140223, 719.7336985315427, 848.0374774630777, 869.6217145541709, 872.3631748823362, 874.6982291109557, 847.817719190374, 847.8346722389136, 851.0213254757017, 851.4550687559966, 852.6930305090162, 750.4184257200686, 918.9494577798218, 919.5953780336664, 920.7836158502746, 921.8110287391103, 892.9107089291051, 912.4160537256129, 913.3150137396952, 913.9908600914039, 916.1662886773071, 669.7488441834312, 670.1656437735003, 670.4401824476568, 670.5004771445256, 670.9765158219438, 14911.478098266618, 14940.0, 14949.327280272306, 14956.326965286587, 15006.774898376532, 14305.0, 14396.0, 14511.492097776527, 14781.852181478154, 15053.172075535842, 12694.11141220115, 12800.16798908068, 12809.679758006032, 12942.482300708054, 13044.347782610832, 4538.546145385468, 4552.226121559343, 4581.633469322446, 4626.282926146419, 4739.502352252998, 78419.6079019607, 79157.0, 79349.0, 79353.0, 80383.5980820098, 11271.38007409584, 11344.546218151347, 11753.118516111326, 12049.15655904096, 12392.380380980914, 21888.452788680093, 22323.44191395228, 22329.776702232768, 22340.0, 22368.776312236994, 9088.681896133603, 9165.495897725556, 9165.725028249164, 9197.448153110834, 9237.214836738818, 5069.214271787879, 5206.479352064839, 5231.8168864089575, 5258.079836028756, 5338.599605029561, 3538.9469157962653, 3598.982005089958, 3615.8011309378235, 3633.0190848471293, 3636.4181730922924, 562.6567793645872, 566.6798258983661, 635.9586626869238, 688.9621070841051, 698.1133959870936, 735.5108852612952, 758.6320634492267, 781.1095351299523, 786.1823703348567, 799.8280369720538, 9902.257330700082, 10311.432871192006, 10448.32085914413, 10479.318844275098, 10519.789604208027, 699.6991293743739, 706.0292098364748, 755.2593106524249, 757.814335487809, 766.3639179481017, 4687.929681054787, 4721.787519561559, 4724.503927087675, 4747.976260118678, 4761.619070474354, 123966.80165991664, 124075.0, 125827.37086314693, 127456.0, 130010.34994824966, 112741.0, 114367.84482327718, 115203.0, 115457.42271288758, 116022.4198879017, 64902.0, 65138.67430662911, 76321.61839190879, 97369.02630973741, 100133.0, 9319.720408387759, 9343.112404321579, 9464.363044484553, 9483.24134069259, 9618.470984095942, 46515.53484465112, 47403.52596474129, 48115.518844812505, 57966.42033579779, 58159.0, 73167.0, 73508.26491735014, 74473.25526744663, 75949.20253987389, 76928.0, 12758.234505929673, 13735.656608584864, 13979.790303145257, 14053.789193162314, 14066.929665351608, 13086.541971030969, 14008.299585020504, 14097.0, 14221.502247421291, 14384.568462946128, 9695.854562181714, 13648.385822637807, 13678.110922789987, 13754.381052852446, 14473.78289325682, 13009.804852927024, 13156.671083222613, 13293.80059299092, 13562.118462299919, 13572.93213533926, 13658.248796316298, 13813.723725525635, 13863.168209907439, 14079.436822527394, 14294.499692510712, 11235.213535052468, 11285.322880627189, 11537.653870383901, 11652.650420487398, 11888.583899563473, 11785.175037747618, 11894.643160705193, 12043.0, 12151.75696486083, 12188.329641869783], "value": 7872.670023760974, "unit": "score"}], "name": "dromaeo_css", "value": 7568.978391344448, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 103.0, 109.0, 109.0, 111.0, 109.0, 110.0, 110.0, 109.0, 108.0], "value": 108.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 124.0, 125.0, 181.0, 124.0, 126.0, 150.0, 124.0, 154.0, 124.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 159.0, 161.0, 160.0, 167.0, 159.0, 161.0, 159.0, 162.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 122.0, 76.0, 76.0, 76.0, 75.0, 102.0, 75.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 116.0, 84.0, 84.0, 85.0, 120.0, 84.0, 84.0, 128.0], "value": 95.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 147.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 52.0, 52.0, 52.0, 53.0, 54.0, 53.0, 53.0, 52.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 60.0, 60.0, 60.0, 59.0, 60.0, 60.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 81.0, 77.0, 78.0, 82.0, 82.0, 103.0, 76.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 128.0, 121.0, 120.0, 119.0, 117.0, 117.0, 119.0, 120.0, 120.0], "value": 120.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 155.0, 162.0, 160.0, 152.0, 159.0, 156.0, 153.0, 154.0, 154.0], "value": 156.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 61.0, 62.0, 61.0, 61.0, 61.0, 60.0, 60.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1475.4, "alertThreshold": 2.0}]} 03:51:38 INFO - Return code: 0 03:51:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:51:38 ERROR - # TBPL SUCCESS # 03:51:38 INFO - Running post-action listener: _resource_record_post_action 03:51:38 INFO - ##### 03:51:38 INFO - ##### Finished run-tests step (success) 03:51:38 INFO - ##### 03:51:38 INFO - Running post-run listener: _resource_record_post_run 03:51:38 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 299008; Write bytes: 507498496; Read time: 64; Write time: 391392 03:51:38 INFO - TinderboxPrint: CPU usage
13.1% 03:51:38 INFO - TinderboxPrint: I/O read bytes / time
299,008 / 64 03:51:38 INFO - TinderboxPrint: I/O write bytes / time
507,498,496 / 391,392 03:51:38 INFO - TinderboxPrint: CPU idle
8,248.5 (86.8%) 03:51:38 INFO - TinderboxPrint: CPU user
1,203.5 (12.7%) 03:51:38 INFO - TinderboxPrint: Swap in / out
0 / 0 03:51:38 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 242925568; Read time: 0; Write time: 245096 03:51:38 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 274432; Write bytes: 252960768; Read time: 56; Write time: 143904 03:51:39 INFO - Running post-run listener: _upload_blobber_files 03:51:39 INFO - Blob upload gear active. 03:51:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:51:39 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 03:51:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:51:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:51:39 INFO - (blobuploader) - INFO - Open directory for files ... 03:51:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:51:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:51:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:51:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:51:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:51:40 INFO - (blobuploader) - INFO - Done attempting. 03:51:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:51:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:51:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:51:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:51:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:51:44 INFO - (blobuploader) - INFO - Done attempting. 03:51:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:51:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:51:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:51:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:51:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:51:45 INFO - (blobuploader) - INFO - Done attempting. 03:51:45 INFO - (blobuploader) - INFO - Iteration through files over. 03:51:45 INFO - Return code: 0 03:51:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:51:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:51:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38cd7700161ed825e391e1a62f2524c08214b2b5b23b03f638646735c0ad9cf62354a1d547cb0cc0fc8db066ea9ffdfa6f34a85a6fe16a95d4b6f8b24e2d3d47", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b5cc0c225bc7b8f9a64af79cdb06770064d76c88d09260cfc148d0481498bdba9701ae3636fc239eac711aca2ac7cafadb4883559bc1f5cea6175c20fafd05b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db9cdb1f9fdd9ecded69d139056ef63aeff0b70a83842c0446769a14a0b63e01663819c2f68250507ec9dcbde9c04092787021485e897d022aa6b4d70179ed71"} 03:51:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:51:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:51:45 INFO - Contents: 03:51:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38cd7700161ed825e391e1a62f2524c08214b2b5b23b03f638646735c0ad9cf62354a1d547cb0cc0fc8db066ea9ffdfa6f34a85a6fe16a95d4b6f8b24e2d3d47", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b5cc0c225bc7b8f9a64af79cdb06770064d76c88d09260cfc148d0481498bdba9701ae3636fc239eac711aca2ac7cafadb4883559bc1f5cea6175c20fafd05b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db9cdb1f9fdd9ecded69d139056ef63aeff0b70a83842c0446769a14a0b63e01663819c2f68250507ec9dcbde9c04092787021485e897d022aa6b4d70179ed71"} 03:51:45 INFO - Running post-run listener: copy_logs_to_upload_dir 03:51:45 INFO - Copying logs to upload dir... 03:51:45 INFO - mkdir: /builds/slave/test/build/upload/logs 03:51:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.724815 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-07-08 03:51:44.965654) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 03:51:44.969519) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38cd7700161ed825e391e1a62f2524c08214b2b5b23b03f638646735c0ad9cf62354a1d547cb0cc0fc8db066ea9ffdfa6f34a85a6fe16a95d4b6f8b24e2d3d47", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b5cc0c225bc7b8f9a64af79cdb06770064d76c88d09260cfc148d0481498bdba9701ae3636fc239eac711aca2ac7cafadb4883559bc1f5cea6175c20fafd05b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db9cdb1f9fdd9ecded69d139056ef63aeff0b70a83842c0446769a14a0b63e01663819c2f68250507ec9dcbde9c04092787021485e897d022aa6b4d70179ed71"} build_url:https://queue.taskcluster.net/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011447 build_url: 'https://queue.taskcluster.net/v1/task/Zy6leXWWRmKLPgJ-ULPD0A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38cd7700161ed825e391e1a62f2524c08214b2b5b23b03f638646735c0ad9cf62354a1d547cb0cc0fc8db066ea9ffdfa6f34a85a6fe16a95d4b6f8b24e2d3d47", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b5cc0c225bc7b8f9a64af79cdb06770064d76c88d09260cfc148d0481498bdba9701ae3636fc239eac711aca2ac7cafadb4883559bc1f5cea6175c20fafd05b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db9cdb1f9fdd9ecded69d139056ef63aeff0b70a83842c0446769a14a0b63e01663819c2f68250507ec9dcbde9c04092787021485e897d022aa6b4d70179ed71"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 03:51:44.994687) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 03:51:44.994988) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1467973871.368803-967227340 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004384 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 03:51:45.047089) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 03:51:45.047489) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 03:51:45.047826) ========= ========= Total master_lag: 0.13 =========