builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-060 starttime: 1466440408.77 results: success (0) buildid: 20160620065526 builduid: db123a3e0e9f4b6a8c8f8e53ac4852be revision: 804b78a09df71fb081761064750cbbc6fc47611c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-20 09:33:28.771235) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-20 09:33:28.771712) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-20 09:33:28.771998) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-20 09:33:28.805182) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 09:33:28.805590) ========= 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=337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-20 09:33:28-- 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% 43.5M=0s 2016-06-20 09:33:29 (43.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.150841 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 09:33:28.971273) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 09:33:28.971623) ========= 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=337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017723 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 09:33:29.023347) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 09:33:29.023837) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 804b78a09df71fb081761064750cbbc6fc47611c --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 804b78a09df71fb081761064750cbbc6fc47611c --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=337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-20 09:33:29,158 truncating revision to first 12 chars 2016-06-20 09:33:29,158 Setting DEBUG logging. 2016-06-20 09:33:29,158 attempt 1/10 2016-06-20 09:33:29,158 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/804b78a09df7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-20 09:33:29,511 unpacking tar archive at: mozilla-aurora-804b78a09df7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.668120 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 09:33:29.705397) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-20 09:33:29.705713) ========= script_repo_revision: 804b78a09df71fb081761064750cbbc6fc47611c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-20 09:33:29.706102) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-20 09:33:29.706367) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-20 09:33:29.717700) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-06-20 09:33:29.718103) ========= /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=337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:33:30 INFO - MultiFileLogger online at 20160620 09:33:30 in /builds/slave/test 09:33:30 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 09:33:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:33:30 INFO - {'append_to_log': False, 09:33:30 INFO - 'base_work_dir': '/builds/slave/test', 09:33:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:33:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:33:30 INFO - 'branch': 'Mozilla-Aurora', 09:33:30 INFO - 'buildbot_json_path': 'buildprops.json', 09:33:30 INFO - 'config_files': ('talos/linux_config.py',), 09:33:30 INFO - 'default_actions': ('clobber', 09:33:30 INFO - 'read-buildbot-config', 09:33:30 INFO - 'download-and-extract', 09:33:30 INFO - 'populate-webroot', 09:33:30 INFO - 'create-virtualenv', 09:33:30 INFO - 'install', 09:33:30 INFO - 'run-tests'), 09:33:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:33:30 INFO - 'download_minidump_stackwalk': True, 09:33:30 INFO - 'download_symbols': 'ondemand', 09:33:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:33:30 INFO - 'tooltool.py': '/tools/tooltool.py', 09:33:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:33:30 INFO - '/tools/misc-python/virtualenv.py')}, 09:33:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:33:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:33:30 INFO - 'installer_path': 'installer.exe', 09:33:30 INFO - 'log_level': 'info', 09:33:30 INFO - 'log_name': 'talos', 09:33:30 INFO - 'log_to_console': True, 09:33:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:33:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:33:30 INFO - 'opt_config_files': (), 09:33:30 INFO - 'pip_index': False, 09:33:30 INFO - 'sps_profile': False, 09:33:30 INFO - 'sps_profile_interval': 0, 09:33:30 INFO - 'suite': 'dromaeojs', 09:33:30 INFO - 'system_bits': '32', 09:33:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:33:30 INFO - 'title': 'talos-linux64-ix-060', 09:33:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:33:30 INFO - 'use_talos_json': True, 09:33:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:33:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:33:30 INFO - 'work_dir': 'build'} 09:33:30 INFO - ##### 09:33:30 INFO - ##### Running clobber step. 09:33:30 INFO - ##### 09:33:30 INFO - Running pre-action listener: _resource_record_pre_action 09:33:30 INFO - Running main action method: clobber 09:33:30 INFO - rmtree: /builds/slave/test/build 09:33:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:33:30 INFO - Running post-action listener: _resource_record_post_action 09:33:30 INFO - ##### 09:33:30 INFO - ##### Finished clobber step (success) 09:33:30 INFO - ##### 09:33:30 INFO - ##### 09:33:30 INFO - ##### Running read-buildbot-config step. 09:33:30 INFO - ##### 09:33:30 INFO - Running pre-action listener: _resource_record_pre_action 09:33:30 INFO - Running main action method: read_buildbot_config 09:33:30 INFO - Using buildbot properties: 09:33:30 INFO - { 09:33:30 INFO - "project": "", 09:33:30 INFO - "product": "firefox", 09:33:30 INFO - "script_repo_revision": "production", 09:33:30 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:33:30 INFO - "repository": "", 09:33:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:33:30 INFO - "buildid": "20160620065526", 09:33:30 INFO - "pgo_build": "True", 09:33:30 INFO - "basedir": "/builds/slave/test", 09:33:30 INFO - "buildnumber": 304, 09:33:30 INFO - "slavename": "talos-linux64-ix-060", 09:33:30 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:33:30 INFO - "platform": "ubuntu64_hw", 09:33:30 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:33:30 INFO - "branch": "mozilla-aurora", 09:33:30 INFO - "repo_path": "releases/mozilla-aurora", 09:33:30 INFO - "revision": "804b78a09df71fb081761064750cbbc6fc47611c", 09:33:30 INFO - "stage_platform": "linux64-pgo", 09:33:30 INFO - "builduid": "db123a3e0e9f4b6a8c8f8e53ac4852be", 09:33:30 INFO - "slavebuilddir": "test" 09:33:30 INFO - } 09:33:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 09:33:30 INFO - Running post-action listener: _resource_record_post_action 09:33:30 INFO - ##### 09:33:30 INFO - ##### Finished read-buildbot-config step (success) 09:33:30 INFO - ##### 09:33:30 INFO - ##### 09:33:30 INFO - ##### Running download-and-extract step. 09:33:30 INFO - ##### 09:33:30 INFO - Running pre-action listener: _resource_record_pre_action 09:33:30 INFO - Running main action method: download_and_extract 09:33:30 INFO - mkdir: /builds/slave/test/build/tests 09:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:30 INFO - https://queue.taskcluster.net/v1/task/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:33:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:33:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:33:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/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 09:33:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/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 09:33:31 INFO - Downloaded 1361 bytes. 09:33:31 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:33:31 INFO - Using the following test package requirements: 09:33:31 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 09:33:31 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:33:31 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:33:31 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:33:31 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:33:31 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:33:31 INFO - u'jsshell-linux-x86_64.zip'], 09:33:31 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:33:31 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:33:31 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 09:33:31 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:33:31 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:33:31 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:33:31 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:33:31 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:33:31 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:33:31 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:33:31 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:33:31 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:33: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')]} 09:33:31 INFO - https://queue.taskcluster.net/v1/task/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:33:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:33:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:33:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/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 09:33:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/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 09:33:34 INFO - Downloaded 24473694 bytes. 09:33:34 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'] 09:33:34 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 09:33:35 INFO - Return code: 0 09:33:35 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 09:33:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:35 INFO - https://queue.taskcluster.net/v1/task/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:33:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:33:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:33:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/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 09:33:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/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 09:33:43 INFO - Downloaded 24473694 bytes. 09:33:43 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'] 09:33:43 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 09:33:45 INFO - Return code: 0 09:33:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:45 INFO - https://queue.taskcluster.net/v1/task/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:33:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 09:33:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 09:33:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/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 09:33:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/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 09:33:46 INFO - Downloaded 10833369 bytes. 09:33:46 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'] 09:33:46 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 09:33:46 INFO - Return code: 0 09:33:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:46 INFO - https://queue.taskcluster.net/v1/task/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:33:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:33:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:33:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/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 09:33:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X3lQo7cmR8ClepaUaTebYA/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 09:33:48 INFO - Downloaded 63609650 bytes. 09:33:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:33:48 INFO - mkdir: /builds/slave/test/properties 09:33:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:33:48 INFO - Writing to file /builds/slave/test/properties/build_url 09:33:48 INFO - Contents: 09:33:48 INFO - build_url:https://queue.taskcluster.net/v1/task/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:33:49 INFO - Running post-action listener: _resource_record_post_action 09:33:49 INFO - Running post-action listener: set_extra_try_arguments 09:33:49 INFO - ##### 09:33:49 INFO - ##### Finished download-and-extract step (success) 09:33:49 INFO - ##### 09:33:49 INFO - ##### 09:33:49 INFO - ##### Running populate-webroot step. 09:33:49 INFO - ##### 09:33:49 INFO - Running pre-action listener: _resource_record_pre_action 09:33:49 INFO - Running main action method: populate_webroot 09:33:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:33:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:33:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:33:49 INFO - '--tppagecycles', 09:33:49 INFO - '7'], 09:33:49 INFO - 'tests': ['tsvgm']}}, 09:33:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:33:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:33:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:33:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:33:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:33:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:33:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:49 INFO - 'talos_options': ['--disable-e10s'], 09:33:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:33:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:33:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:49 INFO - 'talos_options': ['--disable-e10s'], 09:33:49 INFO - 'tests': ['damp', 'tps']}, 09:33:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:49 INFO - 'tests': ['damp', 'tps']}, 09:33:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:33:49 INFO - 'tests': ['dromaeo_dom']}, 09:33:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:33:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:33:49 INFO - 'tests': ['a11yr', 09:33:49 INFO - 'ts_paint', 09:33:49 INFO - 'tpaint', 09:33:49 INFO - 'sessionrestore', 09:33:49 INFO - 'sessionrestore_no_auto_restore', 09:33:49 INFO - 'tabpaint']}, 09:33:49 INFO - 'other-e10s': {'tests': ['a11yr', 09:33:49 INFO - 'ts_paint', 09:33:49 INFO - 'tpaint', 09:33:49 INFO - 'sessionrestore', 09:33:49 INFO - 'sessionrestore_no_auto_restore', 09:33:49 INFO - 'tabpaint']}, 09:33:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:33:49 INFO - 'ts_paint', 09:33:49 INFO - 'tpaint', 09:33:49 INFO - 'sessionrestore', 09:33:49 INFO - 'sessionrestore_no_auto_restore', 09:33:49 INFO - 'tabpaint']}, 09:33:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:33:49 INFO - 'ts_paint', 09:33:49 INFO - 'tpaint', 09:33:49 INFO - 'sessionrestore', 09:33:49 INFO - 'sessionrestore_no_auto_restore', 09:33:49 INFO - 'tabpaint']}, 09:33:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:33:49 INFO - 'tests': ['a11yr', 09:33:49 INFO - 'ts_paint', 09:33:49 INFO - 'tpaint', 09:33:49 INFO - 'sessionrestore', 09:33:49 INFO - 'sessionrestore_no_auto_restore', 09:33:49 INFO - 'tabpaint']}, 09:33:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:33:49 INFO - 'tests': ['a11yr', 09:33:49 INFO - 'ts_paint', 09:33:49 INFO - 'tpaint', 09:33:49 INFO - 'sessionrestore', 09:33:49 INFO - 'sessionrestore_no_auto_restore', 09:33:49 INFO - 'tabpaint']}, 09:33:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:33:49 INFO - 'tests': ['tsvgx', 09:33:49 INFO - 'tsvgr_opacity', 09:33:49 INFO - 'tart', 09:33:49 INFO - 'tscrollx', 09:33:49 INFO - 'cart']}, 09:33:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:33:49 INFO - 'tsvgr_opacity', 09:33:49 INFO - 'tart', 09:33:49 INFO - 'tscrollx', 09:33:49 INFO - 'cart']}, 09:33:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:49 INFO - 'talos_options': ['--disable-e10s'], 09:33:49 INFO - 'tests': ['tp5o']}, 09:33:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:49 INFO - 'tests': ['tp5o']}, 09:33:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:49 INFO - 'talos_options': ['--disable-e10s', 09:33:49 INFO - '--xperf_path', 09:33:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:33:49 INFO - 'tests': ['tp5n']}, 09:33:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:49 INFO - 'talos_options': ['--xperf_path', 09:33:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:33:49 INFO - 'tests': ['tp5n']}}, 09:33:49 INFO - 'talos.zip': {'path': '', 09:33:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:33:49 INFO - Running post-action listener: _resource_record_post_action 09:33:49 INFO - ##### 09:33:49 INFO - ##### Finished populate-webroot step (success) 09:33:49 INFO - ##### 09:33:49 INFO - ##### 09:33:49 INFO - ##### Running create-virtualenv step. 09:33:49 INFO - ##### 09:33:49 INFO - Running pre-action listener: _resource_record_pre_action 09:33:49 INFO - Running main action method: create_virtualenv 09:33:49 INFO - Creating virtualenv /builds/slave/test/build/venv 09:33:49 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 09:33:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:33:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:33:49 INFO - Using real prefix '/usr' 09:33:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:33:50 INFO - Installing distribute.............................................................................................................................................................................................done. 09:33:51 INFO - Installing pip.................done. 09:33:51 INFO - Return code: 0 09:33:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:33:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f13f10f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2018e20>, '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 0x201c3a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c830>, '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': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', '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 09:33:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:33:52 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 09:33:52 INFO - Using env: {'DISPLAY': ':0', 09:33:52 INFO - 'HOME': '/home/cltbld', 09:33:52 INFO - 'LANG': 'en_US.UTF-8', 09:33:52 INFO - 'LANGUAGE': 'en_US:en', 09:33:52 INFO - 'LOGNAME': 'cltbld', 09:33:52 INFO - 'MAIL': '/var/mail/cltbld', 09:33:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:52 INFO - 'MOZ_NO_REMOTE': '1', 09:33:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:52 INFO - 'NO_EM_RESTART': '1', 09:33:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:52 INFO - 'PWD': '/builds/slave/test', 09:33:52 INFO - 'SHELL': '/bin/bash', 09:33:52 INFO - 'SHLVL': '1', 09:33:52 INFO - 'TERM': 'linux', 09:33:52 INFO - 'TMOUT': '86400', 09:33:52 INFO - 'USER': 'cltbld', 09:33:52 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', 09:33:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:52 INFO - '_': '/tools/buildbot/bin/python'} 09:33:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:52 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 09:33:52 INFO - Cleaning up... 09:33:52 INFO - Return code: 0 09:33:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:33:52 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')]} 09:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:52 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')]} 09:33:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:52 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 0x7f13f10f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2018e20>, '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 0x201c3a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c830>, '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': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', '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 09:33:52 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 09:33:52 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 09:33:52 INFO - Using env: {'DISPLAY': ':0', 09:33:52 INFO - 'HOME': '/home/cltbld', 09:33:52 INFO - 'LANG': 'en_US.UTF-8', 09:33:52 INFO - 'LANGUAGE': 'en_US:en', 09:33:52 INFO - 'LOGNAME': 'cltbld', 09:33:52 INFO - 'MAIL': '/var/mail/cltbld', 09:33:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:52 INFO - 'MOZ_NO_REMOTE': '1', 09:33:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:52 INFO - 'NO_EM_RESTART': '1', 09:33:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:52 INFO - 'PWD': '/builds/slave/test', 09:33:52 INFO - 'SHELL': '/bin/bash', 09:33:52 INFO - 'SHLVL': '1', 09:33:52 INFO - 'TERM': 'linux', 09:33:52 INFO - 'TMOUT': '86400', 09:33:52 INFO - 'USER': 'cltbld', 09:33:52 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', 09:33:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:52 INFO - '_': '/tools/buildbot/bin/python'} 09:33:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:52 INFO - Downloading/unpacking psutil>=3.1.1 09:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:33:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:33:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:33:54 INFO - Installing collected packages: psutil 09:33:54 INFO - Running setup.py install for psutil 09:33:54 INFO - building 'psutil._psutil_linux' extension 09:33:54 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 09:33:55 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 09:33:56 INFO - building 'psutil._psutil_posix' extension 09:33:56 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 09:33:56 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 09:33:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:33:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:33:56 INFO - Successfully installed psutil 09:33:56 INFO - Cleaning up... 09:33:56 INFO - Return code: 0 09:33:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:33:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:56 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 0x7f13f10f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2018e20>, '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 0x201c3a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c830>, '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': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', '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 09:33:56 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 09:33:56 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 09:33:56 INFO - Using env: {'DISPLAY': ':0', 09:33:56 INFO - 'HOME': '/home/cltbld', 09:33:56 INFO - 'LANG': 'en_US.UTF-8', 09:33:56 INFO - 'LANGUAGE': 'en_US:en', 09:33:56 INFO - 'LOGNAME': 'cltbld', 09:33:56 INFO - 'MAIL': '/var/mail/cltbld', 09:33:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:56 INFO - 'MOZ_NO_REMOTE': '1', 09:33:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:56 INFO - 'NO_EM_RESTART': '1', 09:33:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:56 INFO - 'PWD': '/builds/slave/test', 09:33:56 INFO - 'SHELL': '/bin/bash', 09:33:56 INFO - 'SHLVL': '1', 09:33:56 INFO - 'TERM': 'linux', 09:33:56 INFO - 'TMOUT': '86400', 09:33:56 INFO - 'USER': 'cltbld', 09:33:56 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', 09:33:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:56 INFO - '_': '/tools/buildbot/bin/python'} 09:33:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:33:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:33:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:33:58 INFO - Installing collected packages: mozsystemmonitor 09:33:58 INFO - Running setup.py install for mozsystemmonitor 09:33:58 INFO - Successfully installed mozsystemmonitor 09:33:58 INFO - Cleaning up... 09:33:58 INFO - Return code: 0 09:33:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:33:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:58 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 0x7f13f10f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2018e20>, '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 0x201c3a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c830>, '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': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', '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 09:33:58 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 09:33:58 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 09:33:58 INFO - Using env: {'DISPLAY': ':0', 09:33:58 INFO - 'HOME': '/home/cltbld', 09:33:58 INFO - 'LANG': 'en_US.UTF-8', 09:33:58 INFO - 'LANGUAGE': 'en_US:en', 09:33:58 INFO - 'LOGNAME': 'cltbld', 09:33:58 INFO - 'MAIL': '/var/mail/cltbld', 09:33:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:58 INFO - 'MOZ_NO_REMOTE': '1', 09:33:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:58 INFO - 'NO_EM_RESTART': '1', 09:33:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:58 INFO - 'PWD': '/builds/slave/test', 09:33:58 INFO - 'SHELL': '/bin/bash', 09:33:58 INFO - 'SHLVL': '1', 09:33:58 INFO - 'TERM': 'linux', 09:33:58 INFO - 'TMOUT': '86400', 09:33:58 INFO - 'USER': 'cltbld', 09:33:58 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', 09:33:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:58 INFO - '_': '/tools/buildbot/bin/python'} 09:33:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:59 INFO - Downloading/unpacking jsonschema==2.5.1 09:33:59 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 09:33:59 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 09:33:59 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 09:33:59 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 09:33:59 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 09:33:59 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 09:34:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:34:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:34:00 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 09:34:00 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 09:34:00 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 09:34:00 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 09:34:00 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 09:34:00 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 09:34:01 INFO - Downloading functools32-3.2.3-2.tar.gz 09:34:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:34:01 INFO - warning: no files found matching '*.txt' 09:34:01 INFO - no previously-included directories found matching 'build' 09:34:01 INFO - no previously-included directories found matching 'dist' 09:34:01 INFO - no previously-included directories found matching '.git*' 09:34:01 INFO - Installing collected packages: jsonschema, functools32 09:34:01 INFO - Running setup.py install for functools32 09:34:01 INFO - warning: no files found matching '*.txt' 09:34:01 INFO - no previously-included directories found matching 'build' 09:34:01 INFO - no previously-included directories found matching 'dist' 09:34:01 INFO - no previously-included directories found matching '.git*' 09:34:01 INFO - Successfully installed jsonschema functools32 09:34:01 INFO - Cleaning up... 09:34:01 INFO - Return code: 0 09:34:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:34:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:34:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f13f10f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2018e20>, '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 0x201c3a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c830>, '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': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', '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 09:34:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:34:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:34:01 INFO - Using env: {'DISPLAY': ':0', 09:34:01 INFO - 'HOME': '/home/cltbld', 09:34:01 INFO - 'LANG': 'en_US.UTF-8', 09:34:01 INFO - 'LANGUAGE': 'en_US:en', 09:34:01 INFO - 'LOGNAME': 'cltbld', 09:34:01 INFO - 'MAIL': '/var/mail/cltbld', 09:34:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:01 INFO - 'MOZ_NO_REMOTE': '1', 09:34:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:01 INFO - 'NO_EM_RESTART': '1', 09:34:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:01 INFO - 'PWD': '/builds/slave/test', 09:34:01 INFO - 'SHELL': '/bin/bash', 09:34:01 INFO - 'SHLVL': '1', 09:34:01 INFO - 'TERM': 'linux', 09:34:01 INFO - 'TMOUT': '86400', 09:34:01 INFO - 'USER': 'cltbld', 09:34:01 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', 09:34:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:01 INFO - '_': '/tools/buildbot/bin/python'} 09:34:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:01 INFO - Downloading/unpacking blobuploader==1.2.4 09:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:03 INFO - Downloading blobuploader-1.2.4.tar.gz 09:34:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:34:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:34:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:34:04 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 09:34:04 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 09:34:04 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 09:34:04 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 09:34:04 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 09:34:04 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 09:34:04 INFO - Downloading docopt-0.6.1.tar.gz 09:34:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:34:04 INFO - Installing collected packages: blobuploader, requests, docopt 09:34:04 INFO - Running setup.py install for blobuploader 09:34:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:34:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:34:04 INFO - Running setup.py install for requests 09:34:04 INFO - Running setup.py install for docopt 09:34:04 INFO - Successfully installed blobuploader requests docopt 09:34:04 INFO - Cleaning up... 09:34:04 INFO - Return code: 0 09:34:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:34:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:34:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:04 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 0x7f13f10f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2018e20>, '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 0x201c3a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c830>, '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': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', '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 09:34:04 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 09:34:04 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 09:34:04 INFO - Using env: {'DISPLAY': ':0', 09:34:04 INFO - 'HOME': '/home/cltbld', 09:34:04 INFO - 'LANG': 'en_US.UTF-8', 09:34:04 INFO - 'LANGUAGE': 'en_US:en', 09:34:04 INFO - 'LOGNAME': 'cltbld', 09:34:04 INFO - 'MAIL': '/var/mail/cltbld', 09:34:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:04 INFO - 'MOZ_NO_REMOTE': '1', 09:34:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:04 INFO - 'NO_EM_RESTART': '1', 09:34:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:04 INFO - 'PWD': '/builds/slave/test', 09:34:04 INFO - 'SHELL': '/bin/bash', 09:34:04 INFO - 'SHLVL': '1', 09:34:04 INFO - 'TERM': 'linux', 09:34:04 INFO - 'TMOUT': '86400', 09:34:04 INFO - 'USER': 'cltbld', 09:34:04 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', 09:34:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:04 INFO - '_': '/tools/buildbot/bin/python'} 09:34:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:34:05 INFO - Running setup.py (path:/tmp/pip-V8xdgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:34:05 INFO - Running setup.py (path:/tmp/pip-qaBNqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:34:05 INFO - Running setup.py (path:/tmp/pip-hKfDZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:34:05 INFO - Running setup.py (path:/tmp/pip-eYWzjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:34:05 INFO - Running setup.py (path:/tmp/pip-IFSJlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:34:05 INFO - Running setup.py (path:/tmp/pip-QVpDRm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:34:05 INFO - Running setup.py (path:/tmp/pip-tGwn2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:34:05 INFO - Running setup.py (path:/tmp/pip-AZ_IEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:34:05 INFO - Running setup.py (path:/tmp/pip-ZyL2z5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:34:05 INFO - Running setup.py (path:/tmp/pip-UJt3di-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:34:05 INFO - Running setup.py (path:/tmp/pip-cvT1hO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:34:06 INFO - Running setup.py (path:/tmp/pip-TZ3GAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:34:06 INFO - Running setup.py (path:/tmp/pip-Cl3_ta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:34:06 INFO - Running setup.py (path:/tmp/pip-4tlKk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:06 INFO - Running setup.py (path:/tmp/pip-ZjJ03i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:34:06 INFO - Running setup.py (path:/tmp/pip-YgNKtt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:34:06 INFO - Running setup.py (path:/tmp/pip-RwzZh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:34:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:34:06 INFO - Running setup.py install for manifestparser 09:34:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:34:06 INFO - Running setup.py install for mozcrash 09:34:06 INFO - Running setup.py install for mozdebug 09:34:06 INFO - Running setup.py install for mozdevice 09:34:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:34:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:34:06 INFO - Running setup.py install for mozfile 09:34:06 INFO - Running setup.py install for mozhttpd 09:34:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:34:07 INFO - Running setup.py install for mozinfo 09:34:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:34:07 INFO - Running setup.py install for mozInstall 09:34:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:34:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:34:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:34:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:34:07 INFO - Running setup.py install for mozleak 09:34:07 INFO - Running setup.py install for mozlog 09:34:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:34:07 INFO - Running setup.py install for moznetwork 09:34:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:34:07 INFO - Running setup.py install for mozprocess 09:34:07 INFO - Running setup.py install for mozprofile 09:34:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:34:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:34:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:34:07 INFO - Running setup.py install for mozrunner 09:34:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:34:07 INFO - Running setup.py install for mozscreenshot 09:34:08 INFO - Running setup.py install for moztest 09:34:08 INFO - Running setup.py install for mozversion 09:34:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:34:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:34:08 INFO - Cleaning up... 09:34:08 INFO - Return code: 0 09:34:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:08 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')]} 09:34:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:08 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')]} 09:34:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:08 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 0x7f13f10f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2018e20>, '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 0x201c3a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c830>, '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': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', '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 09:34:08 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 09:34:08 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 09:34:08 INFO - Using env: {'DISPLAY': ':0', 09:34:08 INFO - 'HOME': '/home/cltbld', 09:34:08 INFO - 'LANG': 'en_US.UTF-8', 09:34:08 INFO - 'LANGUAGE': 'en_US:en', 09:34:08 INFO - 'LOGNAME': 'cltbld', 09:34:08 INFO - 'MAIL': '/var/mail/cltbld', 09:34:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:08 INFO - 'MOZ_NO_REMOTE': '1', 09:34:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:08 INFO - 'NO_EM_RESTART': '1', 09:34:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:08 INFO - 'PWD': '/builds/slave/test', 09:34:08 INFO - 'SHELL': '/bin/bash', 09:34:08 INFO - 'SHLVL': '1', 09:34:08 INFO - 'TERM': 'linux', 09:34:08 INFO - 'TMOUT': '86400', 09:34:08 INFO - 'USER': 'cltbld', 09:34:08 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', 09:34:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:08 INFO - '_': '/tools/buildbot/bin/python'} 09:34:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:34:08 INFO - Running setup.py (path:/tmp/pip-IGvN6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:34:08 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)) 09:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:34:08 INFO - Running setup.py (path:/tmp/pip-o4SVbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:34:08 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)) 09:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:34:08 INFO - Running setup.py (path:/tmp/pip-2ZV41s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:34:08 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)) 09:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:34:08 INFO - Running setup.py (path:/tmp/pip-NjX96J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:34:08 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)) 09:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:34:08 INFO - Running setup.py (path:/tmp/pip-tUd_Lk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:34:08 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)) 09:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:34:08 INFO - Running setup.py (path:/tmp/pip-hHDSvW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:34:08 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)) 09:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:34:08 INFO - Running setup.py (path:/tmp/pip-YvErgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:34:08 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)) 09:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:34:08 INFO - Running setup.py (path:/tmp/pip-z8_Egg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:34:09 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)) 09:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:34:09 INFO - Running setup.py (path:/tmp/pip-VsTwH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:34:09 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)) 09:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:34:09 INFO - Running setup.py (path:/tmp/pip-1xNRJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:34:09 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)) 09:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:34:09 INFO - Running setup.py (path:/tmp/pip-B42q3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:34:09 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)) 09:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:34:09 INFO - Running setup.py (path:/tmp/pip-OSqvs1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:34:09 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)) 09:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:34:09 INFO - Running setup.py (path:/tmp/pip-rhI9ab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:34:09 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)) 09:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:34:09 INFO - Running setup.py (path:/tmp/pip-bse3pU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:34:09 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)) 09:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:09 INFO - Running setup.py (path:/tmp/pip-5OQw_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:09 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)) 09:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:34:09 INFO - Running setup.py (path:/tmp/pip-tRn4PQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:34:09 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)) 09:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:34:09 INFO - Running setup.py (path:/tmp/pip-7zTRuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:34:09 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)) 09:34:09 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)) 09:34:09 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)) 09:34:09 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)) 09:34:09 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)) 09:34:09 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)) 09:34:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:34:09 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 09:34:09 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 09:34:09 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 09:34:09 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 09:34:09 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 09:34:09 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 09:34:11 INFO - Downloading blessings-1.6.tar.gz 09:34:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:34:11 INFO - Installing collected packages: blessings 09:34:11 INFO - Running setup.py install for blessings 09:34:11 INFO - Successfully installed blessings 09:34:11 INFO - Cleaning up... 09:34:11 INFO - Return code: 0 09:34:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:34:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:34:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:34:12 INFO - Reading from file tmpfile_stdout 09:34:12 INFO - Current package versions: 09:34:12 INFO - argparse == 1.2.1 09:34:12 INFO - blessings == 1.6 09:34:12 INFO - blobuploader == 1.2.4 09:34:12 INFO - docopt == 0.6.1 09:34:12 INFO - functools32 == 3.2.3-2 09:34:12 INFO - jsonschema == 2.5.1 09:34:12 INFO - manifestparser == 1.1 09:34:12 INFO - mozInstall == 1.12 09:34:12 INFO - mozcrash == 0.17 09:34:12 INFO - mozdebug == 0.1 09:34:12 INFO - mozdevice == 0.48 09:34:12 INFO - mozfile == 1.2 09:34:12 INFO - mozhttpd == 0.7 09:34:12 INFO - mozinfo == 0.9 09:34:12 INFO - mozleak == 0.1 09:34:12 INFO - mozlog == 3.2 09:34:12 INFO - moznetwork == 0.27 09:34:12 INFO - mozprocess == 0.22 09:34:12 INFO - mozprofile == 0.28 09:34:12 INFO - mozrunner == 6.11 09:34:12 INFO - mozscreenshot == 0.1 09:34:12 INFO - mozsystemmonitor == 0.3 09:34:12 INFO - moztest == 0.7 09:34:12 INFO - mozversion == 1.4 09:34:12 INFO - psutil == 3.1.1 09:34:12 INFO - requests == 1.2.3 09:34:12 INFO - wsgiref == 0.1.2 09:34:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:34:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:12 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 0x7f13f10f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2018e20>, '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 0x201c3a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c830>, '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': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', '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 09:34:12 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 09:34:12 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 09:34:12 INFO - Using env: {'DISPLAY': ':0', 09:34:12 INFO - 'HOME': '/home/cltbld', 09:34:12 INFO - 'LANG': 'en_US.UTF-8', 09:34:12 INFO - 'LANGUAGE': 'en_US:en', 09:34:12 INFO - 'LOGNAME': 'cltbld', 09:34:12 INFO - 'MAIL': '/var/mail/cltbld', 09:34:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:12 INFO - 'MOZ_NO_REMOTE': '1', 09:34:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:12 INFO - 'NO_EM_RESTART': '1', 09:34:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:12 INFO - 'PWD': '/builds/slave/test', 09:34:12 INFO - 'SHELL': '/bin/bash', 09:34:12 INFO - 'SHLVL': '1', 09:34:12 INFO - 'TERM': 'linux', 09:34:12 INFO - 'TMOUT': '86400', 09:34:12 INFO - 'USER': 'cltbld', 09:34:12 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', 09:34:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:12 INFO - '_': '/tools/buildbot/bin/python'} 09:34:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:12 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)) 09:34:12 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)) 09:34:12 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)) 09:34:12 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)) 09:34:12 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)) 09:34:12 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)) 09:34:12 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)) 09:34:12 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)) 09:34:12 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)) 09:34:12 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)) 09:34:12 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)) 09:34:12 INFO - Cleaning up... 09:34:12 INFO - Return code: 0 09:34:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:34:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f13f10f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2018e20>, '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 0x201c3a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c830>, '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': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', '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 09:34:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:34:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:34:12 INFO - Using env: {'DISPLAY': ':0', 09:34:12 INFO - 'HOME': '/home/cltbld', 09:34:12 INFO - 'LANG': 'en_US.UTF-8', 09:34:12 INFO - 'LANGUAGE': 'en_US:en', 09:34:12 INFO - 'LOGNAME': 'cltbld', 09:34:12 INFO - 'MAIL': '/var/mail/cltbld', 09:34:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:12 INFO - 'MOZ_NO_REMOTE': '1', 09:34:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:12 INFO - 'NO_EM_RESTART': '1', 09:34:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:12 INFO - 'PWD': '/builds/slave/test', 09:34:12 INFO - 'SHELL': '/bin/bash', 09:34:12 INFO - 'SHLVL': '1', 09:34:12 INFO - 'TERM': 'linux', 09:34:12 INFO - 'TMOUT': '86400', 09:34:12 INFO - 'USER': 'cltbld', 09:34:12 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', 09:34:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:12 INFO - '_': '/tools/buildbot/bin/python'} 09:34:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:34:12 INFO - Cleaning up... 09:34:12 INFO - Return code: 0 09:34:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:34:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f13f10f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2018e20>, '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 0x201c3a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c830>, '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': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', '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 09:34:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:34:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:34:12 INFO - Using env: {'DISPLAY': ':0', 09:34:12 INFO - 'HOME': '/home/cltbld', 09:34:12 INFO - 'LANG': 'en_US.UTF-8', 09:34:12 INFO - 'LANGUAGE': 'en_US:en', 09:34:12 INFO - 'LOGNAME': 'cltbld', 09:34:12 INFO - 'MAIL': '/var/mail/cltbld', 09:34:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:12 INFO - 'MOZ_NO_REMOTE': '1', 09:34:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:12 INFO - 'NO_EM_RESTART': '1', 09:34:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:12 INFO - 'PWD': '/builds/slave/test', 09:34:12 INFO - 'SHELL': '/bin/bash', 09:34:12 INFO - 'SHLVL': '1', 09:34:12 INFO - 'TERM': 'linux', 09:34:12 INFO - 'TMOUT': '86400', 09:34:12 INFO - 'USER': 'cltbld', 09:34:12 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', 09:34:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:12 INFO - '_': '/tools/buildbot/bin/python'} 09:34:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:12 INFO - Downloading/unpacking flake8 09:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:34:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:34:14 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 09:34:14 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 09:34:14 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 09:34:14 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 09:34:14 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 09:34:14 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 09:34:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:34:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:34:14 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 09:34:14 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 09:34:14 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 09:34:14 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 09:34:14 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 09:34:14 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 09:34:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:34:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:34:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:34:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:34:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:34:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:34:15 INFO - no previously-included directories found matching 'docs/_build' 09:34:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:34:15 INFO - Running setup.py install for pep8 09:34:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:34:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:34:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:34:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:34:15 INFO - no previously-included directories found matching 'docs/_build' 09:34:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:34:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:34:15 INFO - Cleaning up... 09:34:15 INFO - Return code: 0 09:34:15 INFO - Running post-action listener: _resource_record_post_action 09:34:15 INFO - Running post-action listener: _start_resource_monitoring 09:34:15 INFO - Starting resource monitoring. 09:34:15 INFO - ##### 09:34:15 INFO - ##### Finished create-virtualenv step (success) 09:34:15 INFO - ##### 09:34:15 INFO - ##### 09:34:15 INFO - ##### Running install step. 09:34:15 INFO - ##### 09:34:15 INFO - Running pre-action listener: _resource_record_pre_action 09:34:15 INFO - Running main action method: install 09:34:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:34:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:34:15 INFO - Reading from file tmpfile_stdout 09:34:15 INFO - Detecting whether we're running mozinstall >=1.0... 09:34:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:34:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:34:15 INFO - Reading from file tmpfile_stdout 09:34:15 INFO - Output received: 09:34:15 INFO - Usage: mozinstall [options] installer 09:34:15 INFO - Options: 09:34:15 INFO - -h, --help show this help message and exit 09:34:15 INFO - -d DEST, --destination=DEST 09:34:15 INFO - Directory to install application into. [default: 09:34:15 INFO - "/builds/slave/test"] 09:34:15 INFO - --app=APP Application being installed. [default: firefox] 09:34:15 INFO - mkdir: /builds/slave/test/build/application 09:34:15 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'] 09:34:15 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 09:34:31 INFO - Reading from file tmpfile_stdout 09:34:31 INFO - Output received: 09:34:31 INFO - /builds/slave/test/build/application/firefox/firefox 09:34:31 INFO - Running post-action listener: _resource_record_post_action 09:34:31 INFO - ##### 09:34:31 INFO - ##### Finished install step (success) 09:34:31 INFO - ##### 09:34:31 INFO - ##### 09:34:31 INFO - ##### Running run-tests step. 09:34:31 INFO - ##### 09:34:31 INFO - Running pre-action listener: _resource_record_pre_action 09:34:31 INFO - Running main action method: run_tests 09:34:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:34:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:34:31 INFO - Python 2.7.3 09:34:31 INFO - Return code: 0 09:34:31 INFO - grabbing minidump binary from tooltool 09:34: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')]} 09:34:31 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 0x2018e20>, '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 0x201c3a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c830>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:34:31 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 09:34:31 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 09:34:31 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 09:34:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:34:32 INFO - Return code: 0 09:34:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:34:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:34:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:34:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:34:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:34:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:34:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:34:33 INFO - Return code: 0 09:34:33 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/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 09:34:33 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/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-060 --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 09:34:33 INFO - Using env: {'DISPLAY': ':0', 09:34:33 INFO - 'HOME': '/home/cltbld', 09:34:33 INFO - 'LANG': 'en_US.UTF-8', 09:34:33 INFO - 'LANGUAGE': 'en_US:en', 09:34:33 INFO - 'LOGNAME': 'cltbld', 09:34:33 INFO - 'MAIL': '/var/mail/cltbld', 09:34:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:34:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:34:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:33 INFO - 'MOZ_NO_REMOTE': '1', 09:34:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:34:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:33 INFO - 'NO_EM_RESTART': '1', 09:34:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:33 INFO - 'PWD': '/builds/slave/test', 09:34:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:34:33 INFO - 'SHELL': '/bin/bash', 09:34:33 INFO - 'SHLVL': '1', 09:34:33 INFO - 'TERM': 'linux', 09:34:33 INFO - 'TMOUT': '86400', 09:34:33 INFO - 'USER': 'cltbld', 09:34:33 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580', 09:34:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:33 INFO - '_': '/tools/buildbot/bin/python'} 09:34:33 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/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 09:34:33 INFO - mozversion application_buildid: 20160620065526 09:34:33 INFO - mozversion application_changeset: 804b78a09df71fb081761064750cbbc6fc47611c 09:34:33 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:34:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:34:33 INFO - mozversion application_name: Firefox 09:34:33 INFO - mozversion application_remotingname: firefox-dev 09:34:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:34:33 INFO - mozversion application_vendor: Mozilla 09:34:33 INFO - mozversion application_version: 49.0a2 09:34:33 INFO - mozversion platform_buildid: 20160620065526 09:34:33 INFO - mozversion platform_changeset: 804b78a09df71fb081761064750cbbc6fc47611c 09:34:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:34:33 INFO - mozversion platform_version: 49.0a2 09:34:33 INFO - using testdate: 1466440473 09:34:33 INFO - actual date: 1466440473 09:34:33 INFO - starting webserver on 'localhost:51598' 09:34:33 INFO - SUITE-START | Running 2 tests 09:34:33 INFO - TEST-START | dromaeo_css 09:34:33 INFO - operating with platform_type : linux_ 09:34:33 INFO - Initialising browser for dromaeo_css test... 09:34:33 INFO - TEST-INFO | started process 3329 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAXVHmC/profile http://localhost:51598/getInfo.html) 09:34:37 INFO - PROCESS | 3329 | __metrics Screen width/height:1600/1200 09:34:37 INFO - PROCESS | 3329 | colorDepth:24 09:34:37 INFO - PROCESS | 3329 | Browser inner width/height: 1024/697 09:34:37 INFO - PROCESS | 3329 | __metrics 09:34:38 ERROR - PROCESS | 3329 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:34:38 INFO - PROCESS | 3329 | console.error: PushService: 09:34:38 INFO - PROCESS | 3329 | stateChangeProcessEnqueue: Error transitioning state 09:34:38 INFO - PROCESS | 3329 | UnknownError 09:34:39 INFO - TEST-INFO | 3329: exit 0 09:34:39 INFO - Browser initialized. 09:34:39 INFO - Running cycle 1/1 for dromaeo_css test... 09:34:39 INFO - TEST-INFO | started process 3425 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAXVHmC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:34:51 INFO - PROCESS | 3425 | RSS: Main: 145518592 09:34:51 INFO - PROCESS | 3425 | 09:35:42 INFO - PROCESS | 3425 | 1466440542153 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] 09:37:50 INFO - PROCESS | 3425 | Cycle 1(1): loaded http://localhost:51598/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51598/tests/dromaeo/cssquery-ext.html) 09:37:52 INFO - PROCESS | 3425 | RSS: Main: 253894656 09:37:52 INFO - PROCESS | 3425 | 09:40:54 INFO - PROCESS | 3425 | Cycle 1(1): loaded http://localhost:51598/tests/dromaeo/cssquery-ext.html (next: http://localhost:51598/tests/dromaeo/cssquery-jquery.html) 09:40:55 INFO - PROCESS | 3425 | RSS: Main: 234733568 09:40:55 INFO - PROCESS | 3425 | 09:43:57 INFO - PROCESS | 3425 | Cycle 1(1): loaded http://localhost:51598/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51598/tests/dromaeo/cssquery-mootools.html) 09:43:57 INFO - PROCESS | 3425 | RSS: Main: 232157184 09:43:57 INFO - PROCESS | 3425 | 09:46:59 INFO - PROCESS | 3425 | Cycle 1(1): loaded http://localhost:51598/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51598/tests/dromaeo/cssquery-prototype.html) 09:47:00 INFO - PROCESS | 3425 | RSS: Main: 227504128 09:47:00 INFO - PROCESS | 3425 | 09:50:02 INFO - PROCESS | 3425 | Cycle 1(1): loaded http://localhost:51598/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51598/tests/dromaeo/cssquery-yui.html) 09:50:03 INFO - PROCESS | 3425 | RSS: Main: 212869120 09:50:03 INFO - PROCESS | 3425 | 09:53:05 INFO - PROCESS | 3425 | Cycle 1(1): loaded http://localhost:51598/tests/dromaeo/cssquery-yui.html (next: http://localhost:51598/tests/dromaeo/cssquery-dojo.html) 09:53:06 INFO - PROCESS | 3425 | RSS: Main: 260485120 09:53:06 INFO - PROCESS | 3425 | 09:53:06 INFO - PROCESS | 3425 | __start_tp_report 09:53:06 INFO - PROCESS | 3425 | _x_x_mozilla_page_load 09:53:06 INFO - PROCESS | 3425 | _x_x_mozilla_page_load_details 09:53:06 INFO - PROCESS | 3425 | |i|pagename|runs| 09:53:06 INFO - PROCESS | 3425 | |0;dojo.html;3395.881144159954;3422.6748458896413;3430.3139372125574;3451.154467155547;3471.7743346682464;5618.662880227184;5697.544196464283;5719.227904232927;5720.856978575538;5742.971285143579;5588.748506317215;5638.689872057035;5640.0693885508945;5642.858928526789;5646.887062258752;5017.774200161001;5070.974645126769;5108.2848401223855;5109.3613298337705;5150.098732721777;5042.899142017158;5100.744962751866;5113.642045056848;5119.897602047976;5154.974225128869;5071.695698258116;5094.2103973884105;5164.896702065957;5168.405633352157;5169.345809341013;5064.366954130734;5071.239314102877;5082.36470441193;5089.389273287229;5188.325517682696;4926.778294976734;4989.55094041538;5010.123228435009;5034.773435195425;5056.949430505685;5547;5559.63862348946;5574.916376254359;5592.972035139839;5603.467670571289;5862.267216597926;6017.789377371771;6026.276846778371;6035.124906888476;6039.275286965592;6009.248843894513;6065.211522502091;6074.392560743934;6123.112148738453;6140.785072522441;5755.482006619424;5761.164631128505;5763.88472230553;5765.682887441189;5767.480926716616;5906.852328691817;6182.938170618326;6266.404691554295;6266.749330026839;6267.811965641038;5766.250387449605;5829.387914268941;5829.88340233193;5908.527317814564;5910.936031514369;5926;5927.140564618193;5932.703364831741;5950.642961422329;5988.9401105989255;5838.38696936824;5933.436323549256;5941.346451890288;5942.64344139353;5953.404659534012;6001.5198784097165;6017.548683848729;6073.30157031947;6077.696115194222;6113.144159817629;4601.562851529099;4612.723236605814;4626.467956185014;4627.699199552018;4641.327007583882;4042.959570404317;4056.9391459128137;4105.34314509683;4142.984968682632;4147;5708.40061793514;5716.599838011382;5723.969685456658;5749.85625359361;5755.85610359736;6093.482054025458;6101.877962440815;6110.083487476825;6114.816555503342;6130.111133885651;5493.752781124859;5630.943690563124;5663.461971112738;5680.573956953162;5736.397678243808;5191.273221748958;5227.26818245446;5228.1112210923475;5229.215617657382;5233.110371236823;6215.844603884939;6217.036359364306;6228.252609686776;6228.688565571794;6232.345603711545;5840.94159058404;5930.911036334544;6020;6036.63780173191;6104.908426373519;6253.687315634109;6289.40250676185;6295.1186833842385;6296.811095667137;6298.433141017331;5815.273090863642;6036.486898613492;6044.486218671462;6045.183900173421;6047.848803779941;5973.223480947449;6003.969980150071;6034.547408944259;6083.1483592298;6084.756609735561;5935.258092738408;5974.61165024272;5978.043513037893;5978.940210597838;5979.970100149471;5661.773529058792;5772.5381969441505;5894.322152952293;5931.051031834886;5955.553333499918;5677.233573467529;5808.5062769665055;5817.76728930838;5817.7963771267805;5845.941540584539;5921.526277897844;5967.880642387041;5977.760889564543;5978.940210597838;5979.940200598112;5639.520640745583;5774.307083150049;5817.418258174149;5820.912686309624;5823.126531020381 09:53:06 INFO - PROCESS | 3425 | |1;ext.html;602.8462742000789;643.5688088980382;648.4326214562258;655.2366493035614;655.9963256217989;33566.16084597883;46549.53450465494;47189.52810471903;47257.763711181484;47300.526994730084;48294.758526207406;48912.51087489124;48958.26562601564;49029.75485122569;49037.50962490374;8470.576471176448;9073.727788166349;9233.169014788667;10809.702835659731;11051.894810518965;10894.67315980522;10976.890231097706;10983.670489885317;10991.285566438195;11008.064314533274;11100.55597776092;11141.220114591983;11171.106311495101;11173.776524469506;11178.44107794611;8475.533845638487;10837.403942783105;11028.669139925814;11059.557617695282;11334.093272538177;10956.34261944286;11022.173336999676;11025.173112016551;11026.558937642485;11031;44650;44983.550164498265;45040.549594503966;45165.548344516465;45166.32250516245;3698.2418604186346;3700.4449332600057;3719.0888232382968;4043.980154080108;4225.91548169038;34800.47799283013;34880.47679284813;35152.29695406103;35268.47097293543;36157.81921090404;4340.23795514157;4404.163208990281;4412.294032954712;4421.734695918255;4434.556544345572;355820.99999999744;356980;357242;357675;359367;37112.62887371146;60454.18637440884;61806.381936180966;62064;62092;38131.42802857959;40210.19579608391;40248;40275;40338;15672.529824105295;16976.236069376908;17144.228509717093;17156.914215429093;17159.399421020455;13836;14264.786028209584;14311.78532322016;14515.709685806225;14669.119852808652;8898.86651700225;8970.372073954892;8988.640454381752;9033.548322583845;9036.32227582934;3412.658734126617;3413.880514182041;3426.297608990151;3444.431668774624;3451.7583769135917;976.8632391465201;1016.974575635615;1043.5773511727762;1122.8428020077197;1198.789222884875;33458.159801211164;34041;34090.48864267038;34256.65743342585;34389.48415773766;1142.9657110286705;1155.867075286336;1172.9296242225494;1215.7629262293945;1249.1318533619228;17607.647847042987;17915.641687166182;17932.731009034876;17940.730889036677;18021.639567208582;554699;559472;561999;648042;655436;65915.34084659092;66301.34246643777;66511.33488665149;66675;66809.66595166993;1354.966125846842;1464.9598784862653;1467.8018467506963;1499.0630855715178;1522.5736793697783;30407.695923040486;33019.66980330166;34059;34172.48741268933;34437.65562344345;51876.48123518867;52580.737096314275;52707.73646131745;52722.4183274487;52749.736251320035;461177;462994;464815;465224;466638;30743;30763;31558.211044724067;31598.684013159575;31745.8412707935;51890.74054629703;52016.739916300176;52125.478745212065;52603.73698131485;53104.203436947704;42407.36388954106;42939.785301073294;43416.13167736564;44176.77911610421;44258.77870610626;42164.36753448762;42532.5746742541;43190.56809431865;43351.13297734091;43375.13249735051;42653.57346426496;43644.345334819365;43782;44257.11485770331;44479.777601113085;37873.24253514969;38605.613943860204;38740.225195496496;38857.61142388653;39320.80339598284;29405.411891761618;29455.55816662709;29476.410471790874;29489.726655205657;29834.254143646584 09:53:06 INFO - PROCESS | 3425 | |2;jquery.html;409.4983645034832;411.4507132977475;414.2398698388459;414.94605701258837;425.22608851889555;19176.61646767064;20582;20660.793392066094;20923.476913077182;21285.36143915681;20033.799662003414;20035.19859205634;20470.488237794063;20834.791652083473;20919;5699.829005129853;5879.029960056586;5915.911261331062;5926.585139040269;5926.822195334125;4310.310350343945;5869.618474799146;5926.792562260322;6154.50763938886;6491.22105347359;6513.250976137758;6514.739410423577;6549.574277671936;6563.048357988089;6563.212414510265;6261.780837670683;6491.642959637218;6494.31809659986;6494.577852439576;6503.024546318066;3889.727719059667;5415.729213539307;5718.34239062509;5720.1991721159075;5736.971315143439;23735;24162.516749665083;24363.26910192697;24656.383590410205;24720.25839224808;2198.4393979535275;2322.036354912709;2360.6459031145464;2760.9309767255777;3067.3098552825436;10948.945255273753;11797.997170240455;13888.305584720723;14346.139231646135;15410.075395476308;2026.7567891853073;2029.1781828359537;2043.2644248070683;2073.782252863458;2115.5557332959943;83795.58102209511;88126.55936720276;88472.5576372114;88933.55533222356;89175;11339.603113890975;11414;11445.427728613537;11486.770264594828;11663.708407289716;16046.67906641884;16088.356465741474;16129.596760081093;16167;16199.919000404923;8544.060153383123;8571.485710857243;8653.870191947126;8816.559172041501;8944.284457243404;8569.742907712778;8612.224899758927;8691.91308086911;8831.64673413069;8840;4605.585497305258;4715.834945776881;4879.951200488021;4913.459519452857;4951.529604687549;1810.9366172183911;1840.0155916584563;1975.9012049397472;1979.2676709617247;2008.4978755311172;946.2808265718104;977.3012296208245;992.270681049428;1067.114295135036;1105.9889401105886;19465.4160375189;19856.90071549633;19930.30243941484;19932.90033549823;20090.89954550218;1018.7758693087509;1024.8316918712674;1070.272214893864;1071.5338827610015;1103.6358001859367;10754.247202695737;11399.715007124722;11455.255408398594;11487.25332853362;11508.194426390073;451341;459725;460382;474384;540978;92339;92942;93265.5336723312;98461.50769246108;99693.6167668843;484.4646665434644;510.38753495805923;510.4461659099918;985.8225519406433;1084.2247792828166;10970.177236707119;11074.889251107386;11498;11521.884781152416;11638.06895448379;31705.365892681555;42965;44449.55550444454;44610.18575913294;45911.77044114758;59619;61571;63670.36329636644;64226;64366.678166608865;8629.525376104375;8845.469271843709;8921.286297096347;9091.90908090937;9208.723738287594;13180.868191318348;13869.167849929037;13901.72196556043;14029.469245267246;14069.733723964482;11588.807433764247;11712.590059347882;12115.454804533994;12279.140460167702;12488.313142777235;11416;11698.532058717556;12038.638840834788;12317.815232771336;12392.938035309766;11433.542658293907;11863.932246097893;12033.157678962389;12083.637490875288;12270.32513211782;8759.386842920934;8815.603297851483;9031.819363612822;9128.817423651622;9273.907260927574;9016.549172541478;11861.466234019317;12007.579734709243;12066.879331206927;12067.75864482723 09:53:06 INFO - PROCESS | 3425 | |3;mootools.html;1381.4888491258237;1543.529223586806;1583.5170273066715;1776.3161182944561;1884.0108942805034;8941.91058089419;9388.342816002883;9411.058894110585;9590.664326748565;9605.807883842319;9199.034101419355;9204.447733136016;9205.493697846632;9207.309451791109;9281.675141370053;2498.487809998946;2528.84826910386;2529.215943057649;2542.580474221751;2544.860032698201;2450.3384086296687;2558.0790915270486;2561.2700380391593;2562.397836508419;2562.487502499498;2382.285314405671;2386.606209975361;2430.064425196303;2432.9513409731794;2434.111549284516;2418.9637155442592;2429.6112621980487;2443.3525115844313;2454.5459090068393;2460.20043485866;2395.3652282145244;2400.459896523286;2410.0721222329353;2415.4082249848725;2438.2807071913826;8769.824603507957;8899.199072083544;8914.821703565956;9188.63245470187;9555.140037396668;5661.943380566183;5783.363829978699;5794.68129252891;5794.82615521535;5801.187833703285;3595.658412450813;3604.4953706481115;3649.033006253345;3654.214343916071;3661.5240018797385;1241.838560987075;1253.642711827134;1293.864144264848;1313.894888408925;1325.5493132334957;13009.609711708665;13034.739305213938;13088.411021504055;13091.934540327333;13102.08285420021;4788.609589108941;4804.2073057945745;4843.297721830315;4896.608271338285;4913.950860491349;10139.290249682452;10187.592496300065;10207.9489602553;10214.591416343263;10219.846702299472;5694.06048002075;5741;5744.626599271034;5750.051241545182;5750.424957504216;4756.881077973009;4786.880327991758;5202.037623039674;5248.606354523426;5321.148616221386;4016.8393264269685;4035.878923632296;4078.8368465261055;4090.3250963591267;4149.688773342012;1130.5364800431782;1135.1429670598811;1138.7608602193632;1139.6694958461953;1152.348922858599;734.7354952217197;1008.1078245752415;1057.9737654475148;1089.8910108989196;1092.1481244629201;6092.603980741238;6360.84097897547;6425.164728585254;6448.613083215022;6449.677516124175;503.6172508893267;506.76435457512434;526.1844141580535;558.288182567222;672.314239475732;4332.805023773874;4454.354118652777;4471.105778844244;4473.127740090651;4475.888102797391;11230.550777968952;11537.077033837273;11541.653750387502;11573.710657233636;11605.76788464226;11077.169212308949;11553.942230288794;11612.477438515287;11647.068234541217;11674.124440666814;8438.873416898752;8533.317334613092;8599.78500537479;8611;8655.610497527627;6353.904691429725;6494.188226471691;6535.183102112236;6607.405333519814;6630.5027122965;10486.056254937092;10732.892671073501;10800.40597767099;10859.728506787078;10959.232853700483;9465.66870159541;11062.557497700001;11065.668029959113;11070.889291106985;11091.833622495507;7972;8237.958810205912;8241.587920603826;8386.412951093365;8388.706395276136;9162.816743664956;9262.39794413361;9393.859092113487;9402.15380615748;9417.199538039069;8024.358051355995;8231.629576669184;8310.501369917823;8361;8368.20502052304;7960.442769006114;8208.876866846882;8314.334853211607;8386.580670966548;8405.831883362176;7448.366888814511;7459;7507.624618769149;7523.924760752322;7532.661030253761;5594.0769772987005;5605.131204663284;5720.570957178145;5749.837589325938;5756.079027355603;5351.919721204107;5434.864128396822;5705.144228365778;5807.535397168132;5832.50423713989 09:53:06 INFO - PROCESS | 3425 | |4;prototype.html;323.0581552897156;339.6668077794656;347.6072038596387;349.7604141163302;350.98947031589046;5497.780088796449;5542.944570554293;5550.306211723535;5574;5597.748101335439;5193.45468725783;5315.62457839272;5436.184572314155;5452.318460192476;5491.533219676322;4266.168097221043;4274.700770946035;4298.871033868973;4310.288802347609;4326.134773045388;4291.91416171678;4293;4305.332673435622;4312.892177695551;4317.805698743548;3940.566537680853;4063.227986682521;4200.789960501978;4253;4255.2978758504805;4162.521310049353;4180.623743863067;4201.558836322203;4226.197022565702;4231.978840105795;3718.4980027696183;3726.3851464508325;3747.0445036515785;3759.2293579816073;3770.717196210268;5182.663126896739;5275.788968441258;5286.5242128208265;5289.365276166846;5354.330708661418;4856.474633190756;4860.1737704590305;4860.756962151878;4881.170201065792;4887.095887260868;5677.290338707661;5727.570432217601;5728.427157284238;5729.624890026405;5737.684427356494;4037.2934736421007;4126.236646220429;4136.937945930783;4146.295129827937;4149.958500414988;5949.940500594982;5969.910451343233;5982.940170598282;5988.371221021817;6008.459238668497;4208.473940757405;4218.472690913636;4222.746635201836;4224.387463817729;4232.449781528381;4074.1647962167685;4270.42349282849;4281.443412356373;4286.721363111388;4287.378330142112;4856.708597484092;4867.9269810952865;4898.951010489921;4913.852584422473;4931.827386041471;5730.455606717439;5791.8552036199435;5795.623284486495;5821.825345239649;5858.472737453564;1387.2300873015574;1413.9858601413855;1425.201886943315;1464.1215270837413;1468.12646475347;925.1396201532505;931.8695382646434;941.3410612571228;948.796008858085;961.0917682789836;4965.627577931669;4984.22744474607;5004.448620927524;5006.89986200274;5014.771381011676;5268.736563171826;5276.736163191825;5287.4976877196605;5300.787968481218;5316.202569614588;4839.854804355875;4983.950160498421;5000.924986125211;5010.872553675394;5019.121653710586;2816.943661126766;2832.7025662305573;2834.4047749972324;2840.6875243723175;2848.4160747046803;5379.757910894019;5380.498068398214;5413.133898576288;5416.241726158341;5470.316210473691;5175.4824517548695;5268.972550352644;5308.681479111266;5325.920111198335;5368.516833485005;5363.597730170253;5364.14173732201;5364.363869019909;5377.919331210035;5406.83779486616;5243.614155559288;5281.683099014072;5286.656367336111;5294.311739473844;5336.813211537733;4954.950450495449;5311.893762124813;5315.920261196008;5334;5336.7598458068705;5327.3074500314715;5429.239906413184;5444.3194600674915;5455.399906010415;5505.587080969023;3837.6354246346555;3862.555806082224;3883.3980732986433;3891.669208117342;3926.8232929517667;4952.2819191217795;5226.869328266824;5229.372475302988;5342.75957581902;5730.71346432685;5671.773129074956;5867.178594996619;5918.437748413894;5926.644401335933;5927.733252003756;5786.3056433228285;5838.591298609056;5881.382454842351;5885.411458854081;5902.793402230901;5550.458715596234;5769.653820770599;5782.913256301243;5786.85532861665;5814.389489103753;5830.387809279964;5865.794697185578;5871.295444546511;5913.29040515141;5935.40645935403;5404.78380864761;5776.826695199151;5802.303723553201;5810.912836307543;5822.0684690449325 09:53:06 INFO - PROCESS | 3425 | |5;yui.html;796.1441450440774;814.7881550796791;837.5686521441462;859.4628357276703;934.3366209990907;15466.536003919873;15652.84347156528;15823.762643560342;15835.524934251976;17650.470485885413;15548.06711597305;15758.133302668348;16018.519444416657;16019.157796853366;16082.115483648395;743.6430513353594;859.3468963587655;880.8634661627458;899.4558292233203;900.455224589124;897.1477096758073;897.2059727141461;899.9145081217307;900.6082354175921;900.9864852027225;952.0527075559828;955.7085089047872;956.4739393333673;957.952102394881;959.6737109382808;772.9033870766154;806.1213277527464;903.7243640689587;942.5522876633613;948.9620415183384;690.0442886602042;691.7129391302603;693.4833549006023;693.552658535247;693.819606902204;16368.345266189457;16377.918110409491;16387;16401.42595009181;16448.095354755485;14735;14841.7773733393;14888.553343399715;15045.097294162275;15245;13302.534411295559;13427.3957671905;13458.461661533625;13472.865271347258;13648.93175534116;4163.3130533461945;4198.076423186894;4203.600657937522;4233.745975241495;4340;77646;79207.81188282238;79583;80096.19903801003;80264;12950.676233094247;13133;13183;13334.666633334244;13390.397432115578;25260.494790104094;25295.494090118464;25349.873250633627;25366.873165634053;25368.238952831445;9533.237341012702;9592.568334424828;9601.951990240004;9611.759706007266;10074.899251007542;5223.381420655948;5245.501677340647;5280.075986702312;5299.417064122943;5421.512063914267;3817.656410922975;3934.016495876031;3934.6065393460426;3937.7046721496004;3938;664.8072059102902;677.383580941341;679.6805501414327;686.8454597715574;694.8089275449191;718.8813845715503;822.4160845799549;839.4585492357395;840.281559266818;847.1063028504987;10865.48301523125;11115.610953616746;11124.944375278072;11275.774484510428;11319.830202546967;690.4821383962028;805.135350318469;830.1449507007792;848.0628905059943;855.39267120344;5173.32746742921;5377.300950876439;5432.375276843213;5512.145617429305;5527.115661494142;69608;74465.6276718613;110297.44851275692;118920;119174;100725.99999999853;101973;102791;103058.48470757749;103213.48393257985;55809;58151.12773308404;83933;89465.55267223623;90359.54820225989;9920.849564548716;10174.236932229964;10185.236107291834;10196.490175491048;10255.538500767334;54355.45644543504;54487.999999998414;54650;56048;56346.7182664084;75237.24762752303;77476.61261693882;78678.6066069666;79350;81625.59187204026;12910.548130815376;13078.476860925457;13163.67090822737;13211.274439422057;13444.462221511421;14177.645558861112;14198.148111113367;14243.928780356031;14607.19660418687;14737.11577305365;13716.451341946211;13839.169649821042;14031.929840350733;14039.368228429541;14365.569032929028;12884.291363975071;13373.799393008918;13461.730765384833;13662.658433539242;13736;13361.26513041792;13521;13863;13875.861241387456;14079.577612671636;11455.369954652575;11698;12011.918927296585;12031.819522706992;12157.878421215675;10169.9491502542;10962.342259464458;10977.890221097687;10995.835062473909;11069.384656872253 09:53:06 INFO - PROCESS | 3425 | __end_tp_report 09:53:06 INFO - PROCESS | 3425 | __start_cc_report 09:53:06 INFO - PROCESS | 3425 | _x_x_mozilla_cycle_collect,901 09:53:06 INFO - PROCESS | 3425 | __end_cc_report 09:53:06 INFO - PROCESS | 3425 | __startTimestamp1466441586117__endTimestamp 09:53:06 INFO - PROCESS | 3425 | 09:53:06 INFO - PROCESS | 3425 | ------- Summary: start ------- 09:53:06 INFO - PROCESS | 3425 | Number of tests: 6 09:53:06 INFO - PROCESS | 3425 | 09:53:06 INFO - PROCESS | 3425 | [#0] dojo.html Cycles:165 Average:5602.13 Median:5815.27 stddev:623.59 (10.7%) stddev-sans-first:601.00 09:53:06 INFO - PROCESS | 3425 | Values: 3395.9 3422.7 3430.3 3451.2 3471.8 5618.7 5697.5 5719.2 5720.9 5743.0 5588.7 5638.7 5640.1 5642.9 5646.9 5017.8 5071.0 5108.3 5109.4 5150.1 5042.9 5100.7 5113.6 5119.9 5155.0 5071.7 5094.2 5164.9 5168.4 5169.3 5064.4 5071.2 5082.4 5089.4 5188.3 4926.8 4989.6 5010.1 5034.8 5056.9 5547.0 5559.6 5574.9 5593.0 5603.5 5862.3 6017.8 6026.3 6035.1 6039.3 6009.2 6065.2 6074.4 6123.1 6140.8 5755.5 5761.2 5763.9 5765.7 5767.5 5906.9 6182.9 6266.4 6266.7 6267.8 5766.3 5829.4 5829.9 5908.5 5910.9 5926.0 5927.1 5932.7 5950.6 5988.9 5838.4 5933.4 5941.3 5942.6 5953.4 6001.5 6017.5 6073.3 6077.7 6113.1 4601.6 4612.7 4626.5 4627.7 4641.3 4043.0 4056.9 4105.3 4143.0 4147.0 5708.4 5716.6 5724.0 5749.9 5755.9 6093.5 6101.9 6110.1 6114.8 6130.1 5493.8 5630.9 5663.5 5680.6 5736.4 5191.3 5227.3 5228.1 5229.2 5233.1 6215.8 6217.0 6228.3 6228.7 6232.3 5840.9 5930.9 6020.0 6036.6 6104.9 6253.7 6289.4 6295.1 6296.8 6298.4 5815.3 6036.5 6044.5 6045.2 6047.8 5973.2 6004.0 6034.5 6083.1 6084.8 5935.3 5974.6 5978.0 5978.9 5980.0 5661.8 5772.5 5894.3 5931.1 5955.6 5677.2 5808.5 5817.8 5817.8 5845.9 5921.5 5967.9 5977.8 5978.9 5979.9 5639.5 5774.3 5817.4 5820.9 5823.1 09:53:06 INFO - PROCESS | 3425 | 09:53:06 INFO - PROCESS | 3425 | [#1] ext.html Cycles:180 Average:63423.13 Median:38002.34 stddev:128469.08 (338.1%) stddev-sans-first:128742.89 09:53:06 INFO - PROCESS | 3425 | Values: 602.8 643.6 648.4 655.2 656.0 33566.2 46549.5 47189.5 47257.8 47300.5 48294.8 48912.5 48958.3 49029.8 49037.5 8470.6 9073.7 9233.2 10809.7 11051.9 10894.7 10976.9 10983.7 10991.3 11008.1 11100.6 11141.2 11171.1 11173.8 11178.4 8475.5 10837.4 11028.7 11059.6 11334.1 10956.3 11022.2 11025.2 11026.6 11031.0 44650.0 44983.6 45040.5 45165.5 45166.3 3698.2 3700.4 3719.1 4044.0 4225.9 34800.5 34880.5 35152.3 35268.5 36157.8 4340.2 4404.2 4412.3 4421.7 4434.6 355821.0 356980.0 357242.0 357675.0 359367.0 37112.6 60454.2 61806.4 62064.0 62092.0 38131.4 40210.2 40248.0 40275.0 40338.0 15672.5 16976.2 17144.2 17156.9 17159.4 13836.0 14264.8 14311.8 14515.7 14669.1 8898.9 8970.4 8988.6 9033.5 9036.3 3412.7 3413.9 3426.3 3444.4 3451.8 976.9 1017.0 1043.6 1122.8 1198.8 33458.2 34041.0 34090.5 34256.7 34389.5 1143.0 1155.9 1172.9 1215.8 1249.1 17607.6 17915.6 17932.7 17940.7 18021.6 554699.0 559472.0 561999.0 648042.0 655436.0 65915.3 66301.3 66511.3 66675.0 66809.7 1355.0 1465.0 1467.8 1499.1 1522.6 30407.7 33019.7 34059.0 34172.5 34437.7 51876.5 52580.7 52707.7 52722.4 52749.7 461177.0 462994.0 464815.0 465224.0 466638.0 30743.0 30763.0 31558.2 31598.7 31745.8 51890.7 52016.7 52125.5 52603.7 53104.2 42407.4 42939.8 43416.1 44176.8 44258.8 42164.4 42532.6 43190.6 43351.1 43375.1 42653.6 43644.3 43782.0 44257.1 44479.8 37873.2 38605.6 38740.2 38857.6 39320.8 29405.4 29455.6 29476.4 29489.7 29834.3 09:53:06 INFO - PROCESS | 3425 | 09:53:06 INFO - PROCESS | 3425 | [#2] jquery.html Cycles:180 Average:29296.94 Median:2914.12 stddev:79188.79 (2717.4%) stddev-sans-first:79381.23 09:53:06 INFO - PROCESS | 3425 | Values: 409.5 411.5 414.2 414.9 425.2 19176.6 20582.0 20660.8 20923.5 21285.4 20033.8 20035.2 20470.5 20834.8 20919.0 5699.8 5879.0 5915.9 5926.6 5926.8 4310.3 5869.6 5926.8 6154.5 6491.2 6513.3 6514.7 6549.6 6563.0 6563.2 6261.8 6491.6 6494.3 6494.6 6503.0 3889.7 5415.7 5718.3 5720.2 5737.0 23735.0 24162.5 24363.3 24656.4 24720.3 2198.4 2322.0 2360.6 2760.9 3067.3 10948.9 11798.0 13888.3 14346.1 15410.1 2026.8 2029.2 2043.3 2073.8 2115.6 83795.6 88126.6 88472.6 88933.6 89175.0 11339.6 11414.0 11445.4 11486.8 11663.7 16046.7 16088.4 16129.6 16167.0 16199.9 8544.1 8571.5 8653.9 8816.6 8944.3 8569.7 8612.2 8691.9 8831.6 8840.0 4605.6 4715.8 4880.0 4913.5 4951.5 1810.9 1840.0 1975.9 1979.3 2008.5 946.3 977.3 992.3 1067.1 1106.0 19465.4 19856.9 19930.3 19932.9 20090.9 1018.8 1024.8 1070.3 1071.5 1103.6 10754.2 11399.7 11455.3 11487.3 11508.2 451341.0 459725.0 460382.0 474384.0 540978.0 92339.0 92942.0 93265.5 98461.5 99693.6 484.5 510.4 510.4 985.8 1084.2 10970.2 11074.9 11498.0 11521.9 11638.1 31705.4 42965.0 44449.6 44610.2 45911.8 59619.0 61571.0 63670.4 64226.0 64366.7 8629.5 8845.5 8921.3 9091.9 9208.7 13180.9 13869.2 13901.7 14029.5 14069.7 11588.8 11712.6 12115.5 12279.1 12488.3 11416.0 11698.5 12038.6 12317.8 12392.9 11433.5 11863.9 12033.2 12083.6 12270.3 8759.4 8815.6 9031.8 9128.8 9273.9 9016.5 11861.5 12007.6 12066.9 12067.8 09:53:06 INFO - PROCESS | 3425 | 09:53:06 INFO - PROCESS | 3425 | [#3] mootools.html Cycles:180 Average:6147.49 Median:4796.41 stddev:3492.60 (72.8%) stddev-sans-first:3484.02 09:53:06 INFO - PROCESS | 3425 | Values: 1381.5 1543.5 1583.5 1776.3 1884.0 8941.9 9388.3 9411.1 9590.7 9605.8 9199.0 9204.4 9205.5 9207.3 9281.7 2498.5 2528.8 2529.2 2542.6 2544.9 2450.3 2558.1 2561.3 2562.4 2562.5 2382.3 2386.6 2430.1 2433.0 2434.1 2419.0 2429.6 2443.4 2454.5 2460.2 2395.4 2400.5 2410.1 2415.4 2438.3 8769.8 8899.2 8914.8 9188.6 9555.1 5661.9 5783.4 5794.7 5794.8 5801.2 3595.7 3604.5 3649.0 3654.2 3661.5 1241.8 1253.6 1293.9 1313.9 1325.5 13009.6 13034.7 13088.4 13091.9 13102.1 4788.6 4804.2 4843.3 4896.6 4914.0 10139.3 10187.6 10207.9 10214.6 10219.8 5694.1 5741.0 5744.6 5750.1 5750.4 4756.9 4786.9 5202.0 5248.6 5321.1 4016.8 4035.9 4078.8 4090.3 4149.7 1130.5 1135.1 1138.8 1139.7 1152.3 734.7 1008.1 1058.0 1089.9 1092.1 6092.6 6360.8 6425.2 6448.6 6449.7 503.6 506.8 526.2 558.3 672.3 4332.8 4454.4 4471.1 4473.1 4475.9 11230.6 11537.1 11541.7 11573.7 11605.8 11077.2 11553.9 11612.5 11647.1 11674.1 8438.9 8533.3 8599.8 8611.0 8655.6 6353.9 6494.2 6535.2 6607.4 6630.5 10486.1 10732.9 10800.4 10859.7 10959.2 9465.7 11062.6 11065.7 11070.9 11091.8 7972.0 8238.0 8241.6 8386.4 8388.7 9162.8 9262.4 9393.9 9402.2 9417.2 8024.4 8231.6 8310.5 8361.0 8368.2 7960.4 8208.9 8314.3 8386.6 8405.8 7448.4 7459.0 7507.6 7523.9 7532.7 5594.1 5605.1 5720.6 5749.8 5756.1 5351.9 5434.9 5705.1 5807.5 5832.5 09:53:06 INFO - PROCESS | 3425 | 09:53:06 INFO - PROCESS | 3425 | [#4] prototype.html Cycles:180 Average:4662.19 Median:5276.26 stddev:1354.95 (25.7%) stddev-sans-first:1319.02 09:53:06 INFO - PROCESS | 3425 | Values: 323.1 339.7 347.6 349.8 351.0 5497.8 5542.9 5550.3 5574.0 5597.7 5193.5 5315.6 5436.2 5452.3 5491.5 4266.2 4274.7 4298.9 4310.3 4326.1 4291.9 4293.0 4305.3 4312.9 4317.8 3940.6 4063.2 4200.8 4253.0 4255.3 4162.5 4180.6 4201.6 4226.2 4232.0 3718.5 3726.4 3747.0 3759.2 3770.7 5182.7 5275.8 5286.5 5289.4 5354.3 4856.5 4860.2 4860.8 4881.2 4887.1 5677.3 5727.6 5728.4 5729.6 5737.7 4037.3 4126.2 4136.9 4146.3 4150.0 5949.9 5969.9 5982.9 5988.4 6008.5 4208.5 4218.5 4222.7 4224.4 4232.4 4074.2 4270.4 4281.4 4286.7 4287.4 4856.7 4867.9 4899.0 4913.9 4931.8 5730.5 5791.9 5795.6 5821.8 5858.5 1387.2 1414.0 1425.2 1464.1 1468.1 925.1 931.9 941.3 948.8 961.1 4965.6 4984.2 5004.4 5006.9 5014.8 5268.7 5276.7 5287.5 5300.8 5316.2 4839.9 4984.0 5000.9 5010.9 5019.1 2816.9 2832.7 2834.4 2840.7 2848.4 5379.8 5380.5 5413.1 5416.2 5470.3 5175.5 5269.0 5308.7 5325.9 5368.5 5363.6 5364.1 5364.4 5377.9 5406.8 5243.6 5281.7 5286.7 5294.3 5336.8 4955.0 5311.9 5315.9 5334.0 5336.8 5327.3 5429.2 5444.3 5455.4 5505.6 3837.6 3862.6 3883.4 3891.7 3926.8 4952.3 5226.9 5229.4 5342.8 5730.7 5671.8 5867.2 5918.4 5926.6 5927.7 5786.3 5838.6 5881.4 5885.4 5902.8 5550.5 5769.7 5782.9 5786.9 5814.4 5830.4 5865.8 5871.3 5913.3 5935.4 5404.8 5776.8 5802.3 5810.9 5822.1 09:53:06 INFO - PROCESS | 3425 | 09:53:06 INFO - PROCESS | 3425 | [#5] yui.html Cycles:180 Average:20926.85 Median:3936.16 stddev:28930.07 (735.0%) stddev-sans-first:28971.73 09:53:06 INFO - PROCESS | 3425 | Values: 796.1 814.8 837.6 859.5 934.3 15466.5 15652.8 15823.8 15835.5 17650.5 15548.1 15758.1 16018.5 16019.2 16082.1 743.6 859.3 880.9 899.5 900.5 897.1 897.2 899.9 900.6 901.0 952.1 955.7 956.5 958.0 959.7 772.9 806.1 903.7 942.6 949.0 690.0 691.7 693.5 693.6 693.8 16368.3 16377.9 16387.0 16401.4 16448.1 14735.0 14841.8 14888.6 15045.1 15245.0 13302.5 13427.4 13458.5 13472.9 13648.9 4163.3 4198.1 4203.6 4233.7 4340.0 77646.0 79207.8 79583.0 80096.2 80264.0 12950.7 13133.0 13183.0 13334.7 13390.4 25260.5 25295.5 25349.9 25366.9 25368.2 9533.2 9592.6 9602.0 9611.8 10074.9 5223.4 5245.5 5280.1 5299.4 5421.5 3817.7 3934.0 3934.6 3937.7 3938.0 664.8 677.4 679.7 686.8 694.8 718.9 822.4 839.5 840.3 847.1 10865.5 11115.6 11124.9 11275.8 11319.8 690.5 805.1 830.1 848.1 855.4 5173.3 5377.3 5432.4 5512.1 5527.1 69608.0 74465.6 110297.4 118920.0 119174.0 100726.0 101973.0 102791.0 103058.5 103213.5 55809.0 58151.1 83933.0 89465.6 90359.5 9920.8 10174.2 10185.2 10196.5 10255.5 54355.5 54488.0 54650.0 56048.0 56346.7 75237.2 77476.6 78678.6 79350.0 81625.6 12910.5 13078.5 13163.7 13211.3 13444.5 14177.6 14198.1 14243.9 14607.2 14737.1 13716.5 13839.2 14031.9 14039.4 14365.6 12884.3 13373.8 13461.7 13662.7 13736.0 13361.3 13521.0 13863.0 13875.9 14079.6 11455.4 11698.0 12011.9 12031.8 12157.9 10169.9 10962.3 10977.9 10995.8 11069.4 09:53:06 INFO - PROCESS | 3425 | -------- Summary: end -------- 09:53:06 INFO - PROCESS | 3425 | 09:53:06 INFO - TEST-INFO | 3425: exit 0 09:53:06 INFO - TEST-OK | dromaeo_css | took 1113451ms 09:53:06 INFO - TEST-START | kraken 09:53:06 INFO - operating with platform_type : linux_ 09:53:06 INFO - Initialising browser for kraken test... 09:53:06 INFO - TEST-INFO | started process 3614 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPYoS16/profile http://localhost:51598/getInfo.html) 09:53:10 INFO - PROCESS | 3614 | __metrics Screen width/height:1600/1200 09:53:10 INFO - PROCESS | 3614 | colorDepth:24 09:53:10 INFO - PROCESS | 3614 | Browser inner width/height: 1024/697 09:53:10 INFO - PROCESS | 3614 | __metrics 09:53:11 ERROR - PROCESS | 3614 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:53:11 INFO - PROCESS | 3614 | console.error: PushService: 09:53:11 INFO - PROCESS | 3614 | stateChangeProcessEnqueue: Error transitioning state 09:53:11 INFO - PROCESS | 3614 | UnknownError 09:53:12 INFO - TEST-INFO | 3614: exit 0 09:53:12 INFO - Browser initialized. 09:53:12 INFO - Running cycle 1/1 for kraken test... 09:53:12 INFO - TEST-INFO | started process 3706 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPYoS16/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:53:24 INFO - PROCESS | 3706 | RSS: Main: 149184512 09:53:24 INFO - PROCESS | 3706 | 09:53:26 INFO - PROCESS | 3706 | Cycle 1(1): loaded http://localhost:51598/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51598/tests/kraken/driver.html?testName=audio-beat-detection) 09:53:27 INFO - PROCESS | 3706 | RSS: Main: 198160384 09:53:27 INFO - PROCESS | 3706 | 09:53:30 INFO - PROCESS | 3706 | Cycle 1(1): loaded http://localhost:51598/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51598/tests/kraken/driver.html?testName=audio-dft) 09:53:30 INFO - PROCESS | 3706 | RSS: Main: 192057344 09:53:30 INFO - PROCESS | 3706 | 09:53:33 INFO - PROCESS | 3706 | Cycle 1(1): loaded http://localhost:51598/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51598/tests/kraken/driver.html?testName=audio-fft) 09:53:34 INFO - PROCESS | 3706 | RSS: Main: 282501120 09:53:34 INFO - PROCESS | 3706 | 09:53:36 INFO - PROCESS | 3706 | Cycle 1(1): loaded http://localhost:51598/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51598/tests/kraken/driver.html?testName=audio-oscillator) 09:53:36 INFO - PROCESS | 3706 | RSS: Main: 191848448 09:53:36 INFO - PROCESS | 3706 | 09:53:39 INFO - PROCESS | 3706 | Cycle 1(1): loaded http://localhost:51598/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51598/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:53:39 INFO - PROCESS | 3706 | RSS: Main: 191438848 09:53:39 INFO - PROCESS | 3706 | 09:53:42 INFO - PROCESS | 3706 | Cycle 1(1): loaded http://localhost:51598/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51598/tests/kraken/driver.html?testName=imaging-darkroom) 09:53:43 INFO - PROCESS | 3706 | RSS: Main: 226652160 09:53:43 INFO - PROCESS | 3706 | 09:53:46 INFO - PROCESS | 3706 | Cycle 1(1): loaded http://localhost:51598/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51598/tests/kraken/driver.html?testName=imaging-desaturate) 09:53:47 INFO - PROCESS | 3706 | RSS: Main: 227426304 09:53:47 INFO - PROCESS | 3706 | 09:53:50 INFO - PROCESS | 3706 | Cycle 1(1): loaded http://localhost:51598/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51598/tests/kraken/driver.html?testName=json-parse-financial) 09:53:50 INFO - PROCESS | 3706 | RSS: Main: 227393536 09:53:50 INFO - PROCESS | 3706 | 09:53:52 INFO - PROCESS | 3706 | Cycle 1(1): loaded http://localhost:51598/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51598/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:53:52 INFO - PROCESS | 3706 | RSS: Main: 204476416 09:53:52 INFO - PROCESS | 3706 | 09:53:54 INFO - PROCESS | 3706 | Cycle 1(1): loaded http://localhost:51598/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51598/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:53:55 INFO - PROCESS | 3706 | RSS: Main: 205729792 09:53:55 INFO - PROCESS | 3706 | 09:53:57 INFO - PROCESS | 3706 | Cycle 1(1): loaded http://localhost:51598/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51598/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:53:57 INFO - PROCESS | 3706 | RSS: Main: 209022976 09:53:57 INFO - PROCESS | 3706 | 09:54:00 INFO - PROCESS | 3706 | Cycle 1(1): loaded http://localhost:51598/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51598/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:54:00 INFO - PROCESS | 3706 | RSS: Main: 216748032 09:54:00 INFO - PROCESS | 3706 | 09:54:03 INFO - PROCESS | 3706 | Cycle 1(1): loaded http://localhost:51598/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51598/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:54:04 INFO - PROCESS | 3706 | RSS: Main: 217772032 09:54:04 INFO - PROCESS | 3706 | 09:54:05 INFO - PROCESS | 3706 | Cycle 1(1): loaded http://localhost:51598/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51598/tests/kraken/driver.html?testName=ai-astar) 09:54:06 INFO - PROCESS | 3706 | RSS: Main: 260550656 09:54:06 INFO - PROCESS | 3706 | 09:54:06 INFO - PROCESS | 3706 | __start_tp_report 09:54:06 INFO - PROCESS | 3706 | _x_x_mozilla_page_load 09:54:06 INFO - PROCESS | 3706 | _x_x_mozilla_page_load_details 09:54:06 INFO - PROCESS | 3706 | |i|pagename|runs| 09:54:06 INFO - PROCESS | 3706 | |0;ai-astar;111;103;108;110;112;110;110;109;110;110 09:54:06 INFO - PROCESS | 3706 | |1;audio-beat-detection;150;124;125;187;127;124;152;129;151;122 09:54:06 INFO - PROCESS | 3706 | |2;audio-dft;158;161;160;161;161;161;160;160;161;161 09:54:06 INFO - PROCESS | 3706 | |3;audio-fft;75;75;75;133;75;76;75;74;98;76 09:54:06 INFO - PROCESS | 3706 | |4;audio-oscillator;85;85;108;85;85;84;119;84;84;132 09:54:06 INFO - PROCESS | 3706 | |5;imaging-gaussian-blur;117;118;117;117;117;117;118;116;116;116 09:54:06 INFO - PROCESS | 3706 | |6;imaging-darkroom;145;146;146;146;146;145;145;145;146;146 09:54:06 INFO - PROCESS | 3706 | |7;imaging-desaturate;95;95;94;95;96;95;94;95;95;94 09:54:06 INFO - PROCESS | 3706 | |8;json-parse-financial;50;50;51;52;51;52;53;54;51;51 09:54:06 INFO - PROCESS | 3706 | |9;json-stringify-tinderbox;60;61;61;61;61;61;60;61;61;62 09:54:06 INFO - PROCESS | 3706 | |10;stanford-crypto-aes;78;77;82;81;79;84;77;78;109;82 09:54:06 INFO - PROCESS | 3706 | |11;stanford-crypto-ccm;117;119;116;131;116;116;118;117;139;126 09:54:06 INFO - PROCESS | 3706 | |12;stanford-crypto-pbkdf2;157;158;156;155;159;160;155;159;152;154 09:54:06 INFO - PROCESS | 3706 | |13;stanford-crypto-sha256-iterative;61;61;64;62;62;61;62;61;62;62 09:54:06 INFO - PROCESS | 3706 | __end_tp_report 09:54:06 INFO - PROCESS | 3706 | __start_cc_report 09:54:06 INFO - PROCESS | 3706 | _x_x_mozilla_cycle_collect,1595 09:54:06 INFO - PROCESS | 3706 | __end_cc_report 09:54:06 INFO - PROCESS | 3706 | __startTimestamp1466441645998__endTimestamp 09:54:06 INFO - PROCESS | 3706 | 09:54:06 INFO - PROCESS | 3706 | ------- Summary: start ------- 09:54:06 INFO - PROCESS | 3706 | Number of tests: 14 09:54:06 INFO - PROCESS | 3706 | 09:54:06 INFO - PROCESS | 3706 | [#0] ai-astar Cycles:10 Average:109.30 Median:110.00 stddev:2.45 (2.2%) stddev-sans-first:2.52 09:54:06 INFO - PROCESS | 3706 | Values: 111.0 103.0 108.0 110.0 112.0 110.0 110.0 109.0 110.0 110.0 09:54:06 INFO - PROCESS | 3706 | 09:54:06 INFO - PROCESS | 3706 | [#1] audio-beat-detection Cycles:10 Average:139.10 Median:139.50 stddev:20.86 (15.0%) stddev-sans-first:21.75 09:54:06 INFO - PROCESS | 3706 | Values: 150.0 124.0 125.0 187.0 127.0 124.0 152.0 129.0 151.0 122.0 09:54:06 INFO - PROCESS | 3706 | 09:54:06 INFO - PROCESS | 3706 | [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:0.50 09:54:06 INFO - PROCESS | 3706 | Values: 158.0 161.0 160.0 161.0 161.0 161.0 160.0 160.0 161.0 161.0 09:54:06 INFO - PROCESS | 3706 | 09:54:06 INFO - PROCESS | 3706 | [#3] audio-fft Cycles:10 Average:83.20 Median:75.00 stddev:18.93 (25.2%) stddev-sans-first:19.84 09:54:06 INFO - PROCESS | 3706 | Values: 75.0 75.0 75.0 133.0 75.0 76.0 75.0 74.0 98.0 76.0 09:54:06 INFO - PROCESS | 3706 | 09:54:06 INFO - PROCESS | 3706 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:84.50 stddev:17.88 (21.2%) stddev-sans-first:18.59 09:54:06 INFO - PROCESS | 3706 | Values: 85.0 85.0 108.0 85.0 85.0 84.0 119.0 84.0 84.0 132.0 09:54:06 INFO - PROCESS | 3706 | 09:54:06 INFO - PROCESS | 3706 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 09:54:06 INFO - PROCESS | 3706 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 118.0 116.0 116.0 116.0 09:54:06 INFO - PROCESS | 3706 | 09:54:06 INFO - PROCESS | 3706 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 09:54:06 INFO - PROCESS | 3706 | Values: 145.0 146.0 146.0 146.0 146.0 145.0 145.0 145.0 146.0 146.0 09:54:06 INFO - PROCESS | 3706 | 09:54:06 INFO - PROCESS | 3706 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 09:54:06 INFO - PROCESS | 3706 | Values: 95.0 95.0 94.0 95.0 96.0 95.0 94.0 95.0 95.0 94.0 09:54:06 INFO - PROCESS | 3706 | 09:54:06 INFO - PROCESS | 3706 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:51.50 stddev:1.27 (2.5%) stddev-sans-first:1.22 09:54:06 INFO - PROCESS | 3706 | Values: 50.0 50.0 51.0 52.0 51.0 52.0 53.0 54.0 51.0 51.0 09:54:06 INFO - PROCESS | 3706 | 09:54:06 INFO - PROCESS | 3706 | [#9] json-stringify-tinderbox Cycles:10 Average:60.90 Median:61.00 stddev:0.57 (0.9%) stddev-sans-first:0.50 09:54:06 INFO - PROCESS | 3706 | Values: 60.0 61.0 61.0 61.0 61.0 61.0 60.0 61.0 61.0 62.0 09:54:06 INFO - PROCESS | 3706 | 09:54:06 INFO - PROCESS | 3706 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:80.00 stddev:9.55 (11.9%) stddev-sans-first:9.97 09:54:06 INFO - PROCESS | 3706 | Values: 78.0 77.0 82.0 81.0 79.0 84.0 77.0 78.0 109.0 82.0 09:54:06 INFO - PROCESS | 3706 | 09:54:06 INFO - PROCESS | 3706 | [#11] stanford-crypto-ccm Cycles:10 Average:121.50 Median:118.50 stddev:7.93 (6.7%) stddev-sans-first:8.25 09:54:06 INFO - PROCESS | 3706 | Values: 117.0 119.0 116.0 131.0 116.0 116.0 118.0 117.0 139.0 126.0 09:54:06 INFO - PROCESS | 3706 | 09:54:06 INFO - PROCESS | 3706 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.50 Median:157.50 stddev:2.55 (1.6%) stddev-sans-first:2.70 09:54:06 INFO - PROCESS | 3706 | Values: 157.0 158.0 156.0 155.0 159.0 160.0 155.0 159.0 152.0 154.0 09:54:06 INFO - PROCESS | 3706 | 09:54:06 INFO - PROCESS | 3706 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:0.92 (1.5%) stddev-sans-first:0.93 09:54:06 INFO - PROCESS | 3706 | Values: 61.0 61.0 64.0 62.0 62.0 61.0 62.0 61.0 62.0 62.0 09:54:06 INFO - PROCESS | 3706 | -------- Summary: end -------- 09:54:06 INFO - PROCESS | 3706 | 09:54:06 INFO - TEST-INFO | 3706: exit 0 09:54:06 INFO - TEST-OK | kraken | took 60176ms 09:54:06 INFO - SUITE-END | took 1173s 09:54:07 INFO - Completed test suite (00:19:33) 09:54:07 INFO - javascript benchmark 09:54:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3395.881144159954, 3422.6748458896413, 3430.3139372125574, 3451.154467155547, 3471.7743346682464, 5618.662880227184, 5697.544196464283, 5719.227904232927, 5720.856978575538, 5742.971285143579, 5588.748506317215, 5638.689872057035, 5640.0693885508945, 5642.858928526789, 5646.887062258752, 5017.774200161001, 5070.974645126769, 5108.2848401223855, 5109.3613298337705, 5150.098732721777, 5042.899142017158, 5100.744962751866, 5113.642045056848, 5119.897602047976, 5154.974225128869, 5071.695698258116, 5094.2103973884105, 5164.896702065957, 5168.405633352157, 5169.345809341013, 5064.366954130734, 5071.239314102877, 5082.36470441193, 5089.389273287229, 5188.325517682696, 4926.778294976734, 4989.55094041538, 5010.123228435009, 5034.773435195425, 5056.949430505685, 5547.0, 5559.63862348946, 5574.916376254359, 5592.972035139839, 5603.467670571289, 5862.267216597926, 6017.789377371771, 6026.276846778371, 6035.124906888476, 6039.275286965592, 6009.248843894513, 6065.211522502091, 6074.392560743934, 6123.112148738453, 6140.785072522441, 5755.482006619424, 5761.164631128505, 5763.88472230553, 5765.682887441189, 5767.480926716616, 5906.852328691817, 6182.938170618326, 6266.404691554295, 6266.749330026839, 6267.811965641038, 5766.250387449605, 5829.387914268941, 5829.88340233193, 5908.527317814564, 5910.936031514369, 5926.0, 5927.140564618193, 5932.703364831741, 5950.642961422329, 5988.9401105989255, 5838.38696936824, 5933.436323549256, 5941.346451890288, 5942.64344139353, 5953.404659534012, 6001.5198784097165, 6017.548683848729, 6073.30157031947, 6077.696115194222, 6113.144159817629, 4601.562851529099, 4612.723236605814, 4626.467956185014, 4627.699199552018, 4641.327007583882, 4042.959570404317, 4056.9391459128137, 4105.34314509683, 4142.984968682632, 4147.0, 5708.40061793514, 5716.599838011382, 5723.969685456658, 5749.85625359361, 5755.85610359736, 6093.482054025458, 6101.877962440815, 6110.083487476825, 6114.816555503342, 6130.111133885651, 5493.752781124859, 5630.943690563124, 5663.461971112738, 5680.573956953162, 5736.397678243808, 5191.273221748958, 5227.26818245446, 5228.1112210923475, 5229.215617657382, 5233.110371236823, 6215.844603884939, 6217.036359364306, 6228.252609686776, 6228.688565571794, 6232.345603711545, 5840.94159058404, 5930.911036334544, 6020.0, 6036.63780173191, 6104.908426373519, 6253.687315634109, 6289.40250676185, 6295.1186833842385, 6296.811095667137, 6298.433141017331, 5815.273090863642, 6036.486898613492, 6044.486218671462, 6045.183900173421, 6047.848803779941, 5973.223480947449, 6003.969980150071, 6034.547408944259, 6083.1483592298, 6084.756609735561, 5935.258092738408, 5974.61165024272, 5978.043513037893, 5978.940210597838, 5979.970100149471, 5661.773529058792, 5772.5381969441505, 5894.322152952293, 5931.051031834886, 5955.553333499918, 5677.233573467529, 5808.5062769665055, 5817.76728930838, 5817.7963771267805, 5845.941540584539, 5921.526277897844, 5967.880642387041, 5977.760889564543, 5978.940210597838, 5979.940200598112, 5639.520640745583, 5774.307083150049, 5817.418258174149, 5820.912686309624, 5823.126531020381], "value": 5561.8634367569275, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [602.8462742000789, 643.5688088980382, 648.4326214562258, 655.2366493035614, 655.9963256217989, 33566.16084597883, 46549.53450465494, 47189.52810471903, 47257.763711181484, 47300.526994730084, 48294.758526207406, 48912.51087489124, 48958.26562601564, 49029.75485122569, 49037.50962490374, 8470.576471176448, 9073.727788166349, 9233.169014788667, 10809.702835659731, 11051.894810518965, 10894.67315980522, 10976.890231097706, 10983.670489885317, 10991.285566438195, 11008.064314533274, 11100.55597776092, 11141.220114591983, 11171.106311495101, 11173.776524469506, 11178.44107794611, 8475.533845638487, 10837.403942783105, 11028.669139925814, 11059.557617695282, 11334.093272538177, 10956.34261944286, 11022.173336999676, 11025.173112016551, 11026.558937642485, 11031.0, 44650.0, 44983.550164498265, 45040.549594503966, 45165.548344516465, 45166.32250516245, 3698.2418604186346, 3700.4449332600057, 3719.0888232382968, 4043.980154080108, 4225.91548169038, 34800.47799283013, 34880.47679284813, 35152.29695406103, 35268.47097293543, 36157.81921090404, 4340.23795514157, 4404.163208990281, 4412.294032954712, 4421.734695918255, 4434.556544345572, 355820.99999999744, 356980.0, 357242.0, 357675.0, 359367.0, 37112.62887371146, 60454.18637440884, 61806.381936180966, 62064.0, 62092.0, 38131.42802857959, 40210.19579608391, 40248.0, 40275.0, 40338.0, 15672.529824105295, 16976.236069376908, 17144.228509717093, 17156.914215429093, 17159.399421020455, 13836.0, 14264.786028209584, 14311.78532322016, 14515.709685806225, 14669.119852808652, 8898.86651700225, 8970.372073954892, 8988.640454381752, 9033.548322583845, 9036.32227582934, 3412.658734126617, 3413.880514182041, 3426.297608990151, 3444.431668774624, 3451.7583769135917, 976.8632391465201, 1016.974575635615, 1043.5773511727762, 1122.8428020077197, 1198.789222884875, 33458.159801211164, 34041.0, 34090.48864267038, 34256.65743342585, 34389.48415773766, 1142.9657110286705, 1155.867075286336, 1172.9296242225494, 1215.7629262293945, 1249.1318533619228, 17607.647847042987, 17915.641687166182, 17932.731009034876, 17940.730889036677, 18021.639567208582, 554699.0, 559472.0, 561999.0, 648042.0, 655436.0, 65915.34084659092, 66301.34246643777, 66511.33488665149, 66675.0, 66809.66595166993, 1354.966125846842, 1464.9598784862653, 1467.8018467506963, 1499.0630855715178, 1522.5736793697783, 30407.695923040486, 33019.66980330166, 34059.0, 34172.48741268933, 34437.65562344345, 51876.48123518867, 52580.737096314275, 52707.73646131745, 52722.4183274487, 52749.736251320035, 461177.0, 462994.0, 464815.0, 465224.0, 466638.0, 30743.0, 30763.0, 31558.211044724067, 31598.684013159575, 31745.8412707935, 51890.74054629703, 52016.739916300176, 52125.478745212065, 52603.73698131485, 53104.203436947704, 42407.36388954106, 42939.785301073294, 43416.13167736564, 44176.77911610421, 44258.77870610626, 42164.36753448762, 42532.5746742541, 43190.56809431865, 43351.13297734091, 43375.13249735051, 42653.57346426496, 43644.345334819365, 43782.0, 44257.11485770331, 44479.777601113085, 37873.24253514969, 38605.613943860204, 38740.225195496496, 38857.61142388653, 39320.80339598284, 29405.411891761618, 29455.55816662709, 29476.410471790874, 29489.726655205657, 29834.254143646584], "value": 20521.78842917147, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.4983645034832, 411.4507132977475, 414.2398698388459, 414.94605701258837, 425.22608851889555, 19176.61646767064, 20582.0, 20660.793392066094, 20923.476913077182, 21285.36143915681, 20033.799662003414, 20035.19859205634, 20470.488237794063, 20834.791652083473, 20919.0, 5699.829005129853, 5879.029960056586, 5915.911261331062, 5926.585139040269, 5926.822195334125, 4310.310350343945, 5869.618474799146, 5926.792562260322, 6154.50763938886, 6491.22105347359, 6513.250976137758, 6514.739410423577, 6549.574277671936, 6563.048357988089, 6563.212414510265, 6261.780837670683, 6491.642959637218, 6494.31809659986, 6494.577852439576, 6503.024546318066, 3889.727719059667, 5415.729213539307, 5718.34239062509, 5720.1991721159075, 5736.971315143439, 23735.0, 24162.516749665083, 24363.26910192697, 24656.383590410205, 24720.25839224808, 2198.4393979535275, 2322.036354912709, 2360.6459031145464, 2760.9309767255777, 3067.3098552825436, 10948.945255273753, 11797.997170240455, 13888.305584720723, 14346.139231646135, 15410.075395476308, 2026.7567891853073, 2029.1781828359537, 2043.2644248070683, 2073.782252863458, 2115.5557332959943, 83795.58102209511, 88126.55936720276, 88472.5576372114, 88933.55533222356, 89175.0, 11339.603113890975, 11414.0, 11445.427728613537, 11486.770264594828, 11663.708407289716, 16046.67906641884, 16088.356465741474, 16129.596760081093, 16167.0, 16199.919000404923, 8544.060153383123, 8571.485710857243, 8653.870191947126, 8816.559172041501, 8944.284457243404, 8569.742907712778, 8612.224899758927, 8691.91308086911, 8831.64673413069, 8840.0, 4605.585497305258, 4715.834945776881, 4879.951200488021, 4913.459519452857, 4951.529604687549, 1810.9366172183911, 1840.0155916584563, 1975.9012049397472, 1979.2676709617247, 2008.4978755311172, 946.2808265718104, 977.3012296208245, 992.270681049428, 1067.114295135036, 1105.9889401105886, 19465.4160375189, 19856.90071549633, 19930.30243941484, 19932.90033549823, 20090.89954550218, 1018.7758693087509, 1024.8316918712674, 1070.272214893864, 1071.5338827610015, 1103.6358001859367, 10754.247202695737, 11399.715007124722, 11455.255408398594, 11487.25332853362, 11508.194426390073, 451341.0, 459725.0, 460382.0, 474384.0, 540978.0, 92339.0, 92942.0, 93265.5336723312, 98461.50769246108, 99693.6167668843, 484.4646665434644, 510.38753495805923, 510.4461659099918, 985.8225519406433, 1084.2247792828166, 10970.177236707119, 11074.889251107386, 11498.0, 11521.884781152416, 11638.06895448379, 31705.365892681555, 42965.0, 44449.55550444454, 44610.18575913294, 45911.77044114758, 59619.0, 61571.0, 63670.36329636644, 64226.0, 64366.678166608865, 8629.525376104375, 8845.469271843709, 8921.286297096347, 9091.90908090937, 9208.723738287594, 13180.868191318348, 13869.167849929037, 13901.72196556043, 14029.469245267246, 14069.733723964482, 11588.807433764247, 11712.590059347882, 12115.454804533994, 12279.140460167702, 12488.313142777235, 11416.0, 11698.532058717556, 12038.638840834788, 12317.815232771336, 12392.938035309766, 11433.542658293907, 11863.932246097893, 12033.157678962389, 12083.637490875288, 12270.32513211782, 8759.386842920934, 8815.603297851483, 9031.819363612822, 9128.817423651622, 9273.907260927574, 9016.549172541478, 11861.466234019317, 12007.579734709243, 12066.879331206927, 12067.75864482723], "value": 9449.35400847327, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.4888491258237, 1543.529223586806, 1583.5170273066715, 1776.3161182944561, 1884.0108942805034, 8941.91058089419, 9388.342816002883, 9411.058894110585, 9590.664326748565, 9605.807883842319, 9199.034101419355, 9204.447733136016, 9205.493697846632, 9207.309451791109, 9281.675141370053, 2498.487809998946, 2528.84826910386, 2529.215943057649, 2542.580474221751, 2544.860032698201, 2450.3384086296687, 2558.0790915270486, 2561.2700380391593, 2562.397836508419, 2562.487502499498, 2382.285314405671, 2386.606209975361, 2430.064425196303, 2432.9513409731794, 2434.111549284516, 2418.9637155442592, 2429.6112621980487, 2443.3525115844313, 2454.5459090068393, 2460.20043485866, 2395.3652282145244, 2400.459896523286, 2410.0721222329353, 2415.4082249848725, 2438.2807071913826, 8769.824603507957, 8899.199072083544, 8914.821703565956, 9188.63245470187, 9555.140037396668, 5661.943380566183, 5783.363829978699, 5794.68129252891, 5794.82615521535, 5801.187833703285, 3595.658412450813, 3604.4953706481115, 3649.033006253345, 3654.214343916071, 3661.5240018797385, 1241.838560987075, 1253.642711827134, 1293.864144264848, 1313.894888408925, 1325.5493132334957, 13009.609711708665, 13034.739305213938, 13088.411021504055, 13091.934540327333, 13102.08285420021, 4788.609589108941, 4804.2073057945745, 4843.297721830315, 4896.608271338285, 4913.950860491349, 10139.290249682452, 10187.592496300065, 10207.9489602553, 10214.591416343263, 10219.846702299472, 5694.06048002075, 5741.0, 5744.626599271034, 5750.051241545182, 5750.424957504216, 4756.881077973009, 4786.880327991758, 5202.037623039674, 5248.606354523426, 5321.148616221386, 4016.8393264269685, 4035.878923632296, 4078.8368465261055, 4090.3250963591267, 4149.688773342012, 1130.5364800431782, 1135.1429670598811, 1138.7608602193632, 1139.6694958461953, 1152.348922858599, 734.7354952217197, 1008.1078245752415, 1057.9737654475148, 1089.8910108989196, 1092.1481244629201, 6092.603980741238, 6360.84097897547, 6425.164728585254, 6448.613083215022, 6449.677516124175, 503.6172508893267, 506.76435457512434, 526.1844141580535, 558.288182567222, 672.314239475732, 4332.805023773874, 4454.354118652777, 4471.105778844244, 4473.127740090651, 4475.888102797391, 11230.550777968952, 11537.077033837273, 11541.653750387502, 11573.710657233636, 11605.76788464226, 11077.169212308949, 11553.942230288794, 11612.477438515287, 11647.068234541217, 11674.124440666814, 8438.873416898752, 8533.317334613092, 8599.78500537479, 8611.0, 8655.610497527627, 6353.904691429725, 6494.188226471691, 6535.183102112236, 6607.405333519814, 6630.5027122965, 10486.056254937092, 10732.892671073501, 10800.40597767099, 10859.728506787078, 10959.232853700483, 9465.66870159541, 11062.557497700001, 11065.668029959113, 11070.889291106985, 11091.833622495507, 7972.0, 8237.958810205912, 8241.587920603826, 8386.412951093365, 8388.706395276136, 9162.816743664956, 9262.39794413361, 9393.859092113487, 9402.15380615748, 9417.199538039069, 8024.358051355995, 8231.629576669184, 8310.501369917823, 8361.0, 8368.20502052304, 7960.442769006114, 8208.876866846882, 8314.334853211607, 8386.580670966548, 8405.831883362176, 7448.366888814511, 7459.0, 7507.624618769149, 7523.924760752322, 7532.661030253761, 5594.0769772987005, 5605.131204663284, 5720.570957178145, 5749.837589325938, 5756.079027355603, 5351.919721204107, 5434.864128396822, 5705.144228365778, 5807.535397168132, 5832.50423713989], "value": 4842.990974868011, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.0581552897156, 339.6668077794656, 347.6072038596387, 349.7604141163302, 350.98947031589046, 5497.780088796449, 5542.944570554293, 5550.306211723535, 5574.0, 5597.748101335439, 5193.45468725783, 5315.62457839272, 5436.184572314155, 5452.318460192476, 5491.533219676322, 4266.168097221043, 4274.700770946035, 4298.871033868973, 4310.288802347609, 4326.134773045388, 4291.91416171678, 4293.0, 4305.332673435622, 4312.892177695551, 4317.805698743548, 3940.566537680853, 4063.227986682521, 4200.789960501978, 4253.0, 4255.2978758504805, 4162.521310049353, 4180.623743863067, 4201.558836322203, 4226.197022565702, 4231.978840105795, 3718.4980027696183, 3726.3851464508325, 3747.0445036515785, 3759.2293579816073, 3770.717196210268, 5182.663126896739, 5275.788968441258, 5286.5242128208265, 5289.365276166846, 5354.330708661418, 4856.474633190756, 4860.1737704590305, 4860.756962151878, 4881.170201065792, 4887.095887260868, 5677.290338707661, 5727.570432217601, 5728.427157284238, 5729.624890026405, 5737.684427356494, 4037.2934736421007, 4126.236646220429, 4136.937945930783, 4146.295129827937, 4149.958500414988, 5949.940500594982, 5969.910451343233, 5982.940170598282, 5988.371221021817, 6008.459238668497, 4208.473940757405, 4218.472690913636, 4222.746635201836, 4224.387463817729, 4232.449781528381, 4074.1647962167685, 4270.42349282849, 4281.443412356373, 4286.721363111388, 4287.378330142112, 4856.708597484092, 4867.9269810952865, 4898.951010489921, 4913.852584422473, 4931.827386041471, 5730.455606717439, 5791.8552036199435, 5795.623284486495, 5821.825345239649, 5858.472737453564, 1387.2300873015574, 1413.9858601413855, 1425.201886943315, 1464.1215270837413, 1468.12646475347, 925.1396201532505, 931.8695382646434, 941.3410612571228, 948.796008858085, 961.0917682789836, 4965.627577931669, 4984.22744474607, 5004.448620927524, 5006.89986200274, 5014.771381011676, 5268.736563171826, 5276.736163191825, 5287.4976877196605, 5300.787968481218, 5316.202569614588, 4839.854804355875, 4983.950160498421, 5000.924986125211, 5010.872553675394, 5019.121653710586, 2816.943661126766, 2832.7025662305573, 2834.4047749972324, 2840.6875243723175, 2848.4160747046803, 5379.757910894019, 5380.498068398214, 5413.133898576288, 5416.241726158341, 5470.316210473691, 5175.4824517548695, 5268.972550352644, 5308.681479111266, 5325.920111198335, 5368.516833485005, 5363.597730170253, 5364.14173732201, 5364.363869019909, 5377.919331210035, 5406.83779486616, 5243.614155559288, 5281.683099014072, 5286.656367336111, 5294.311739473844, 5336.813211537733, 4954.950450495449, 5311.893762124813, 5315.920261196008, 5334.0, 5336.7598458068705, 5327.3074500314715, 5429.239906413184, 5444.3194600674915, 5455.399906010415, 5505.587080969023, 3837.6354246346555, 3862.555806082224, 3883.3980732986433, 3891.669208117342, 3926.8232929517667, 4952.2819191217795, 5226.869328266824, 5229.372475302988, 5342.75957581902, 5730.71346432685, 5671.773129074956, 5867.178594996619, 5918.437748413894, 5926.644401335933, 5927.733252003756, 5786.3056433228285, 5838.591298609056, 5881.382454842351, 5885.411458854081, 5902.793402230901, 5550.458715596234, 5769.653820770599, 5782.913256301243, 5786.85532861665, 5814.389489103753, 5830.387809279964, 5865.794697185578, 5871.295444546511, 5913.29040515141, 5935.40645935403, 5404.78380864761, 5776.826695199151, 5802.303723553201, 5810.912836307543, 5822.0684690449325], "value": 4232.537986699296, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [796.1441450440774, 814.7881550796791, 837.5686521441462, 859.4628357276703, 934.3366209990907, 15466.536003919873, 15652.84347156528, 15823.762643560342, 15835.524934251976, 17650.470485885413, 15548.06711597305, 15758.133302668348, 16018.519444416657, 16019.157796853366, 16082.115483648395, 743.6430513353594, 859.3468963587655, 880.8634661627458, 899.4558292233203, 900.455224589124, 897.1477096758073, 897.2059727141461, 899.9145081217307, 900.6082354175921, 900.9864852027225, 952.0527075559828, 955.7085089047872, 956.4739393333673, 957.952102394881, 959.6737109382808, 772.9033870766154, 806.1213277527464, 903.7243640689587, 942.5522876633613, 948.9620415183384, 690.0442886602042, 691.7129391302603, 693.4833549006023, 693.552658535247, 693.819606902204, 16368.345266189457, 16377.918110409491, 16387.0, 16401.42595009181, 16448.095354755485, 14735.0, 14841.7773733393, 14888.553343399715, 15045.097294162275, 15245.0, 13302.534411295559, 13427.3957671905, 13458.461661533625, 13472.865271347258, 13648.93175534116, 4163.3130533461945, 4198.076423186894, 4203.600657937522, 4233.745975241495, 4340.0, 77646.0, 79207.81188282238, 79583.0, 80096.19903801003, 80264.0, 12950.676233094247, 13133.0, 13183.0, 13334.666633334244, 13390.397432115578, 25260.494790104094, 25295.494090118464, 25349.873250633627, 25366.873165634053, 25368.238952831445, 9533.237341012702, 9592.568334424828, 9601.951990240004, 9611.759706007266, 10074.899251007542, 5223.381420655948, 5245.501677340647, 5280.075986702312, 5299.417064122943, 5421.512063914267, 3817.656410922975, 3934.016495876031, 3934.6065393460426, 3937.7046721496004, 3938.0, 664.8072059102902, 677.383580941341, 679.6805501414327, 686.8454597715574, 694.8089275449191, 718.8813845715503, 822.4160845799549, 839.4585492357395, 840.281559266818, 847.1063028504987, 10865.48301523125, 11115.610953616746, 11124.944375278072, 11275.774484510428, 11319.830202546967, 690.4821383962028, 805.135350318469, 830.1449507007792, 848.0628905059943, 855.39267120344, 5173.32746742921, 5377.300950876439, 5432.375276843213, 5512.145617429305, 5527.115661494142, 69608.0, 74465.6276718613, 110297.44851275692, 118920.0, 119174.0, 100725.99999999853, 101973.0, 102791.0, 103058.48470757749, 103213.48393257985, 55809.0, 58151.12773308404, 83933.0, 89465.55267223623, 90359.54820225989, 9920.849564548716, 10174.236932229964, 10185.236107291834, 10196.490175491048, 10255.538500767334, 54355.45644543504, 54487.999999998414, 54650.0, 56048.0, 56346.7182664084, 75237.24762752303, 77476.61261693882, 78678.6066069666, 79350.0, 81625.59187204026, 12910.548130815376, 13078.476860925457, 13163.67090822737, 13211.274439422057, 13444.462221511421, 14177.645558861112, 14198.148111113367, 14243.928780356031, 14607.19660418687, 14737.11577305365, 13716.451341946211, 13839.169649821042, 14031.929840350733, 14039.368228429541, 14365.569032929028, 12884.291363975071, 13373.799393008918, 13461.730765384833, 13662.658433539242, 13736.0, 13361.26513041792, 13521.0, 13863.0, 13875.861241387456, 14079.577612671636, 11455.369954652575, 11698.0, 12011.918927296585, 12031.819522706992, 12157.878421215675, 10169.9491502542, 10962.342259464458, 10977.890221097687, 10995.835062473909, 11069.384656872253], "value": 8052.073150754236, "unit": "score"}], "name": "dromaeo_css", "value": 7500.392312233863, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 103.0, 108.0, 110.0, 112.0, 110.0, 110.0, 109.0, 110.0, 110.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 124.0, 125.0, 187.0, 127.0, 124.0, 152.0, 129.0, 151.0, 122.0], "value": 139.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 161.0, 160.0, 161.0, 161.0, 161.0, 160.0, 160.0, 161.0, 161.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 133.0, 75.0, 76.0, 75.0, 74.0, 98.0, 76.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 108.0, 85.0, 85.0, 84.0, 119.0, 84.0, 84.0, 132.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 118.0, 116.0, 116.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 96.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 51.0, 52.0, 51.0, 52.0, 53.0, 54.0, 51.0, 51.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 61.0, 61.0, 61.0, 60.0, 61.0, 61.0, 62.0], "value": 60.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 82.0, 81.0, 79.0, 84.0, 77.0, 78.0, 109.0, 82.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 119.0, 116.0, 131.0, 116.0, 116.0, 118.0, 117.0, 139.0, 126.0], "value": 121.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 158.0, 156.0, 155.0, 159.0, 160.0, 155.0, 159.0, 152.0, 154.0], "value": 156.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 64.0, 62.0, 62.0, 61.0, 62.0, 61.0, 62.0, 62.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1479.3, "alertThreshold": 2.0}]} 09:54:07 INFO - Return code: 0 09:54:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:54:07 ERROR - # TBPL SUCCESS # 09:54:07 INFO - Running post-action listener: _resource_record_post_action 09:54:07 INFO - ##### 09:54:07 INFO - ##### Finished run-tests step (success) 09:54:07 INFO - ##### 09:54:07 INFO - Running post-run listener: _resource_record_post_run 09:54:07 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 7917568; Write bytes: 382001152; Read time: 1396; Write time: 258212 09:54:07 INFO - TinderboxPrint: CPU usage
13.1% 09:54:07 INFO - TinderboxPrint: I/O read bytes / time
7,917,568 / 1,396 09:54:07 INFO - TinderboxPrint: I/O write bytes / time
382,001,152 / 258,212 09:54:07 INFO - TinderboxPrint: CPU idle
8,254.6 (86.8%) 09:54:07 INFO - TinderboxPrint: CPU user
1,206.1 (12.7%) 09:54:07 INFO - TinderboxPrint: Swap in / out
0 / 0 09:54:07 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 60678144; Read time: 0; Write time: 21320 09:54:07 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 6156288; Write bytes: 239661056; Read time: 1172; Write time: 131000 09:54:07 INFO - Running post-run listener: _upload_blobber_files 09:54:07 INFO - Blob upload gear active. 09:54:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:54:07 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:54:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:54:08 INFO - (blobuploader) - INFO - Open directory for files ... 09:54:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:54:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:54:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:09 INFO - (blobuploader) - INFO - Done attempting. 09:54:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:54:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:54:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:09 INFO - (blobuploader) - INFO - Done attempting. 09:54:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:54:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:54:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:15 INFO - (blobuploader) - INFO - Done attempting. 09:54:15 INFO - (blobuploader) - INFO - Iteration through files over. 09:54:15 INFO - Return code: 0 09:54:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:54:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:54:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7faf9dab37e8efe42b288ecc8a4f0f517cc3fae9fc59da0243a987cc3f630371b08dcb1cd914e79e853f997455da7413de67b2445f44e7d2e860974eebf25328", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f2df092cd92a037e48e1160c147a8bfcfebce85492f0145932d70108bcb4ceee112f9a9ae93be820f42b37e1c49017848be1a827e83f9b0f967261428923d82", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b7ac04fc9d73100d4ea4a7a329b5f7f6ae93d8cb40eb669f269ada1e5879b0b6c4534ba63a4a882d83039c3b32aaf5a525bf986646c6f91d7e40941da5f6182"} 09:54:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:54:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:54:15 INFO - Contents: 09:54:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7faf9dab37e8efe42b288ecc8a4f0f517cc3fae9fc59da0243a987cc3f630371b08dcb1cd914e79e853f997455da7413de67b2445f44e7d2e860974eebf25328", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f2df092cd92a037e48e1160c147a8bfcfebce85492f0145932d70108bcb4ceee112f9a9ae93be820f42b37e1c49017848be1a827e83f9b0f967261428923d82", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b7ac04fc9d73100d4ea4a7a329b5f7f6ae93d8cb40eb669f269ada1e5879b0b6c4534ba63a4a882d83039c3b32aaf5a525bf986646c6f91d7e40941da5f6182"} 09:54:15 INFO - Running post-run listener: copy_logs_to_upload_dir 09:54:15 INFO - Copying logs to upload dir... 09:54:15 INFO - mkdir: /builds/slave/test/build/upload/logs 09:54:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.244477 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-06-20 09:54:14.986361) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-20 09:54:14.989964) ========= 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=337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580 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/7faf9dab37e8efe42b288ecc8a4f0f517cc3fae9fc59da0243a987cc3f630371b08dcb1cd914e79e853f997455da7413de67b2445f44e7d2e860974eebf25328", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f2df092cd92a037e48e1160c147a8bfcfebce85492f0145932d70108bcb4ceee112f9a9ae93be820f42b37e1c49017848be1a827e83f9b0f967261428923d82", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b7ac04fc9d73100d4ea4a7a329b5f7f6ae93d8cb40eb669f269ada1e5879b0b6c4534ba63a4a882d83039c3b32aaf5a525bf986646c6f91d7e40941da5f6182"} build_url:https://queue.taskcluster.net/v1/task/X3lQo7cmR8ClepaUaTebYA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011126 build_url: 'https://queue.taskcluster.net/v1/task/X3lQo7cmR8ClepaUaTebYA/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/7faf9dab37e8efe42b288ecc8a4f0f517cc3fae9fc59da0243a987cc3f630371b08dcb1cd914e79e853f997455da7413de67b2445f44e7d2e860974eebf25328", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f2df092cd92a037e48e1160c147a8bfcfebce85492f0145932d70108bcb4ceee112f9a9ae93be820f42b37e1c49017848be1a827e83f9b0f967261428923d82", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b7ac04fc9d73100d4ea4a7a329b5f7f6ae93d8cb40eb669f269ada1e5879b0b6c4534ba63a4a882d83039c3b32aaf5a525bf986646c6f91d7e40941da5f6182"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-20 09:54:15.020169) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 09:54:15.020499) ========= 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=337fe3f4b1febe37b85fccc40000019e-1466438801.925345-22308580 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004388 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 09:54:15.103473) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-20 09:54:15.104302) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-20 09:54:15.105011) ========= ========= Total master_lag: 0.18 =========