builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-021 starttime: 1466332345.67 results: success (0) buildid: 20160619004049 builduid: 8b35016ce1a94f7f87f61ec10515de0c revision: 541035fe27cb71fd6fdcd273c5190edd688f0731 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-19 03:32:25.670238) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-19 03:32:25.670664) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-19 03:32:25.670940) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-19 03:32:25.703906) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-19 03:32:25.704168) ========= 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=df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-19 03:32:27-- 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% 38.0M=0s 2016-06-19 03:32:27 (38.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.342867 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-19 03:32:26.060479) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-19 03:32:26.060807) ========= 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=df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020085 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-19 03:32:26.111111) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-19 03:32:26.111441) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 541035fe27cb71fd6fdcd273c5190edd688f0731 --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 541035fe27cb71fd6fdcd273c5190edd688f0731 --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=df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-19 03:32:27,688 truncating revision to first 12 chars 2016-06-19 03:32:27,688 Setting DEBUG logging. 2016-06-19 03:32:27,688 attempt 1/10 2016-06-19 03:32:27,689 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/541035fe27cb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-19 03:32:27,984 unpacking tar archive at: mozilla-aurora-541035fe27cb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.631956 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-19 03:32:26.757787) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-19 03:32:26.758100) ========= script_repo_revision: 541035fe27cb71fd6fdcd273c5190edd688f0731 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-19 03:32:26.758523) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-19 03:32:26.758796) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-19 03:32:26.767389) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-06-19 03:32:26.767792) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:32:28 INFO - MultiFileLogger online at 20160619 03:32:28 in /builds/slave/test 03:32:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:32:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:32:28 INFO - {'append_to_log': False, 03:32:28 INFO - 'base_work_dir': '/builds/slave/test', 03:32:28 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:32:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:32:28 INFO - 'branch': 'Mozilla-Aurora', 03:32:28 INFO - 'buildbot_json_path': 'buildprops.json', 03:32:28 INFO - 'config_files': ('talos/linux_config.py',), 03:32:28 INFO - 'default_actions': ('clobber', 03:32:28 INFO - 'read-buildbot-config', 03:32:28 INFO - 'download-and-extract', 03:32:28 INFO - 'populate-webroot', 03:32:28 INFO - 'create-virtualenv', 03:32:28 INFO - 'install', 03:32:28 INFO - 'run-tests'), 03:32:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:32:28 INFO - 'download_minidump_stackwalk': True, 03:32:28 INFO - 'download_symbols': 'ondemand', 03:32:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:32:28 INFO - 'tooltool.py': '/tools/tooltool.py', 03:32:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:32:28 INFO - '/tools/misc-python/virtualenv.py')}, 03:32:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:32:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:32:28 INFO - 'installer_path': 'installer.exe', 03:32:28 INFO - 'log_level': 'info', 03:32:28 INFO - 'log_name': 'talos', 03:32:28 INFO - 'log_to_console': True, 03:32:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:32:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:32:28 INFO - 'opt_config_files': (), 03:32:28 INFO - 'pip_index': False, 03:32:28 INFO - 'sps_profile': False, 03:32:28 INFO - 'sps_profile_interval': 0, 03:32:28 INFO - 'suite': 'dromaeojs-e10s', 03:32:28 INFO - 'system_bits': '32', 03:32:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:32:28 INFO - 'title': 'talos-linux64-ix-021', 03:32:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:32:28 INFO - 'use_talos_json': True, 03:32:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:32:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:32:28 INFO - 'work_dir': 'build'} 03:32:28 INFO - ##### 03:32:28 INFO - ##### Running clobber step. 03:32:28 INFO - ##### 03:32:28 INFO - Running pre-action listener: _resource_record_pre_action 03:32:28 INFO - Running main action method: clobber 03:32:28 INFO - rmtree: /builds/slave/test/build 03:32:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:32:29 INFO - Running post-action listener: _resource_record_post_action 03:32:29 INFO - ##### 03:32:29 INFO - ##### Finished clobber step (success) 03:32:29 INFO - ##### 03:32:29 INFO - ##### 03:32:29 INFO - ##### Running read-buildbot-config step. 03:32:29 INFO - ##### 03:32:29 INFO - Running pre-action listener: _resource_record_pre_action 03:32:29 INFO - Running main action method: read_buildbot_config 03:32:29 INFO - Using buildbot properties: 03:32:29 INFO - { 03:32:29 INFO - "project": "", 03:32:29 INFO - "product": "firefox", 03:32:29 INFO - "script_repo_revision": "production", 03:32:29 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 03:32:29 INFO - "repository": "", 03:32:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 03:32:29 INFO - "buildid": "20160619004049", 03:32:29 INFO - "stage_platform": "linux64-pgo", 03:32:29 INFO - "basedir": "/builds/slave/test", 03:32:29 INFO - "nightly_build": "True", 03:32:29 INFO - "buildnumber": 359, 03:32:29 INFO - "slavename": "talos-linux64-ix-021", 03:32:29 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:32:29 INFO - "platform": "ubuntu64_hw", 03:32:29 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:32:29 INFO - "branch": "mozilla-aurora", 03:32:29 INFO - "repo_path": "releases/mozilla-aurora", 03:32:29 INFO - "revision": "541035fe27cb71fd6fdcd273c5190edd688f0731", 03:32:29 INFO - "pgo_build": "True", 03:32:29 INFO - "builduid": "8b35016ce1a94f7f87f61ec10515de0c", 03:32:29 INFO - "slavebuilddir": "test" 03:32:29 INFO - } 03:32:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 03:32:29 INFO - Running post-action listener: _resource_record_post_action 03:32:29 INFO - ##### 03:32:29 INFO - ##### Finished read-buildbot-config step (success) 03:32:29 INFO - ##### 03:32:29 INFO - ##### 03:32:29 INFO - ##### Running download-and-extract step. 03:32:29 INFO - ##### 03:32:29 INFO - Running pre-action listener: _resource_record_pre_action 03:32:29 INFO - Running main action method: download_and_extract 03:32:29 INFO - mkdir: /builds/slave/test/build/tests 03:32:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:29 INFO - https://queue.taskcluster.net/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:32:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:32:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:32:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:32:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:32:34 INFO - Downloaded 1361 bytes. 03:32:34 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:32:34 INFO - Using the following test package requirements: 03:32:34 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:32:34 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:34 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:32:34 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:34 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:32:34 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:34 INFO - u'jsshell-linux-x86_64.zip'], 03:32:34 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:34 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:32:34 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:32:34 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:34 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:32:34 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:34 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:32:34 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:34 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:32:34 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:34 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:32:34 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:34 INFO - https://queue.taskcluster.net/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:32:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:32:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:32:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:32:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:32:35 INFO - Downloaded 24474024 bytes. 03:32:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:32:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:32:36 INFO - Return code: 0 03:32:36 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:32:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:36 INFO - https://queue.taskcluster.net/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:32:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:32:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:32:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:32:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:32:39 INFO - Downloaded 24474024 bytes. 03:32:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:32:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:32:40 INFO - Return code: 0 03:32:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:40 INFO - https://queue.taskcluster.net/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:32:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:32:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:32:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:32:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:32:41 INFO - Downloaded 10833369 bytes. 03:32:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:32:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:32:41 INFO - Return code: 0 03:32:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:41 INFO - https://queue.taskcluster.net/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:32:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:32:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:32:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:32:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:32:43 INFO - Downloaded 63545884 bytes. 03:32:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:32:43 INFO - mkdir: /builds/slave/test/properties 03:32:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:32:43 INFO - Writing to file /builds/slave/test/properties/build_url 03:32:43 INFO - Contents: 03:32:43 INFO - build_url:https://queue.taskcluster.net/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:32:44 INFO - Running post-action listener: _resource_record_post_action 03:32:44 INFO - Running post-action listener: set_extra_try_arguments 03:32:44 INFO - ##### 03:32:44 INFO - ##### Finished download-and-extract step (success) 03:32:44 INFO - ##### 03:32:44 INFO - ##### 03:32:44 INFO - ##### Running populate-webroot step. 03:32:44 INFO - ##### 03:32:44 INFO - Running pre-action listener: _resource_record_pre_action 03:32:44 INFO - Running main action method: populate_webroot 03:32:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:32:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:32:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:32:44 INFO - '--tppagecycles', 03:32:44 INFO - '7'], 03:32:44 INFO - 'tests': ['tsvgm']}}, 03:32:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:32:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:32:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:32:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:32:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:32:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:32:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:44 INFO - 'talos_options': ['--disable-e10s'], 03:32:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:32:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:32:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:44 INFO - 'talos_options': ['--disable-e10s'], 03:32:44 INFO - 'tests': ['damp', 'tps']}, 03:32:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:44 INFO - 'tests': ['damp', 'tps']}, 03:32:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:32:44 INFO - 'tests': ['dromaeo_dom']}, 03:32:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:32:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:32:44 INFO - 'tests': ['a11yr', 03:32:44 INFO - 'ts_paint', 03:32:44 INFO - 'tpaint', 03:32:44 INFO - 'sessionrestore', 03:32:44 INFO - 'sessionrestore_no_auto_restore', 03:32:44 INFO - 'tabpaint']}, 03:32:44 INFO - 'other-e10s': {'tests': ['a11yr', 03:32:44 INFO - 'ts_paint', 03:32:44 INFO - 'tpaint', 03:32:44 INFO - 'sessionrestore', 03:32:44 INFO - 'sessionrestore_no_auto_restore', 03:32:44 INFO - 'tabpaint']}, 03:32:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:32:44 INFO - 'ts_paint', 03:32:44 INFO - 'tpaint', 03:32:44 INFO - 'sessionrestore', 03:32:44 INFO - 'sessionrestore_no_auto_restore', 03:32:44 INFO - 'tabpaint']}, 03:32:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:32:44 INFO - 'ts_paint', 03:32:44 INFO - 'tpaint', 03:32:44 INFO - 'sessionrestore', 03:32:44 INFO - 'sessionrestore_no_auto_restore', 03:32:44 INFO - 'tabpaint']}, 03:32:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:32:44 INFO - 'tests': ['a11yr', 03:32:44 INFO - 'ts_paint', 03:32:44 INFO - 'tpaint', 03:32:44 INFO - 'sessionrestore', 03:32:44 INFO - 'sessionrestore_no_auto_restore', 03:32:44 INFO - 'tabpaint']}, 03:32:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:32:44 INFO - 'tests': ['a11yr', 03:32:44 INFO - 'ts_paint', 03:32:44 INFO - 'tpaint', 03:32:44 INFO - 'sessionrestore', 03:32:44 INFO - 'sessionrestore_no_auto_restore', 03:32:44 INFO - 'tabpaint']}, 03:32:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:32:44 INFO - 'tests': ['tsvgx', 03:32:44 INFO - 'tsvgr_opacity', 03:32:44 INFO - 'tart', 03:32:44 INFO - 'tscrollx', 03:32:44 INFO - 'cart']}, 03:32:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:32:44 INFO - 'tsvgr_opacity', 03:32:44 INFO - 'tart', 03:32:44 INFO - 'tscrollx', 03:32:44 INFO - 'cart']}, 03:32:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:44 INFO - 'talos_options': ['--disable-e10s'], 03:32:44 INFO - 'tests': ['tp5o']}, 03:32:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:44 INFO - 'tests': ['tp5o']}, 03:32:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:44 INFO - 'talos_options': ['--disable-e10s', 03:32:44 INFO - '--xperf_path', 03:32:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:32:44 INFO - 'tests': ['tp5n']}, 03:32:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:44 INFO - 'talos_options': ['--xperf_path', 03:32:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:32:44 INFO - 'tests': ['tp5n']}}, 03:32:44 INFO - 'talos.zip': {'path': '', 03:32:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:32:44 INFO - Running post-action listener: _resource_record_post_action 03:32:44 INFO - ##### 03:32:44 INFO - ##### Finished populate-webroot step (success) 03:32:44 INFO - ##### 03:32:44 INFO - ##### 03:32:44 INFO - ##### Running create-virtualenv step. 03:32:44 INFO - ##### 03:32:44 INFO - Running pre-action listener: _resource_record_pre_action 03:32:44 INFO - Running main action method: create_virtualenv 03:32:44 INFO - Creating virtualenv /builds/slave/test/build/venv 03:32:44 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:32:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:32:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:32:44 INFO - Using real prefix '/usr' 03:32:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:32:44 INFO - Installing distribute.............................................................................................................................................................................................done. 03:32:46 INFO - Installing pip.................done. 03:32:46 INFO - Return code: 0 03:32:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:32: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')]} 03:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32: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')]} 03:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f005ca7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa41c0>, '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 0x1fa50c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa5550>, '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': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:32:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:32:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:32:46 INFO - Using env: {'DISPLAY': ':0', 03:32:46 INFO - 'HOME': '/home/cltbld', 03:32:46 INFO - 'LANG': 'en_US.UTF-8', 03:32:46 INFO - 'LANGUAGE': 'en_US:en', 03:32:46 INFO - 'LOGNAME': 'cltbld', 03:32:46 INFO - 'MAIL': '/var/mail/cltbld', 03:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:46 INFO - 'MOZ_NO_REMOTE': '1', 03:32:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:46 INFO - 'NO_EM_RESTART': '1', 03:32:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:46 INFO - 'PWD': '/builds/slave/test', 03:32:46 INFO - 'SHELL': '/bin/bash', 03:32:46 INFO - 'SHLVL': '1', 03:32:46 INFO - 'TERM': 'linux', 03:32:46 INFO - 'TMOUT': '86400', 03:32:46 INFO - 'USER': 'cltbld', 03:32:46 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 03:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:46 INFO - '_': '/tools/buildbot/bin/python'} 03:32:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:32:47 INFO - Cleaning up... 03:32:47 INFO - Return code: 0 03:32:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f005ca7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa41c0>, '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 0x1fa50c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa5550>, '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': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:32:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:32:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:32:47 INFO - Using env: {'DISPLAY': ':0', 03:32:47 INFO - 'HOME': '/home/cltbld', 03:32:47 INFO - 'LANG': 'en_US.UTF-8', 03:32:47 INFO - 'LANGUAGE': 'en_US:en', 03:32:47 INFO - 'LOGNAME': 'cltbld', 03:32:47 INFO - 'MAIL': '/var/mail/cltbld', 03:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:47 INFO - 'MOZ_NO_REMOTE': '1', 03:32:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:47 INFO - 'NO_EM_RESTART': '1', 03:32:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:47 INFO - 'PWD': '/builds/slave/test', 03:32:47 INFO - 'SHELL': '/bin/bash', 03:32:47 INFO - 'SHLVL': '1', 03:32:47 INFO - 'TERM': 'linux', 03:32:47 INFO - 'TMOUT': '86400', 03:32:47 INFO - 'USER': 'cltbld', 03:32:47 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 03:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:47 INFO - '_': '/tools/buildbot/bin/python'} 03:32:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:47 INFO - Downloading/unpacking psutil>=3.1.1 03:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:32:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:32:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:32:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:32:49 INFO - Installing collected packages: psutil 03:32:49 INFO - Running setup.py install for psutil 03:32:49 INFO - building 'psutil._psutil_linux' extension 03:32:49 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:32:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:32:49 INFO - building 'psutil._psutil_posix' extension 03:32:49 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:32:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:32:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:32:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:32:49 INFO - Successfully installed psutil 03:32:49 INFO - Cleaning up... 03:32:49 INFO - Return code: 0 03:32:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:32:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f005ca7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa41c0>, '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 0x1fa50c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa5550>, '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': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:32:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:32:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:32:49 INFO - Using env: {'DISPLAY': ':0', 03:32:49 INFO - 'HOME': '/home/cltbld', 03:32:49 INFO - 'LANG': 'en_US.UTF-8', 03:32:49 INFO - 'LANGUAGE': 'en_US:en', 03:32:49 INFO - 'LOGNAME': 'cltbld', 03:32:49 INFO - 'MAIL': '/var/mail/cltbld', 03:32:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:49 INFO - 'MOZ_NO_REMOTE': '1', 03:32:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:49 INFO - 'NO_EM_RESTART': '1', 03:32:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:49 INFO - 'PWD': '/builds/slave/test', 03:32:49 INFO - 'SHELL': '/bin/bash', 03:32:49 INFO - 'SHLVL': '1', 03:32:49 INFO - 'TERM': 'linux', 03:32:49 INFO - 'TMOUT': '86400', 03:32:49 INFO - 'USER': 'cltbld', 03:32:49 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 03:32:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:49 INFO - '_': '/tools/buildbot/bin/python'} 03:32:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:32:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:32:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:32:52 INFO - Installing collected packages: mozsystemmonitor 03:32:52 INFO - Running setup.py install for mozsystemmonitor 03:32:52 INFO - Successfully installed mozsystemmonitor 03:32:52 INFO - Cleaning up... 03:32:52 INFO - Return code: 0 03:32:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:32: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')]} 03:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32: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')]} 03:32:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32: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', '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 0x7f005ca7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa41c0>, '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 0x1fa50c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa5550>, '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': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:32: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:32: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 jsonschema==2.5.1 03:32:52 INFO - Using env: {'DISPLAY': ':0', 03:32:52 INFO - 'HOME': '/home/cltbld', 03:32:52 INFO - 'LANG': 'en_US.UTF-8', 03:32:52 INFO - 'LANGUAGE': 'en_US:en', 03:32:52 INFO - 'LOGNAME': 'cltbld', 03:32:52 INFO - 'MAIL': '/var/mail/cltbld', 03:32:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:52 INFO - 'MOZ_NO_REMOTE': '1', 03:32:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:52 INFO - 'NO_EM_RESTART': '1', 03:32:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:52 INFO - 'PWD': '/builds/slave/test', 03:32:52 INFO - 'SHELL': '/bin/bash', 03:32:52 INFO - 'SHLVL': '1', 03:32:52 INFO - 'TERM': 'linux', 03:32:52 INFO - 'TMOUT': '86400', 03:32:52 INFO - 'USER': 'cltbld', 03:32:52 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 03:32:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:52 INFO - '_': '/tools/buildbot/bin/python'} 03:32:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:52 INFO - Downloading/unpacking jsonschema==2.5.1 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:32:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:32:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:54 INFO - Downloading functools32-3.2.3-2.tar.gz 03:32:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:32:54 INFO - warning: no files found matching '*.txt' 03:32:54 INFO - no previously-included directories found matching 'build' 03:32:54 INFO - no previously-included directories found matching 'dist' 03:32:54 INFO - no previously-included directories found matching '.git*' 03:32:54 INFO - Installing collected packages: jsonschema, functools32 03:32:54 INFO - Running setup.py install for functools32 03:32:54 INFO - warning: no files found matching '*.txt' 03:32:54 INFO - no previously-included directories found matching 'build' 03:32:54 INFO - no previously-included directories found matching 'dist' 03:32:54 INFO - no previously-included directories found matching '.git*' 03:32:54 INFO - Successfully installed jsonschema functools32 03:32:54 INFO - Cleaning up... 03:32:54 INFO - Return code: 0 03:32:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:32:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f005ca7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa41c0>, '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 0x1fa50c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa5550>, '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': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:32:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:32:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:32:54 INFO - Using env: {'DISPLAY': ':0', 03:32:54 INFO - 'HOME': '/home/cltbld', 03:32:54 INFO - 'LANG': 'en_US.UTF-8', 03:32:54 INFO - 'LANGUAGE': 'en_US:en', 03:32:54 INFO - 'LOGNAME': 'cltbld', 03:32:54 INFO - 'MAIL': '/var/mail/cltbld', 03:32:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:54 INFO - 'MOZ_NO_REMOTE': '1', 03:32:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:54 INFO - 'NO_EM_RESTART': '1', 03:32:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:54 INFO - 'PWD': '/builds/slave/test', 03:32:54 INFO - 'SHELL': '/bin/bash', 03:32:54 INFO - 'SHLVL': '1', 03:32:54 INFO - 'TERM': 'linux', 03:32:54 INFO - 'TMOUT': '86400', 03:32:54 INFO - 'USER': 'cltbld', 03:32:54 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 03:32:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:54 INFO - '_': '/tools/buildbot/bin/python'} 03:32:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:54 INFO - Downloading/unpacking blobuploader==1.2.4 03:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:32:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:56 INFO - Downloading blobuploader-1.2.4.tar.gz 03:32:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:32:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:32:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:32:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:57 INFO - Downloading docopt-0.6.1.tar.gz 03:32:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:32:57 INFO - Installing collected packages: blobuploader, requests, docopt 03:32:57 INFO - Running setup.py install for blobuploader 03:32:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:32:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:32:57 INFO - Running setup.py install for requests 03:32:58 INFO - Running setup.py install for docopt 03:32:58 INFO - Successfully installed blobuploader requests docopt 03:32:58 INFO - Cleaning up... 03:32:58 INFO - Return code: 0 03:32:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:32: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')]} 03:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32: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')]} 03:32:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:58 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 0x7f005ca7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa41c0>, '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 0x1fa50c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa5550>, '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': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:32:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:32:58 INFO - Using env: {'DISPLAY': ':0', 03:32:58 INFO - 'HOME': '/home/cltbld', 03:32:58 INFO - 'LANG': 'en_US.UTF-8', 03:32:58 INFO - 'LANGUAGE': 'en_US:en', 03:32:58 INFO - 'LOGNAME': 'cltbld', 03:32:58 INFO - 'MAIL': '/var/mail/cltbld', 03:32:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:58 INFO - 'MOZ_NO_REMOTE': '1', 03:32:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:58 INFO - 'NO_EM_RESTART': '1', 03:32:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:58 INFO - 'PWD': '/builds/slave/test', 03:32:58 INFO - 'SHELL': '/bin/bash', 03:32:58 INFO - 'SHLVL': '1', 03:32:58 INFO - 'TERM': 'linux', 03:32:58 INFO - 'TMOUT': '86400', 03:32:58 INFO - 'USER': 'cltbld', 03:32:58 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 03:32:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:58 INFO - '_': '/tools/buildbot/bin/python'} 03:32:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:32:58 INFO - Running setup.py (path:/tmp/pip-c9kVg4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:32:58 INFO - Running setup.py (path:/tmp/pip-hG01UB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:32:58 INFO - Running setup.py (path:/tmp/pip-QruRj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:32:58 INFO - Running setup.py (path:/tmp/pip-ODQKeN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:32:58 INFO - Running setup.py (path:/tmp/pip-bMtW4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:32:58 INFO - Running setup.py (path:/tmp/pip-ndnqY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:32:58 INFO - Running setup.py (path:/tmp/pip-FVehxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:32:58 INFO - Running setup.py (path:/tmp/pip-_GbtsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:32:59 INFO - Running setup.py (path:/tmp/pip-RTuMY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:32:59 INFO - Running setup.py (path:/tmp/pip-aPpVXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:32:59 INFO - Running setup.py (path:/tmp/pip-Hu2ST3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:32:59 INFO - Running setup.py (path:/tmp/pip-U1CX6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:32:59 INFO - Running setup.py (path:/tmp/pip-fTbkfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:32:59 INFO - Running setup.py (path:/tmp/pip-iNnnNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:32:59 INFO - Running setup.py (path:/tmp/pip-Pse39g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:32:59 INFO - Running setup.py (path:/tmp/pip-C_htWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:32:59 INFO - Running setup.py (path:/tmp/pip-SFLEoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:32:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:32:59 INFO - Running setup.py install for manifestparser 03:32:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:32:59 INFO - Running setup.py install for mozcrash 03:32:59 INFO - Running setup.py install for mozdebug 03:32:59 INFO - Running setup.py install for mozdevice 03:33:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:33:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:33:00 INFO - Running setup.py install for mozfile 03:33:00 INFO - Running setup.py install for mozhttpd 03:33:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:33:00 INFO - Running setup.py install for mozinfo 03:33:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:33:00 INFO - Running setup.py install for mozInstall 03:33:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:33:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:33:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:33:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:33:00 INFO - Running setup.py install for mozleak 03:33:00 INFO - Running setup.py install for mozlog 03:33:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:33:00 INFO - Running setup.py install for moznetwork 03:33:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:33:00 INFO - Running setup.py install for mozprocess 03:33:00 INFO - Running setup.py install for mozprofile 03:33:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:33:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:33:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:33:00 INFO - Running setup.py install for mozrunner 03:33:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:33:01 INFO - Running setup.py install for mozscreenshot 03:33:01 INFO - Running setup.py install for moztest 03:33:01 INFO - Running setup.py install for mozversion 03:33:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:33:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:33:01 INFO - Cleaning up... 03:33:01 INFO - Return code: 0 03:33:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:33: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')]} 03:33:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33: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')]} 03:33:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:01 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 0x7f005ca7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa41c0>, '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 0x1fa50c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa5550>, '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': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:33:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:33:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:33:01 INFO - Using env: {'DISPLAY': ':0', 03:33:01 INFO - 'HOME': '/home/cltbld', 03:33:01 INFO - 'LANG': 'en_US.UTF-8', 03:33:01 INFO - 'LANGUAGE': 'en_US:en', 03:33:01 INFO - 'LOGNAME': 'cltbld', 03:33:01 INFO - 'MAIL': '/var/mail/cltbld', 03:33:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:01 INFO - 'MOZ_NO_REMOTE': '1', 03:33:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:33:01 INFO - 'NO_EM_RESTART': '1', 03:33:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:33:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:01 INFO - 'PWD': '/builds/slave/test', 03:33:01 INFO - 'SHELL': '/bin/bash', 03:33:01 INFO - 'SHLVL': '1', 03:33:01 INFO - 'TERM': 'linux', 03:33:01 INFO - 'TMOUT': '86400', 03:33:01 INFO - 'USER': 'cltbld', 03:33:01 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 03:33:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:01 INFO - '_': '/tools/buildbot/bin/python'} 03:33:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:33:01 INFO - Running setup.py (path:/tmp/pip-7h5VUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:33:01 INFO - Running setup.py (path:/tmp/pip-_Ksdxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:33:01 INFO - Running setup.py (path:/tmp/pip-cOxYE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:33:01 INFO - Running setup.py (path:/tmp/pip-NkhYsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:33:01 INFO - Running setup.py (path:/tmp/pip-QqGEwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:33:02 INFO - Running setup.py (path:/tmp/pip-uHGv89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:33:02 INFO - Running setup.py (path:/tmp/pip-ZO3ajQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:33:02 INFO - Running setup.py (path:/tmp/pip-dLJnwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:33:02 INFO - Running setup.py (path:/tmp/pip-0UKXfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:33:02 INFO - Running setup.py (path:/tmp/pip-O6jzxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:33:02 INFO - Running setup.py (path:/tmp/pip-fvO5Cg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:33:02 INFO - Running setup.py (path:/tmp/pip-88n02a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:33:02 INFO - Running setup.py (path:/tmp/pip-2VDdWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:33:02 INFO - Running setup.py (path:/tmp/pip-fvk889-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:33:02 INFO - Running setup.py (path:/tmp/pip-Jixp95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:33:02 INFO - Running setup.py (path:/tmp/pip-ZYgaR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:33:02 INFO - Running setup.py (path:/tmp/pip-3CkmRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:33:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:04 INFO - Downloading blessings-1.6.tar.gz 03:33:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:33:04 INFO - Installing collected packages: blessings 03:33:04 INFO - Running setup.py install for blessings 03:33:04 INFO - Successfully installed blessings 03:33:04 INFO - Cleaning up... 03:33:05 INFO - Return code: 0 03:33:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:33:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:33:05 INFO - Reading from file tmpfile_stdout 03:33:05 INFO - Current package versions: 03:33:05 INFO - argparse == 1.2.1 03:33:05 INFO - blessings == 1.6 03:33:05 INFO - blobuploader == 1.2.4 03:33:05 INFO - docopt == 0.6.1 03:33:05 INFO - functools32 == 3.2.3-2 03:33:05 INFO - jsonschema == 2.5.1 03:33:05 INFO - manifestparser == 1.1 03:33:05 INFO - mozInstall == 1.12 03:33:05 INFO - mozcrash == 0.17 03:33:05 INFO - mozdebug == 0.1 03:33:05 INFO - mozdevice == 0.48 03:33:05 INFO - mozfile == 1.2 03:33:05 INFO - mozhttpd == 0.7 03:33:05 INFO - mozinfo == 0.9 03:33:05 INFO - mozleak == 0.1 03:33:05 INFO - mozlog == 3.2 03:33:05 INFO - moznetwork == 0.27 03:33:05 INFO - mozprocess == 0.22 03:33:05 INFO - mozprofile == 0.28 03:33:05 INFO - mozrunner == 6.11 03:33:05 INFO - mozscreenshot == 0.1 03:33:05 INFO - mozsystemmonitor == 0.3 03:33:05 INFO - moztest == 0.7 03:33:05 INFO - mozversion == 1.4 03:33:05 INFO - psutil == 3.1.1 03:33:05 INFO - requests == 1.2.3 03:33:05 INFO - wsgiref == 0.1.2 03:33:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:05 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 0x7f005ca7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa41c0>, '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 0x1fa50c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa5550>, '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': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:33:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:33:05 INFO - Using env: {'DISPLAY': ':0', 03:33:05 INFO - 'HOME': '/home/cltbld', 03:33:05 INFO - 'LANG': 'en_US.UTF-8', 03:33:05 INFO - 'LANGUAGE': 'en_US:en', 03:33:05 INFO - 'LOGNAME': 'cltbld', 03:33:05 INFO - 'MAIL': '/var/mail/cltbld', 03:33:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:05 INFO - 'MOZ_NO_REMOTE': '1', 03:33:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:33:05 INFO - 'NO_EM_RESTART': '1', 03:33:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:33:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:05 INFO - 'PWD': '/builds/slave/test', 03:33:05 INFO - 'SHELL': '/bin/bash', 03:33:05 INFO - 'SHLVL': '1', 03:33:05 INFO - 'TERM': 'linux', 03:33:05 INFO - 'TMOUT': '86400', 03:33:05 INFO - 'USER': 'cltbld', 03:33:05 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 03:33:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:05 INFO - '_': '/tools/buildbot/bin/python'} 03:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:33:05 INFO - Cleaning up... 03:33:05 INFO - Return code: 0 03:33:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f005ca7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa41c0>, '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 0x1fa50c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa5550>, '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': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:33:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:33:05 INFO - Using env: {'DISPLAY': ':0', 03:33:05 INFO - 'HOME': '/home/cltbld', 03:33:05 INFO - 'LANG': 'en_US.UTF-8', 03:33:05 INFO - 'LANGUAGE': 'en_US:en', 03:33:05 INFO - 'LOGNAME': 'cltbld', 03:33:05 INFO - 'MAIL': '/var/mail/cltbld', 03:33:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:05 INFO - 'MOZ_NO_REMOTE': '1', 03:33:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:33:05 INFO - 'NO_EM_RESTART': '1', 03:33:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:33:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:05 INFO - 'PWD': '/builds/slave/test', 03:33:05 INFO - 'SHELL': '/bin/bash', 03:33:05 INFO - 'SHLVL': '1', 03:33:05 INFO - 'TERM': 'linux', 03:33:05 INFO - 'TMOUT': '86400', 03:33:05 INFO - 'USER': 'cltbld', 03:33:05 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 03:33:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:05 INFO - '_': '/tools/buildbot/bin/python'} 03:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:33:05 INFO - Cleaning up... 03:33:05 INFO - Return code: 0 03:33:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f005ca7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa41c0>, '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 0x1fa50c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa5550>, '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': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:33:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:33:05 INFO - Using env: {'DISPLAY': ':0', 03:33:05 INFO - 'HOME': '/home/cltbld', 03:33:05 INFO - 'LANG': 'en_US.UTF-8', 03:33:05 INFO - 'LANGUAGE': 'en_US:en', 03:33:05 INFO - 'LOGNAME': 'cltbld', 03:33:05 INFO - 'MAIL': '/var/mail/cltbld', 03:33:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:05 INFO - 'MOZ_NO_REMOTE': '1', 03:33:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:33:05 INFO - 'NO_EM_RESTART': '1', 03:33:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:33:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:05 INFO - 'PWD': '/builds/slave/test', 03:33:05 INFO - 'SHELL': '/bin/bash', 03:33:05 INFO - 'SHLVL': '1', 03:33:05 INFO - 'TERM': 'linux', 03:33:05 INFO - 'TMOUT': '86400', 03:33:05 INFO - 'USER': 'cltbld', 03:33:05 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 03:33:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:05 INFO - '_': '/tools/buildbot/bin/python'} 03:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:05 INFO - Downloading/unpacking flake8 03:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:33:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:33:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:33:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:33:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:33:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:33:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:33:08 INFO - no previously-included directories found matching 'docs/_build' 03:33:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:33:08 INFO - Running setup.py install for pep8 03:33:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:33:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:33:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:33:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:33:08 INFO - no previously-included directories found matching 'docs/_build' 03:33:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:33:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:33:08 INFO - Cleaning up... 03:33:08 INFO - Return code: 0 03:33:08 INFO - Running post-action listener: _resource_record_post_action 03:33:08 INFO - Running post-action listener: _start_resource_monitoring 03:33:08 INFO - Starting resource monitoring. 03:33:08 INFO - ##### 03:33:08 INFO - ##### Finished create-virtualenv step (success) 03:33:08 INFO - ##### 03:33:08 INFO - ##### 03:33:08 INFO - ##### Running install step. 03:33:08 INFO - ##### 03:33:08 INFO - Running pre-action listener: _resource_record_pre_action 03:33:08 INFO - Running main action method: install 03:33:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:33:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:33:08 INFO - Reading from file tmpfile_stdout 03:33:08 INFO - Detecting whether we're running mozinstall >=1.0... 03:33:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:33:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:33:09 INFO - Reading from file tmpfile_stdout 03:33:09 INFO - Output received: 03:33:09 INFO - Usage: mozinstall [options] installer 03:33:09 INFO - Options: 03:33:09 INFO - -h, --help show this help message and exit 03:33:09 INFO - -d DEST, --destination=DEST 03:33:09 INFO - Directory to install application into. [default: 03:33:09 INFO - "/builds/slave/test"] 03:33:09 INFO - --app=APP Application being installed. [default: firefox] 03:33:09 INFO - mkdir: /builds/slave/test/build/application 03:33:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:33:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:33:24 INFO - Reading from file tmpfile_stdout 03:33:24 INFO - Output received: 03:33:24 INFO - /builds/slave/test/build/application/firefox/firefox 03:33:24 INFO - Running post-action listener: _resource_record_post_action 03:33:24 INFO - ##### 03:33:24 INFO - ##### Finished install step (success) 03:33:24 INFO - ##### 03:33:24 INFO - ##### 03:33:24 INFO - ##### Running run-tests step. 03:33:24 INFO - ##### 03:33:24 INFO - Running pre-action listener: _resource_record_pre_action 03:33:24 INFO - Running main action method: run_tests 03:33:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:33:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:33:24 INFO - Python 2.7.3 03:33:24 INFO - Return code: 0 03:33:24 INFO - grabbing minidump binary from tooltool 03:33:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:24 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa41c0>, '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 0x1fa50c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa5550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:33:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:33:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:33:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:33:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:33:25 INFO - Return code: 0 03:33:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:33:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:33:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:33:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:33:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:33:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:33:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:33:25 INFO - Return code: 0 03:33:25 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:33:25 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:33:25 INFO - Using env: {'DISPLAY': ':0', 03:33:25 INFO - 'HOME': '/home/cltbld', 03:33:25 INFO - 'LANG': 'en_US.UTF-8', 03:33:25 INFO - 'LANGUAGE': 'en_US:en', 03:33:25 INFO - 'LOGNAME': 'cltbld', 03:33:25 INFO - 'MAIL': '/var/mail/cltbld', 03:33:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:33:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:33:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:25 INFO - 'MOZ_NO_REMOTE': '1', 03:33:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:33:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:33:25 INFO - 'NO_EM_RESTART': '1', 03:33:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:33:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:25 INFO - 'PWD': '/builds/slave/test', 03:33:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:33:25 INFO - 'SHELL': '/bin/bash', 03:33:25 INFO - 'SHLVL': '1', 03:33:25 INFO - 'TERM': 'linux', 03:33:25 INFO - 'TMOUT': '86400', 03:33:25 INFO - 'USER': 'cltbld', 03:33:25 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813', 03:33:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:25 INFO - '_': '/tools/buildbot/bin/python'} 03:33:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:33:26 INFO - mozversion application_buildid: 20160619004049 03:33:26 INFO - mozversion application_changeset: 541035fe27cb71fd6fdcd273c5190edd688f0731 03:33:26 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:33:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:33:26 INFO - mozversion application_name: Firefox 03:33:26 INFO - mozversion application_remotingname: firefox-dev 03:33:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:33:26 INFO - mozversion application_vendor: Mozilla 03:33:26 INFO - mozversion application_version: 49.0a2 03:33:26 INFO - mozversion platform_buildid: 20160619004049 03:33:26 INFO - mozversion platform_changeset: 541035fe27cb71fd6fdcd273c5190edd688f0731 03:33:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:33:26 INFO - mozversion platform_version: 49.0a2 03:33:26 INFO - using testdate: 1466332406 03:33:26 INFO - actual date: 1466332406 03:33:26 INFO - starting webserver on 'localhost:55836' 03:33:26 INFO - SUITE-START | Running 2 tests 03:33:26 INFO - TEST-START | dromaeo_css 03:33:26 INFO - operating with platform_type : linux_ 03:33:26 INFO - Initialising browser for dromaeo_css test... 03:33:26 INFO - TEST-INFO | started process 24884 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRGTcL8/profile http://localhost:55836/getInfo.html) 03:33:32 INFO - PROCESS | 24884 | __metrics Screen width/height:1600/1200 03:33:32 INFO - PROCESS | 24884 | colorDepth:24 03:33:32 INFO - PROCESS | 24884 | Browser inner width/height: 1024/697 03:33:32 INFO - PROCESS | 24884 | __metrics 03:33:32 ERROR - PROCESS | 24884 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2948: TypeError: this.tabs is undefined 03:33:33 INFO - TEST-INFO | 24884: exit 0 03:33:33 INFO - Browser initialized. 03:33:33 INFO - Running cycle 1/1 for dromaeo_css test... 03:33:33 INFO - TEST-INFO | started process 25012 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRGTcL8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:33:45 INFO - PROCESS | 25012 | RSS: Main: 155684864 03:33:45 INFO - PROCESS | 25012 | 03:34:35 INFO - PROCESS | 25012 | 1466332475127 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:36:42 INFO - PROCESS | 25012 | Cycle 1(1): loaded http://localhost:55836/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55836/tests/dromaeo/cssquery-ext.html) 03:36:43 INFO - PROCESS | 25012 | RSS: Main: 159698944 03:36:43 INFO - PROCESS | 25012 | 03:39:45 INFO - PROCESS | 25012 | Cycle 1(1): loaded http://localhost:55836/tests/dromaeo/cssquery-ext.html (next: http://localhost:55836/tests/dromaeo/cssquery-jquery.html) 03:39:46 INFO - PROCESS | 25012 | RSS: Main: 157683712 03:39:46 INFO - PROCESS | 25012 | 03:42:48 INFO - PROCESS | 25012 | Cycle 1(1): loaded http://localhost:55836/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55836/tests/dromaeo/cssquery-mootools.html) 03:42:48 INFO - PROCESS | 25012 | RSS: Main: 156958720 03:42:48 INFO - PROCESS | 25012 | 03:45:50 INFO - PROCESS | 25012 | Cycle 1(1): loaded http://localhost:55836/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55836/tests/dromaeo/cssquery-prototype.html) 03:45:51 INFO - PROCESS | 25012 | RSS: Main: 156778496 03:45:51 INFO - PROCESS | 25012 | 03:48:53 INFO - PROCESS | 25012 | Cycle 1(1): loaded http://localhost:55836/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55836/tests/dromaeo/cssquery-yui.html) 03:48:54 INFO - PROCESS | 25012 | RSS: Main: 158801920 03:48:54 INFO - PROCESS | 25012 | 03:51:56 INFO - PROCESS | 25012 | Cycle 1(1): loaded http://localhost:55836/tests/dromaeo/cssquery-yui.html (next: http://localhost:55836/tests/dromaeo/cssquery-dojo.html) 03:51:56 INFO - PROCESS | 25012 | RSS: Main: 159129600 03:51:56 INFO - PROCESS | 25012 | 03:51:56 INFO - PROCESS | 25012 | __start_tp_report 03:51:56 INFO - PROCESS | 25012 | _x_x_mozilla_page_load 03:51:56 INFO - PROCESS | 25012 | _x_x_mozilla_page_load_details 03:51:56 INFO - PROCESS | 25012 | |i|pagename|runs| 03:51:56 INFO - PROCESS | 25012 | |0;dojo.html;3367.5790526184223;3368.056944055665;3393.8133402662843;3403.523506709061;3407.625161232264;5548;5574.9721251393685;5610.410906854782;5619.988402087628;5644.887102257952;5631.18347839564;5635.718214089299;5675.6878371689645;5692.487676109149;5701.942980570193;5079.568236699894;5181.248718935752;5203.583713302926;5210.843674689765;5231.110711179109;5136.922946155792;5180.404253510838;5233.816816411427;5245.711485868275;5249.580033597322;5151.74241287936;5204.869878253036;5214.634975551713;5240.187770895498;5285.392179899322;5136.820211292606;5142.8714282143055;5215.556677682402;5220.112580861262;5223.791048358061;5077.64456488046;5140.383154021504;5193.883315087274;5205.011047900886;5232.110541208004;5432.456754324576;5432.972835135799;5483.643563168382;5489.58828087891;5547.944520554784;6049.455549000567;6070.969645151746;6081.756729730805;6095.878082438327;6097.42074502926;6138.324784273771;6153.569250152491;6158;6158.230221222348;6173.9073913891325;5534.363548191969;5633.6619802811965;5691.231683722686;5710.514606258432;5728;6228.252609686776;6254.124422580842;6265.780697675559;6275.717592708339;6289.0880822280515;6011.939880601226;6022.277326720821;6057.909131363033;6059.242594675666;6080.209572755513;5859.677717725481;6011.609245399035;6047.0627052806885;6094.238220222473;6174.197354343906;6087.4521293083835;6114.755409783559;6120.8775824484155;6134.631922084689;6138.263408390932;5908.497777688859;6114.327423983447;6115.908261376083;6120.846978825565;6127.632342059491;4560.791390281578;4570.223062079454;4592.517785632527;4605.424321959755;4627.629789616823;4118.546959834416;4132.855350062733;4133.855315063958;4176.185643799491;4180.561041090642;5657.8019769307875;5675.858103547444;5849.5027922626205;5851.824445266649;5871.55963302754;5879.500242479352;5893.116032595145;5996.4003599640555;6007.098935159674;6111.572189946661;5704.914426283609;5740.799072032459;5745.597808153389;5761.251037365116;5767.711614419262;5111.667741596784;5158.148905430562;5162.92255616166;5183.170692689227;5201.687898726089;6217.067439884054;6342.5243106767175;6353.364663533702;6356.968215158895;6357.046443033582;6086.482648974887;6116.877662446637;6128.816135515942;6133.570650054543;6150.630962142286;6129.601575897553;6173.043178307369;6220.564560480904;6247.468965138014;6248.687565621792;6043.667598282137;6046.5767396281835;6047.334793172836;6059.272887253558;6074.514038876968;6141.723622436912;6143.477804386677;6150.969245153746;6159.353267906805;6166.352532983972;5746.798862039808;5800.50695690871;5872.882542349214;5891.617044892068;5990.101484777319;5875.383084776037;5876.324222714443;5898.64608123514;5907.2911250650195;5925.703714814328;5754.6547207167705;5791.855203619774;5794.246747922743;5815.651060936358;5824.854378640568;5741.712914354349;5879.470847623735;5907.409259074058;5940.316863560746;6028.125921741235;5890.4993075587345;5891.646501209942;5901.29184497863;5910;5915.91126133117 03:51:56 INFO - PROCESS | 25012 | |1;ext.html;584.7134903897091;636.6625688385155;654.908312836203;667.1427216027406;668.411797618096;42116;46919.061618767606;47088.05823883525;47106.528934710645;47162.76418617906;48744.2688359674;49390;49427.752861235735;49441.752791235995;49555.50444495554;8992.910070899288;9185.265178785714;9190.632374705005;9194.632214711437;9197.21823644991;8968.327375446868;9095.590698418553;9097.818043639123;9124.726258212264;9128.497932613704;9168.174864262215;9261.6295348186;9269.119433653792;9269.536523173849;9290.535473226346;9037.548122593877;9059.003509613936;9088.954555227214;9091.772705682344;9110.453372797654;9014.323925705598;9129.497877616728;9130.041345658676;9149.130832570896;9167.220786233178;45358.54641453576;45536;45725.54274457246;45840.3123953141;45883;3382.7124694401004;3678.2091850252054;3681.5214022176947;3690.5571331440133;3767.5290588676417;34901.476477852855;35055.29889402198;35336.64663353359;35337.469937950955;35436.468452973226;4094.7747873866924;4132.40080188374;4138.213739389506;4159.854405095807;4264.530901600821;378745;379467;380954.0952295248;383327;384218;67023.32976670268;67184.3281567178;67367;67548.66225668938;67804.32195677981;39685.60314396819;41034.79482602568;41087.383689244685;42927;70091.64954175196;19805.504862378268;19923.701144482846;20103.19587216524;20144;20208.09063592142;15362.462313818962;15391.230438478031;15493.690126197413;15522.525360091;15532.068075915482;22126.668099978193;22177;22328.441788955406;22374.888125559268;22444.438889027904;7766.650500727481;7888.171741967126;8044.517328960281;8215.630296636666;8266.338692904554;979.779549601334;1096.8354746788045;1127.4588197665003;1131.2194585735815;1135.6763322453062;34062.829685851415;34319.828400857834;34511.31066732191;34605.307893841986;34825.47761783575;1141.868685101224;1207.076586411396;1230.341767154568;1246.8566114896903;1291.3930452687223;17911.552211194823;18169.545761356072;18171.454856354332;18212.81787182138;18226.817731822513;561732;663820;677542;678353;912485;63381;64616;64622;64671.35328646747;64737.676311619085;1453.1644304524941;1532.7164474572462;1537.200655659051;1538.2155100898622;1543.5523698127417;33199.83400082984;33559.32881342409;33675.66324336725;33917.321653566294;34499.48250776291;55182.72408637931;55760.44239557553;55788.72105639446;55797.44202557922;55993;436851;437052;438422;440385;440424;30534.694653053186;31450;31465.528017079305;31496.37007259888;31704.524432133996;51208.23187652263;52193.7390313046;52652.47347526476;53154.46845531495;53363.733181333846;43533.78233108814;43571.34642980421;44145;44432.77783611061;45246;43452.565474344854;44079.11841763211;44247.55752442563;44266.3360049606;44802;43841.78079109584;43849.34225986677;43970.78014609907;45617.31574026459;46574.83562910819;38841;40559;40680.38979415252;41190.1761964765;41544.58455415407;30080.435119502963;30667;30738.692613073585;30891.845540772152;30995.019920318726 03:51:56 INFO - PROCESS | 25012 | |2;jquery.html;417.57616019739953;425.2833974752544;426.3668452348263;427.1030835245984;440.42744432238084;18044.458666240018;19237.23051077955;19833.206671733133;20210.49473763153;20257.290994815183;19222.51943701408;19318.32385866495;20325.39023829284;20342.898285508552;20432;4946.876328091799;5268.5521730652945;5276.472352764731;5317.601179911522;5352.866178345553;5231.712255825928;5246.370435547739;5297.841064768044;5467.043267428204;5471.781128754845;5444.292242008533;5444.8366549003595;5454.7272636368225;5467.972660136714;5484.287042684446;5246.816361427351;5404.270423492818;5428.077226871422;5441.319835020622;5446.700431476267;5299.231611416361;5305.920411193835;5308.230306605559;5312.893742125175;5315;13836.723265534734;14576.344064517121;14579.92710036443;14624.415023399051;14658.706825863423;2307.8730669813153;2356.4580146566386;2388.4864754077785;2567.3838278813137;2840.0343883079854;10891.237613367068;14664.12015279076;14963.551093467213;14983.475578354706;15276.854235932238;2081.354780018199;2136.83973701973;2168.9023993920155;2177.1835561664375;2180.324099529151;59899;68246.317536825;77906.83139752851;78913.21086789116;89605.55197224037;11244.381559014333;11328;11601.651950441501;11608.941955290169;11629.709257268636;15757.68484630301;15797.605059873365;15924.522264332089;15998.76001859973;16100.597485062734;8664.61009254585;8750;8812.95593522041;8916.375853690179;9019.098090191059;8535.615897284513;8560.91439085601;8639.395242332976;8642.654293828302;8810.86783698245;4871.3667223260745;4873.244647079709;4873.975630121827;4879.975600121977;4919.877003074952;1951.2585217617216;1964.3517639179322;1998.490384951828;2039.697335234104;2061.701053347256;884.920357167858;965.2471072563412;1017.2878984710732;1040.4433628008978;1091.44881834673;19799.505012374804;19942.30201942925;19982.70025949612;20314.695279570817;20389;1011.2163073617858;1020.9081182693593;1025.7230547752065;1037.3516552154904;1039.0596510158318;10897.782044359226;11130.49912753928;11263.887361126448;11409.60066397672;11484.94257528707;533460;538747;540170;546526;549673;92851;95754.52122739342;97148;97266.51366743262;97621;1026.3482688492861;1040.0743338428956;1117.5529788084832;1137.5790957345669;1161.024739219043;17567.648647026734;18106.09469526545;18117.184726687596;18144.909275453538;18263;41584.792076039426;42755.35866961936;43260.35109473298;44075;44097.77951110224;54518.72740636271;54601.72699136479;57298.7135064322;64692.353076470514;65411;8892.777680558038;8933.106689331014;9069.863952040592;9108;9223.169914707709;13936;13936.372863221382;14039.649008774863;14080.929595351958;14387.85612143907;12197.878021220029;12346.950509206818;12379.381030948596;12414.565490207799;12555.811662825248;11795.938365547141;12327.568535101229;12334.50661973511;12445.688857778629;12678.112532122663;12173.330466824409;12597.811032834332;12631.715624094948;12660.366981651065;13016.804747928978;9109.77225569366;9169.633214671605;9193.86209206849;9298.349115561845;9435.952820235854;11896.286222826659;12625.116241863348;12762.68093297675;12993.675158121123;13016.15394999322 03:51:56 INFO - PROCESS | 25012 | |3;mootools.html;1603.8716902647786;1626.3332033866116;1838.76096107506;1941.3496478679642;1952.4533130723403;8525.616347264373;9304.395214311066;9307.720768376943;9926.99999999999;9936;9312.441253524794;9551.808963820737;9643.132118109368;9645.228381729463;9650.517474126284;2423.7091549014144;2439.0365805506904;2440.9633855492184;2447.620618804079;2465.0263146057296;2456.115798312606;2476.8761561921924;2483.838550494212;2487.4900645367657;2489.2034548944343;2408.494216214597;2411.8191135664897;2462.335169504233;2476.3809047738064;2481.367251350912;2381.3332266965276;2390.4023994001586;2411.05968672218;2428.283656321372;2434.294054724128;2363.3973336799177;2390.5457962986975;2390.9043638254448;2394.916177933764;2405.615101583756;9649.469279189641;9667.516624168833;9678.370905891095;9727.805443891153;9747.122758951657;5463.453654634544;5639.802606908738;5650.604457687963;5650.858728531778;5669.744861481243;3395.5585773849243;3483.9477407838904;3500.0549851540195;3502.667246611568;3512.7892326460496;1141.3551343490901;1143.5997400909698;1146.0430540498694;1147.7532330548888;1165.947532361046;13573.11774734639;13706.45174193031;13755.656108597264;13787.448502059906;13788.93105534466;4878.926816097691;4905.681130726492;4934.087193869145;4953.876153096129;5026.1204289249235;11504;11551.711207219887;11555.306681599131;11581.305121692725;11583.305001699757;5539.833804985857;5606.299212598426;5606.411326810708;5616.9157462638095;5624.775008999594;4501.234790085693;4874.683145595596;5038.244263360452;5041.6722913010535;5043.344365232496;4372.80322385487;4391.143726973273;4439.356293337448;4470.731756094645;4498.640108791355;1332.5735764555443;1338.5984204738734;1339.370495866941;1367.1865240182076;1369.8493165751759;1046.2885238038207;1060.3478860500736;1079.63292480557;1080.3247970018738;1082.24783775277;6296.65368404733;6623.801285961429;6647.900281495781;6683.933160668428;6725.562838415487;449.2609657114094;452.75098695717224;459.1116190171961;461.1307685013706;463.80056575672785;4533.070720502289;4590.127875703605;4601.28680054592;4611.146937816515;4670.509596492319;11608.24546404481;11713.355765432812;12037.939810300892;12040.759184816254;12080.21478603888;11792.88207117935;11826.408679566159;11972.940135299443;12025.098117641212;12043.458044388024;8971.416857904345;9003.189712925738;9030.593623286968;9038.186563209343;9080.545972701339;6154.876902462015;6183.072539119168;6250.906236406367;6253.218347706536;6381.361863813581;10774.191935604704;10875.966783155587;11097.33415995043;11264.7747045057;11309.264897781617;11204.215704900578;11319.377434240867;11449.771004579696;11554.364509952033;11570.190086694187;7678.424118191269;7801.336886364723;8077.838443230985;8580.099089595502;8643;9393.765155870884;9438.24494040489;9445.905540944503;9540.141387275178;9562;8364.665413383396;8584.828303433771;8608;8681.739547813575;8725.65097396097;8407;8428.747137585882;8553.358498112791;8591.87112193305;8623.482591044558;7541.660625271986;7571.659275332513;7632.427567932538;7635.5800430974605;7636.885446718415;5413.945860541502;5443.836684899459;5534.335879694462;5617.578681598815;5686.65880047182;5340.465953404629;5419.3225846769155;5452.100403433389;5477.20580515815;5537.141743029837 03:51:56 INFO - PROCESS | 25012 | |4;prototype.html;351.3289616831852;354.42406090103583;356.8322888242525;364.63718599992995;366.5326708446732;5648.378678345389;5652.406497317784;5792.76828926843;5841.7955371562;5857.56068294878;5620.690862002588;5717.514011309044;5722.136184243391;5734.741936612852;5745.597808153431;4480.887977800565;4511.864644060675;4549.181147393468;4560.677403552966;4563.243151386723;4474.306482495218;4515.683902126836;4516.864494065167;4517.344984977176;4604.97697511512;4432.670198940321;4449.39933109031;4452.462096604936;4470.865874023768;4476.350929115276;4460.553944605546;4530.365748795188;4545.613622842063;4549;4567.406237189178;3949.7630142191274;4005.719599628027;4030.193961207741;4033.3344998075577;4043.2317859606583;5514.641548299348;5532.75102620383;5538.806141785059;5552.167174923753;5563.610547261653;5150.330457040561;5156.20078887773;5181.948180518184;5190.71451070191;5196.61025423091;5846.21076154718;5987.790427335022;6016.639001659914;6037.335893051761;6072.848178795522;4367.563243675607;4388.934165987513;4420.093880754438;4438.800253988546;4458.8885277867985;6177.490431984676;6321.24145102586;6345.206849143857;6365.395287447685;6366.612520304886;4445.3998710174355;4515.616172625364;4524.502304746476;4536.183486972377;4546.886327841764;4541.296099104644;4544.409226800495;4593.885152871138;4631.791569379386;4653.6509761768;5237.371515418175;5239.449857764956;5279.52484276417;5302.522772950453;5304.151335786256;5897.823065308047;5949.851253718692;6019.0068638675;6077.9392206078255;6117.021276595812;1533.1874106723455;1546.234613866121;1546.5746919596975;1552.2781906413572;1555.564441956254;972.8783902012249;985.7831277119022;988.6539711101201;990.9108180263811;1003.6236411345745;5244.816431424882;5275.5515781158265;5276.34045744282;5338.386085600128;5340.679559226459;5487.945120548823;5524.309461317335;5570.526505247019;5589.888202235932;5662.462066103713;5027.296178535008;5206.609504287194;5318.654287471302;5346.625736198429;5350.571954243651;2959.334149816317;3011.623547056618;3014.553014553027;3016.069715382157;3037.711417415342;5692.971535142297;5778.306603207642;5792.855178620568;5806.68063256525;5823.854403639943;5980.700964951735;6395.712192951329;6426.550141490145;6441.420272175433;6454.548181627335;6432.678366081771;6467.417932386108;6470.22357317125;6477.57895736785;6483.351664833479;5941.851453713692;6275.592086514362;6301.873962520633;6305.117283580211;6315.778947736807;6174.320824709189;6235.004798464448;6253.249610046823;6259.405356491126;6259.436650701276;6410;6463.127477790438;6519.218853065116;6525.673716314071;6551.901721474086;4423.8894027648275;4426.867193984185;4459.308807134899;4464.772187648423;4518.43519560055;6031.306399764083;6168.9691551541955;6372;6382.393672601095;6394.8808958431155;6095.75616975316;6101.359357267601;6109.633421994695;6124.938750612437;6143.784967526115;6045.244344456943;6077.817665470043;6087.786927457518;6136.539759518143;6149.169862068742;5698.515626171656;6017.789377371771;6042.697865106815;6051.334353221054;6051.5461340400525;6157.676099638599;6186.845328866814;6197.597156184834;6219.129321895029;6219.533534984804;5916.585838991229;5960.791372301948;6046.3046749624355;6057.818725348691;6072.574919755752 03:51:56 INFO - PROCESS | 25012 | |5;yui.html;776.3478677910557;786.4927122006306;862.65493802479;866.6576702202634;893.7006102955512;15746.133962632064;15951.441699540519;16042.197890105477;16125.596860078504;16204.594885127877;15699.921500392511;15890.52328430146;15901.1254381009;15916.204189790493;15926.283317250722;713.5540287320425;846.4244313866568;858.7552547523957;860.1699360117485;860.7073594977707;866.9133086691344;871.9956400218022;874.4185116897256;875.155474966657;875.8379699755534;915.5925613102172;922.2437601167046;927.6753136402273;930.7905721212741;935.5135329628589;917.7980844214262;920.397139873384;921.3918813583036;929.4655573045526;931.6785708930408;680.8059702984675;682.5870348439222;683.8324610470416;687.5874475314821;688.2119972631309;14623.78064329036;14697.632559186106;14698.632534186623;14726.926365368212;14875.107493550422;15794.368225270975;16057.91971040149;16104.436344727996;16579.171041448;16809.6638067238;13152.934235328858;13313.400896959563;13447.932760336234;13464.461421543127;13526.729465410735;4049.311617025113;4124.628783409507;4155.646770024551;4169.833206671729;4236.279832428495;81737;84155.15844841534;84682.57658711667;84930;85626.14373856244;12716.936415318049;12815.359232038361;12862.228266304051;12935.611931642066;12938.417771200131;24308;24330.635040474408;24358.756412436003;24417;24473.14343997987;8512.829743405096;8562.48625082497;8567.357448191286;8656.26421754158;9025.684101056431;5027;5075.213341932006;5107.41264754558;5152.793888244503;5213.322268105122;3564.1267889366754;3675.026118078711;3695.9630403696156;4011.438398624195;4184.4141820145205;682.6450245872218;683.0505597219868;683.1153656015472;692.3215249055853;695.25954858077;782.8991051165983;791.0407963736584;794.7417089445953;817.840521098392;823.8269963307772;10911.83632245517;11311.321320720783;11421.828672569918;11442.885571144181;11500;687.4191308344541;691.7751730687446;715.1918332284589;807.4226927746572;815.8980127484065;5037.748112594429;5152.536271735562;5161.535461808456;5184.7926082956255;5220.216967454836;86511.56744216238;108715.45642271738;116777;127636.36181819031;133581;72557.6372118136;113125;113806;114530;115254;94119.52940235255;94915.52542237245;95382.52308738412;97049.51475242579;99795.00204997858;10279.331843430153;10331.32846364984;10375.377477351383;10475.42385168823;10487.423191724529;51644.48355516397;54193.45806541884;55777.72111139418;56764.43235567756;56801.71599141978;84007;85518;85911.5704421499;87227;88417;12547.184433011826;12847.03647226443;13733.587992360246;13754.587362378745;13841.30793460343;15107.848921510644;15262.3895044197;15276;15487.380504779683;16138;14130.293485325486;14290.142591444546;14585.489507867174;14740.631484212552;15062.096274223582;13670.794938075738;14008.229547374585;14026.649333766329;14214.21821799811;14326.57020289393;13451.865481345452;13577.32113394346;13864.861351386357;13869.167849929037;14145.080569762933;11159.944200278947;11729.648110556696;11796.76406471883;11862;11862.525498979945;12001.459934302802;12255.387230638255;12258.693532661398;12355.196912200678;12499.875001249871 03:51:56 INFO - PROCESS | 25012 | __end_tp_report 03:51:56 INFO - PROCESS | 25012 | __start_cc_report 03:51:56 INFO - PROCESS | 25012 | _x_x_mozilla_cycle_collect,336 03:51:56 INFO - PROCESS | 25012 | __end_cc_report 03:51:56 INFO - PROCESS | 25012 | __startTimestamp1466333516826__endTimestamp 03:51:56 INFO - PROCESS | 25012 | 03:51:56 INFO - PROCESS | 25012 | ------- Summary: start ------- 03:51:56 INFO - PROCESS | 25012 | Number of tests: 6 03:51:56 INFO - PROCESS | 25012 | 03:51:56 INFO - PROCESS | 25012 | [#0] dojo.html Cycles:165 Average:5641.15 Median:5859.68 stddev:634.32 (10.8%) stddev-sans-first:610.67 03:51:56 INFO - PROCESS | 25012 | Values: 3367.6 3368.1 3393.8 3403.5 3407.6 5548.0 5575.0 5610.4 5620.0 5644.9 5631.2 5635.7 5675.7 5692.5 5701.9 5079.6 5181.2 5203.6 5210.8 5231.1 5136.9 5180.4 5233.8 5245.7 5249.6 5151.7 5204.9 5214.6 5240.2 5285.4 5136.8 5142.9 5215.6 5220.1 5223.8 5077.6 5140.4 5193.9 5205.0 5232.1 5432.5 5433.0 5483.6 5489.6 5547.9 6049.5 6071.0 6081.8 6095.9 6097.4 6138.3 6153.6 6158.0 6158.2 6173.9 5534.4 5633.7 5691.2 5710.5 5728.0 6228.3 6254.1 6265.8 6275.7 6289.1 6011.9 6022.3 6057.9 6059.2 6080.2 5859.7 6011.6 6047.1 6094.2 6174.2 6087.5 6114.8 6120.9 6134.6 6138.3 5908.5 6114.3 6115.9 6120.8 6127.6 4560.8 4570.2 4592.5 4605.4 4627.6 4118.5 4132.9 4133.9 4176.2 4180.6 5657.8 5675.9 5849.5 5851.8 5871.6 5879.5 5893.1 5996.4 6007.1 6111.6 5704.9 5740.8 5745.6 5761.3 5767.7 5111.7 5158.1 5162.9 5183.2 5201.7 6217.1 6342.5 6353.4 6357.0 6357.0 6086.5 6116.9 6128.8 6133.6 6150.6 6129.6 6173.0 6220.6 6247.5 6248.7 6043.7 6046.6 6047.3 6059.3 6074.5 6141.7 6143.5 6151.0 6159.4 6166.4 5746.8 5800.5 5872.9 5891.6 5990.1 5875.4 5876.3 5898.6 5907.3 5925.7 5754.7 5791.9 5794.2 5815.7 5824.9 5741.7 5879.5 5907.4 5940.3 6028.1 5890.5 5891.6 5901.3 5910.0 5915.9 03:51:56 INFO - PROCESS | 25012 | 03:51:56 INFO - PROCESS | 25012 | [#1] ext.html Cycles:180 Average:67440.46 Median:241135.67 stddev:141249.89 (58.6%) stddev-sans-first:141556.94 03:51:56 INFO - PROCESS | 25012 | Values: 584.7 636.7 654.9 667.1 668.4 42116.0 46919.1 47088.1 47106.5 47162.8 48744.3 49390.0 49427.8 49441.8 49555.5 8992.9 9185.3 9190.6 9194.6 9197.2 8968.3 9095.6 9097.8 9124.7 9128.5 9168.2 9261.6 9269.1 9269.5 9290.5 9037.5 9059.0 9089.0 9091.8 9110.5 9014.3 9129.5 9130.0 9149.1 9167.2 45358.5 45536.0 45725.5 45840.3 45883.0 3382.7 3678.2 3681.5 3690.6 3767.5 34901.5 35055.3 35336.6 35337.5 35436.5 4094.8 4132.4 4138.2 4159.9 4264.5 378745.0 379467.0 380954.1 383327.0 384218.0 67023.3 67184.3 67367.0 67548.7 67804.3 39685.6 41034.8 41087.4 42927.0 70091.6 19805.5 19923.7 20103.2 20144.0 20208.1 15362.5 15391.2 15493.7 15522.5 15532.1 22126.7 22177.0 22328.4 22374.9 22444.4 7766.7 7888.2 8044.5 8215.6 8266.3 979.8 1096.8 1127.5 1131.2 1135.7 34062.8 34319.8 34511.3 34605.3 34825.5 1141.9 1207.1 1230.3 1246.9 1291.4 17911.6 18169.5 18171.5 18212.8 18226.8 561732.0 663820.0 677542.0 678353.0 912485.0 63381.0 64616.0 64622.0 64671.4 64737.7 1453.2 1532.7 1537.2 1538.2 1543.6 33199.8 33559.3 33675.7 33917.3 34499.5 55182.7 55760.4 55788.7 55797.4 55993.0 436851.0 437052.0 438422.0 440385.0 440424.0 30534.7 31450.0 31465.5 31496.4 31704.5 51208.2 52193.7 52652.5 53154.5 53363.7 43533.8 43571.3 44145.0 44432.8 45246.0 43452.6 44079.1 44247.6 44266.3 44802.0 43841.8 43849.3 43970.8 45617.3 46574.8 38841.0 40559.0 40680.4 41190.2 41544.6 30080.4 30667.0 30738.7 30891.8 30995.0 03:51:56 INFO - PROCESS | 25012 | 03:51:56 INFO - PROCESS | 25012 | [#2] jquery.html Cycles:180 Average:30601.44 Median:2173.04 stddev:89051.06 (4098.0%) stddev-sans-first:89272.03 03:51:56 INFO - PROCESS | 25012 | Values: 417.6 425.3 426.4 427.1 440.4 18044.5 19237.2 19833.2 20210.5 20257.3 19222.5 19318.3 20325.4 20342.9 20432.0 4946.9 5268.6 5276.5 5317.6 5352.9 5231.7 5246.4 5297.8 5467.0 5471.8 5444.3 5444.8 5454.7 5468.0 5484.3 5246.8 5404.3 5428.1 5441.3 5446.7 5299.2 5305.9 5308.2 5312.9 5315.0 13836.7 14576.3 14579.9 14624.4 14658.7 2307.9 2356.5 2388.5 2567.4 2840.0 10891.2 14664.1 14963.6 14983.5 15276.9 2081.4 2136.8 2168.9 2177.2 2180.3 59899.0 68246.3 77906.8 78913.2 89605.6 11244.4 11328.0 11601.7 11608.9 11629.7 15757.7 15797.6 15924.5 15998.8 16100.6 8664.6 8750.0 8813.0 8916.4 9019.1 8535.6 8560.9 8639.4 8642.7 8810.9 4871.4 4873.2 4874.0 4880.0 4919.9 1951.3 1964.4 1998.5 2039.7 2061.7 884.9 965.2 1017.3 1040.4 1091.4 19799.5 19942.3 19982.7 20314.7 20389.0 1011.2 1020.9 1025.7 1037.4 1039.1 10897.8 11130.5 11263.9 11409.6 11484.9 533460.0 538747.0 540170.0 546526.0 549673.0 92851.0 95754.5 97148.0 97266.5 97621.0 1026.3 1040.1 1117.6 1137.6 1161.0 17567.6 18106.1 18117.2 18144.9 18263.0 41584.8 42755.4 43260.4 44075.0 44097.8 54518.7 54601.7 57298.7 64692.4 65411.0 8892.8 8933.1 9069.9 9108.0 9223.2 13936.0 13936.4 14039.6 14080.9 14387.9 12197.9 12347.0 12379.4 12414.6 12555.8 11795.9 12327.6 12334.5 12445.7 12678.1 12173.3 12597.8 12631.7 12660.4 13016.8 9109.8 9169.6 9193.9 9298.3 9436.0 11896.3 12625.1 12762.7 12993.7 13016.2 03:51:56 INFO - PROCESS | 25012 | 03:51:56 INFO - PROCESS | 25012 | [#3] mootools.html Cycles:180 Average:6305.50 Median:4595.71 stddev:3692.35 (80.3%) stddev-sans-first:3685.81 03:51:56 INFO - PROCESS | 25012 | Values: 1603.9 1626.3 1838.8 1941.3 1952.5 8525.6 9304.4 9307.7 9927.0 9936.0 9312.4 9551.8 9643.1 9645.2 9650.5 2423.7 2439.0 2441.0 2447.6 2465.0 2456.1 2476.9 2483.8 2487.5 2489.2 2408.5 2411.8 2462.3 2476.4 2481.4 2381.3 2390.4 2411.1 2428.3 2434.3 2363.4 2390.5 2390.9 2394.9 2405.6 9649.5 9667.5 9678.4 9727.8 9747.1 5463.5 5639.8 5650.6 5650.9 5669.7 3395.6 3483.9 3500.1 3502.7 3512.8 1141.4 1143.6 1146.0 1147.8 1165.9 13573.1 13706.5 13755.7 13787.4 13788.9 4878.9 4905.7 4934.1 4953.9 5026.1 11504.0 11551.7 11555.3 11581.3 11583.3 5539.8 5606.3 5606.4 5616.9 5624.8 4501.2 4874.7 5038.2 5041.7 5043.3 4372.8 4391.1 4439.4 4470.7 4498.6 1332.6 1338.6 1339.4 1367.2 1369.8 1046.3 1060.3 1079.6 1080.3 1082.2 6296.7 6623.8 6647.9 6683.9 6725.6 449.3 452.8 459.1 461.1 463.8 4533.1 4590.1 4601.3 4611.1 4670.5 11608.2 11713.4 12037.9 12040.8 12080.2 11792.9 11826.4 11972.9 12025.1 12043.5 8971.4 9003.2 9030.6 9038.2 9080.5 6154.9 6183.1 6250.9 6253.2 6381.4 10774.2 10876.0 11097.3 11264.8 11309.3 11204.2 11319.4 11449.8 11554.4 11570.2 7678.4 7801.3 8077.8 8580.1 8643.0 9393.8 9438.2 9445.9 9540.1 9562.0 8364.7 8584.8 8608.0 8681.7 8725.7 8407.0 8428.7 8553.4 8591.9 8623.5 7541.7 7571.7 7632.4 7635.6 7636.9 5413.9 5443.8 5534.3 5617.6 5686.7 5340.5 5419.3 5452.1 5477.2 5537.1 03:51:56 INFO - PROCESS | 25012 | 03:51:56 INFO - PROCESS | 25012 | [#4] prototype.html Cycles:180 Average:5056.50 Median:5634.53 stddev:1490.36 (26.5%) stddev-sans-first:1452.10 03:51:56 INFO - PROCESS | 25012 | Values: 351.3 354.4 356.8 364.6 366.5 5648.4 5652.4 5792.8 5841.8 5857.6 5620.7 5717.5 5722.1 5734.7 5745.6 4480.9 4511.9 4549.2 4560.7 4563.2 4474.3 4515.7 4516.9 4517.3 4605.0 4432.7 4449.4 4452.5 4470.9 4476.4 4460.6 4530.4 4545.6 4549.0 4567.4 3949.8 4005.7 4030.2 4033.3 4043.2 5514.6 5532.8 5538.8 5552.2 5563.6 5150.3 5156.2 5181.9 5190.7 5196.6 5846.2 5987.8 6016.6 6037.3 6072.8 4367.6 4388.9 4420.1 4438.8 4458.9 6177.5 6321.2 6345.2 6365.4 6366.6 4445.4 4515.6 4524.5 4536.2 4546.9 4541.3 4544.4 4593.9 4631.8 4653.7 5237.4 5239.4 5279.5 5302.5 5304.2 5897.8 5949.9 6019.0 6077.9 6117.0 1533.2 1546.2 1546.6 1552.3 1555.6 972.9 985.8 988.7 990.9 1003.6 5244.8 5275.6 5276.3 5338.4 5340.7 5487.9 5524.3 5570.5 5589.9 5662.5 5027.3 5206.6 5318.7 5346.6 5350.6 2959.3 3011.6 3014.6 3016.1 3037.7 5693.0 5778.3 5792.9 5806.7 5823.9 5980.7 6395.7 6426.6 6441.4 6454.5 6432.7 6467.4 6470.2 6477.6 6483.4 5941.9 6275.6 6301.9 6305.1 6315.8 6174.3 6235.0 6253.2 6259.4 6259.4 6410.0 6463.1 6519.2 6525.7 6551.9 4423.9 4426.9 4459.3 4464.8 4518.4 6031.3 6169.0 6372.0 6382.4 6394.9 6095.8 6101.4 6109.6 6124.9 6143.8 6045.2 6077.8 6087.8 6136.5 6149.2 5698.5 6017.8 6042.7 6051.3 6051.5 6157.7 6186.8 6197.6 6219.1 6219.5 5916.6 5960.8 6046.3 6057.8 6072.6 03:51:56 INFO - PROCESS | 25012 | 03:51:56 INFO - PROCESS | 25012 | [#5] yui.html Cycles:180 Average:22410.63 Median:3853.70 stddev:32271.73 (837.4%) stddev-sans-first:32321.37 03:51:56 INFO - PROCESS | 25012 | Values: 776.3 786.5 862.7 866.7 893.7 15746.1 15951.4 16042.2 16125.6 16204.6 15699.9 15890.5 15901.1 15916.2 15926.3 713.6 846.4 858.8 860.2 860.7 866.9 872.0 874.4 875.2 875.8 915.6 922.2 927.7 930.8 935.5 917.8 920.4 921.4 929.5 931.7 680.8 682.6 683.8 687.6 688.2 14623.8 14697.6 14698.6 14726.9 14875.1 15794.4 16057.9 16104.4 16579.2 16809.7 13152.9 13313.4 13447.9 13464.5 13526.7 4049.3 4124.6 4155.6 4169.8 4236.3 81737.0 84155.2 84682.6 84930.0 85626.1 12716.9 12815.4 12862.2 12935.6 12938.4 24308.0 24330.6 24358.8 24417.0 24473.1 8512.8 8562.5 8567.4 8656.3 9025.7 5027.0 5075.2 5107.4 5152.8 5213.3 3564.1 3675.0 3696.0 4011.4 4184.4 682.6 683.1 683.1 692.3 695.3 782.9 791.0 794.7 817.8 823.8 10911.8 11311.3 11421.8 11442.9 11500.0 687.4 691.8 715.2 807.4 815.9 5037.7 5152.5 5161.5 5184.8 5220.2 86511.6 108715.5 116777.0 127636.4 133581.0 72557.6 113125.0 113806.0 114530.0 115254.0 94119.5 94915.5 95382.5 97049.5 99795.0 10279.3 10331.3 10375.4 10475.4 10487.4 51644.5 54193.5 55777.7 56764.4 56801.7 84007.0 85518.0 85911.6 87227.0 88417.0 12547.2 12847.0 13733.6 13754.6 13841.3 15107.8 15262.4 15276.0 15487.4 16138.0 14130.3 14290.1 14585.5 14740.6 15062.1 13670.8 14008.2 14026.6 14214.2 14326.6 13451.9 13577.3 13864.9 13869.2 14145.1 11159.9 11729.6 11796.8 11862.0 11862.5 12001.5 12255.4 12258.7 12355.2 12499.9 03:51:56 INFO - PROCESS | 25012 | -------- Summary: end -------- 03:51:56 INFO - PROCESS | 25012 | 03:51:57 INFO - TEST-INFO | 25012: exit 0 03:51:57 INFO - TEST-OK | dromaeo_css | took 1111433ms 03:51:57 INFO - TEST-START | kraken 03:51:57 INFO - operating with platform_type : linux_ 03:51:57 INFO - Initialising browser for kraken test... 03:51:57 INFO - TEST-INFO | started process 25258 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_uqb3E/profile http://localhost:55836/getInfo.html) 03:52:02 INFO - PROCESS | 25258 | __metrics Screen width/height:1600/1200 03:52:02 INFO - PROCESS | 25258 | colorDepth:24 03:52:02 INFO - PROCESS | 25258 | Browser inner width/height: 1024/697 03:52:02 INFO - PROCESS | 25258 | __metrics 03:52:03 ERROR - PROCESS | 25258 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2948: TypeError: this.tabs is undefined 03:52:04 INFO - TEST-INFO | 25258: exit 0 03:52:04 INFO - Browser initialized. 03:52:04 INFO - Running cycle 1/1 for kraken test... 03:52:04 INFO - TEST-INFO | started process 25389 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_uqb3E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:52:16 INFO - PROCESS | 25389 | RSS: Main: 156356608 03:52:16 INFO - PROCESS | 25389 | 03:52:18 INFO - PROCESS | 25389 | Cycle 1(1): loaded http://localhost:55836/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55836/tests/kraken/driver.html?testName=audio-beat-detection) 03:52:19 INFO - PROCESS | 25389 | RSS: Main: 161542144 03:52:19 INFO - PROCESS | 25389 | 03:52:22 INFO - PROCESS | 25389 | Cycle 1(1): loaded http://localhost:55836/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55836/tests/kraken/driver.html?testName=audio-dft) 03:52:23 INFO - PROCESS | 25389 | RSS: Main: 160489472 03:52:23 INFO - PROCESS | 25389 | 03:52:25 INFO - PROCESS | 25389 | Cycle 1(1): loaded http://localhost:55836/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55836/tests/kraken/driver.html?testName=audio-fft) 03:52:26 INFO - PROCESS | 25389 | RSS: Main: 161689600 03:52:26 INFO - PROCESS | 25389 | 03:52:28 INFO - PROCESS | 25389 | Cycle 1(1): loaded http://localhost:55836/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55836/tests/kraken/driver.html?testName=audio-oscillator) 03:52:29 INFO - PROCESS | 25389 | RSS: Main: 160858112 03:52:29 INFO - PROCESS | 25389 | 03:52:31 INFO - PROCESS | 25389 | Cycle 1(1): loaded http://localhost:55836/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55836/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:52:32 INFO - PROCESS | 25389 | RSS: Main: 161091584 03:52:32 INFO - PROCESS | 25389 | 03:52:35 INFO - PROCESS | 25389 | Cycle 1(1): loaded http://localhost:55836/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55836/tests/kraken/driver.html?testName=imaging-darkroom) 03:52:35 INFO - PROCESS | 25389 | RSS: Main: 161652736 03:52:35 INFO - PROCESS | 25389 | 03:52:39 INFO - PROCESS | 25389 | Cycle 1(1): loaded http://localhost:55836/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55836/tests/kraken/driver.html?testName=imaging-desaturate) 03:52:39 INFO - PROCESS | 25389 | RSS: Main: 161570816 03:52:39 INFO - PROCESS | 25389 | 03:52:42 INFO - PROCESS | 25389 | Cycle 1(1): loaded http://localhost:55836/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55836/tests/kraken/driver.html?testName=json-parse-financial) 03:52:43 INFO - PROCESS | 25389 | RSS: Main: 160849920 03:52:43 INFO - PROCESS | 25389 | 03:52:44 INFO - PROCESS | 25389 | Cycle 1(1): loaded http://localhost:55836/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55836/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:52:45 INFO - PROCESS | 25389 | RSS: Main: 160911360 03:52:45 INFO - PROCESS | 25389 | 03:52:47 INFO - PROCESS | 25389 | Cycle 1(1): loaded http://localhost:55836/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55836/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:52:47 INFO - PROCESS | 25389 | RSS: Main: 161038336 03:52:47 INFO - PROCESS | 25389 | 03:52:49 INFO - PROCESS | 25389 | Cycle 1(1): loaded http://localhost:55836/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55836/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:52:50 INFO - PROCESS | 25389 | RSS: Main: 161677312 03:52:50 INFO - PROCESS | 25389 | 03:52:53 INFO - PROCESS | 25389 | Cycle 1(1): loaded http://localhost:55836/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55836/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:52:53 INFO - PROCESS | 25389 | RSS: Main: 161959936 03:52:53 INFO - PROCESS | 25389 | 03:52:56 INFO - PROCESS | 25389 | Cycle 1(1): loaded http://localhost:55836/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55836/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:52:56 INFO - PROCESS | 25389 | RSS: Main: 161386496 03:52:56 INFO - PROCESS | 25389 | 03:52:58 INFO - PROCESS | 25389 | Cycle 1(1): loaded http://localhost:55836/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55836/tests/kraken/driver.html?testName=ai-astar) 03:52:58 INFO - PROCESS | 25389 | RSS: Main: 162623488 03:52:58 INFO - PROCESS | 25389 | 03:52:58 INFO - PROCESS | 25389 | __start_tp_report 03:52:58 INFO - PROCESS | 25389 | _x_x_mozilla_page_load 03:52:58 INFO - PROCESS | 25389 | _x_x_mozilla_page_load_details 03:52:58 INFO - PROCESS | 25389 | |i|pagename|runs| 03:52:58 INFO - PROCESS | 25389 | |0;ai-astar;112;106;112;112;113;111;113;111;111;112 03:52:58 INFO - PROCESS | 25389 | |1;audio-beat-detection;156;133;153;170;127;153;157;129;188;124 03:52:58 INFO - PROCESS | 25389 | |2;audio-dft;167;163;163;163;164;162;163;164;162;162 03:52:58 INFO - PROCESS | 25389 | |3;audio-fft;79;76;76;172;76;76;74;75;160;76 03:52:58 INFO - PROCESS | 25389 | |4;audio-oscillator;85;86;145;85;85;86;143;84;88;144 03:52:58 INFO - PROCESS | 25389 | |5;imaging-gaussian-blur;117;117;118;118;117;117;117;117;118;117 03:52:58 INFO - PROCESS | 25389 | |6;imaging-darkroom;148;147;147;149;148;146;148;147;149;146 03:52:58 INFO - PROCESS | 25389 | |7;imaging-desaturate;95;95;95;100;95;95;95;96;97;95 03:52:58 INFO - PROCESS | 25389 | |8;json-parse-financial;54;52;51;51;53;56;52;51;51;52 03:52:58 INFO - PROCESS | 25389 | |9;json-stringify-tinderbox;60;61;61;61;61;60;61;60;60;61 03:52:58 INFO - PROCESS | 25389 | |10;stanford-crypto-aes;77;75;76;80;77;80;77;80;111;77 03:52:58 INFO - PROCESS | 25389 | |11;stanford-crypto-ccm;119;119;119;119;128;117;125;139;124;126 03:52:58 INFO - PROCESS | 25389 | |12;stanford-crypto-pbkdf2;153;153;158;154;154;150;152;155;153;152 03:52:58 INFO - PROCESS | 25389 | |13;stanford-crypto-sha256-iterative;62;61;62;67;59;67;60;60;63;59 03:52:58 INFO - PROCESS | 25389 | __end_tp_report 03:52:58 INFO - PROCESS | 25389 | __start_cc_report 03:52:58 INFO - PROCESS | 25389 | _x_x_mozilla_cycle_collect,909 03:52:58 INFO - PROCESS | 25389 | __end_cc_report 03:52:58 INFO - PROCESS | 25389 | __startTimestamp1466333578771__endTimestamp 03:52:58 INFO - PROCESS | 25389 | 03:52:58 INFO - PROCESS | 25389 | ------- Summary: start ------- 03:52:58 INFO - PROCESS | 25389 | Number of tests: 14 03:52:58 INFO - PROCESS | 25389 | 03:52:58 INFO - PROCESS | 25389 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:2.00 (1.8%) stddev-sans-first:2.11 03:52:58 INFO - PROCESS | 25389 | Values: 112.0 106.0 112.0 112.0 113.0 111.0 113.0 111.0 111.0 112.0 03:52:58 INFO - PROCESS | 25389 | 03:52:58 INFO - PROCESS | 25389 | [#1] audio-beat-detection Cycles:10 Average:149.00 Median:154.50 stddev:20.74 (13.4%) stddev-sans-first:21.84 03:52:58 INFO - PROCESS | 25389 | Values: 156.0 133.0 153.0 170.0 127.0 153.0 157.0 129.0 188.0 124.0 03:52:58 INFO - PROCESS | 25389 | 03:52:58 INFO - PROCESS | 25389 | [#2] audio-dft Cycles:10 Average:163.30 Median:163.00 stddev:1.49 (0.9%) stddev-sans-first:0.78 03:52:58 INFO - PROCESS | 25389 | Values: 167.0 163.0 163.0 163.0 164.0 162.0 163.0 164.0 162.0 162.0 03:52:58 INFO - PROCESS | 25389 | 03:52:58 INFO - PROCESS | 25389 | [#3] audio-fft Cycles:10 Average:94.00 Median:76.00 stddev:38.07 (50.1%) stddev-sans-first:39.99 03:52:58 INFO - PROCESS | 25389 | Values: 79.0 76.0 76.0 172.0 76.0 76.0 74.0 75.0 160.0 76.0 03:52:58 INFO - PROCESS | 25389 | 03:52:58 INFO - PROCESS | 25389 | [#4] audio-oscillator Cycles:10 Average:103.10 Median:85.00 stddev:28.25 (33.2%) stddev-sans-first:29.19 03:52:58 INFO - PROCESS | 25389 | Values: 85.0 86.0 145.0 85.0 85.0 86.0 143.0 84.0 88.0 144.0 03:52:58 INFO - PROCESS | 25389 | 03:52:58 INFO - PROCESS | 25389 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 03:52:58 INFO - PROCESS | 25389 | Values: 117.0 117.0 118.0 118.0 117.0 117.0 117.0 117.0 118.0 117.0 03:52:58 INFO - PROCESS | 25389 | 03:52:58 INFO - PROCESS | 25389 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:148.00 stddev:1.08 (0.7%) stddev-sans-first:1.13 03:52:58 INFO - PROCESS | 25389 | Values: 148.0 147.0 147.0 149.0 148.0 146.0 148.0 147.0 149.0 146.0 03:52:58 INFO - PROCESS | 25389 | 03:52:58 INFO - PROCESS | 25389 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:95.00 stddev:1.62 (1.7%) stddev-sans-first:1.69 03:52:58 INFO - PROCESS | 25389 | Values: 95.0 95.0 95.0 100.0 95.0 95.0 95.0 96.0 97.0 95.0 03:52:58 INFO - PROCESS | 25389 | 03:52:58 INFO - PROCESS | 25389 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:52.00 stddev:1.64 (3.1%) stddev-sans-first:1.62 03:52:58 INFO - PROCESS | 25389 | Values: 54.0 52.0 51.0 51.0 53.0 56.0 52.0 51.0 51.0 52.0 03:52:58 INFO - PROCESS | 25389 | 03:52:58 INFO - PROCESS | 25389 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:61.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 03:52:58 INFO - PROCESS | 25389 | Values: 60.0 61.0 61.0 61.0 61.0 60.0 61.0 60.0 60.0 61.0 03:52:58 INFO - PROCESS | 25389 | 03:52:58 INFO - PROCESS | 25389 | [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:77.00 stddev:10.69 (13.9%) stddev-sans-first:11.24 03:52:58 INFO - PROCESS | 25389 | Values: 77.0 75.0 76.0 80.0 77.0 80.0 77.0 80.0 111.0 77.0 03:52:58 INFO - PROCESS | 25389 | 03:52:58 INFO - PROCESS | 25389 | [#11] stanford-crypto-ccm Cycles:10 Average:123.50 Median:124.50 stddev:6.60 (5.3%) stddev-sans-first:6.80 03:52:58 INFO - PROCESS | 25389 | Values: 119.0 119.0 119.0 119.0 128.0 117.0 125.0 139.0 124.0 126.0 03:52:58 INFO - PROCESS | 25389 | 03:52:58 INFO - PROCESS | 25389 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.40 Median:153.50 stddev:2.12 (1.4%) stddev-sans-first:2.24 03:52:58 INFO - PROCESS | 25389 | Values: 153.0 153.0 158.0 154.0 154.0 150.0 152.0 155.0 153.0 152.0 03:52:58 INFO - PROCESS | 25389 | 03:52:58 INFO - PROCESS | 25389 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.00 Median:62.00 stddev:2.94 (4.7%) stddev-sans-first:3.12 03:52:58 INFO - PROCESS | 25389 | Values: 62.0 61.0 62.0 67.0 59.0 67.0 60.0 60.0 63.0 59.0 03:52:58 INFO - PROCESS | 25389 | -------- Summary: end -------- 03:52:58 INFO - PROCESS | 25389 | 03:52:59 INFO - TEST-INFO | 25389: exit 0 03:52:59 INFO - TEST-OK | kraken | took 62183ms 03:52:59 INFO - SUITE-END | took 1173s 03:52:59 INFO - Completed test suite (00:19:33) 03:52:59 INFO - javascript benchmark 03:53:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7714.118946875476, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3367.5790526184223, 3368.056944055665, 3393.8133402662843, 3403.523506709061, 3407.625161232264, 5548.0, 5574.9721251393685, 5610.410906854782, 5619.988402087628, 5644.887102257952, 5631.18347839564, 5635.718214089299, 5675.6878371689645, 5692.487676109149, 5701.942980570193, 5079.568236699894, 5181.248718935752, 5203.583713302926, 5210.843674689765, 5231.110711179109, 5136.922946155792, 5180.404253510838, 5233.816816411427, 5245.711485868275, 5249.580033597322, 5151.74241287936, 5204.869878253036, 5214.634975551713, 5240.187770895498, 5285.392179899322, 5136.820211292606, 5142.8714282143055, 5215.556677682402, 5220.112580861262, 5223.791048358061, 5077.64456488046, 5140.383154021504, 5193.883315087274, 5205.011047900886, 5232.110541208004, 5432.456754324576, 5432.972835135799, 5483.643563168382, 5489.58828087891, 5547.944520554784, 6049.455549000567, 6070.969645151746, 6081.756729730805, 6095.878082438327, 6097.42074502926, 6138.324784273771, 6153.569250152491, 6158.0, 6158.230221222348, 6173.9073913891325, 5534.363548191969, 5633.6619802811965, 5691.231683722686, 5710.514606258432, 5728.0, 6228.252609686776, 6254.124422580842, 6265.780697675559, 6275.717592708339, 6289.0880822280515, 6011.939880601226, 6022.277326720821, 6057.909131363033, 6059.242594675666, 6080.209572755513, 5859.677717725481, 6011.609245399035, 6047.0627052806885, 6094.238220222473, 6174.197354343906, 6087.4521293083835, 6114.755409783559, 6120.8775824484155, 6134.631922084689, 6138.263408390932, 5908.497777688859, 6114.327423983447, 6115.908261376083, 6120.846978825565, 6127.632342059491, 4560.791390281578, 4570.223062079454, 4592.517785632527, 4605.424321959755, 4627.629789616823, 4118.546959834416, 4132.855350062733, 4133.855315063958, 4176.185643799491, 4180.561041090642, 5657.8019769307875, 5675.858103547444, 5849.5027922626205, 5851.824445266649, 5871.55963302754, 5879.500242479352, 5893.116032595145, 5996.4003599640555, 6007.098935159674, 6111.572189946661, 5704.914426283609, 5740.799072032459, 5745.597808153389, 5761.251037365116, 5767.711614419262, 5111.667741596784, 5158.148905430562, 5162.92255616166, 5183.170692689227, 5201.687898726089, 6217.067439884054, 6342.5243106767175, 6353.364663533702, 6356.968215158895, 6357.046443033582, 6086.482648974887, 6116.877662446637, 6128.816135515942, 6133.570650054543, 6150.630962142286, 6129.601575897553, 6173.043178307369, 6220.564560480904, 6247.468965138014, 6248.687565621792, 6043.667598282137, 6046.5767396281835, 6047.334793172836, 6059.272887253558, 6074.514038876968, 6141.723622436912, 6143.477804386677, 6150.969245153746, 6159.353267906805, 6166.352532983972, 5746.798862039808, 5800.50695690871, 5872.882542349214, 5891.617044892068, 5990.101484777319, 5875.383084776037, 5876.324222714443, 5898.64608123514, 5907.2911250650195, 5925.703714814328, 5754.6547207167705, 5791.855203619774, 5794.246747922743, 5815.651060936358, 5824.854378640568, 5741.712914354349, 5879.470847623735, 5907.409259074058, 5940.316863560746, 6028.125921741235, 5890.4993075587345, 5891.646501209942, 5901.29184497863, 5910.0, 5915.91126133117], "value": 5599.408590290089, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.7134903897091, 636.6625688385155, 654.908312836203, 667.1427216027406, 668.411797618096, 42116.0, 46919.061618767606, 47088.05823883525, 47106.528934710645, 47162.76418617906, 48744.2688359674, 49390.0, 49427.752861235735, 49441.752791235995, 49555.50444495554, 8992.910070899288, 9185.265178785714, 9190.632374705005, 9194.632214711437, 9197.21823644991, 8968.327375446868, 9095.590698418553, 9097.818043639123, 9124.726258212264, 9128.497932613704, 9168.174864262215, 9261.6295348186, 9269.119433653792, 9269.536523173849, 9290.535473226346, 9037.548122593877, 9059.003509613936, 9088.954555227214, 9091.772705682344, 9110.453372797654, 9014.323925705598, 9129.497877616728, 9130.041345658676, 9149.130832570896, 9167.220786233178, 45358.54641453576, 45536.0, 45725.54274457246, 45840.3123953141, 45883.0, 3382.7124694401004, 3678.2091850252054, 3681.5214022176947, 3690.5571331440133, 3767.5290588676417, 34901.476477852855, 35055.29889402198, 35336.64663353359, 35337.469937950955, 35436.468452973226, 4094.7747873866924, 4132.40080188374, 4138.213739389506, 4159.854405095807, 4264.530901600821, 378745.0, 379467.0, 380954.0952295248, 383327.0, 384218.0, 67023.32976670268, 67184.3281567178, 67367.0, 67548.66225668938, 67804.32195677981, 39685.60314396819, 41034.79482602568, 41087.383689244685, 42927.0, 70091.64954175196, 19805.504862378268, 19923.701144482846, 20103.19587216524, 20144.0, 20208.09063592142, 15362.462313818962, 15391.230438478031, 15493.690126197413, 15522.525360091, 15532.068075915482, 22126.668099978193, 22177.0, 22328.441788955406, 22374.888125559268, 22444.438889027904, 7766.650500727481, 7888.171741967126, 8044.517328960281, 8215.630296636666, 8266.338692904554, 979.779549601334, 1096.8354746788045, 1127.4588197665003, 1131.2194585735815, 1135.6763322453062, 34062.829685851415, 34319.828400857834, 34511.31066732191, 34605.307893841986, 34825.47761783575, 1141.868685101224, 1207.076586411396, 1230.341767154568, 1246.8566114896903, 1291.3930452687223, 17911.552211194823, 18169.545761356072, 18171.454856354332, 18212.81787182138, 18226.817731822513, 561732.0, 663820.0, 677542.0, 678353.0, 912485.0, 63381.0, 64616.0, 64622.0, 64671.35328646747, 64737.676311619085, 1453.1644304524941, 1532.7164474572462, 1537.200655659051, 1538.2155100898622, 1543.5523698127417, 33199.83400082984, 33559.32881342409, 33675.66324336725, 33917.321653566294, 34499.48250776291, 55182.72408637931, 55760.44239557553, 55788.72105639446, 55797.44202557922, 55993.0, 436851.0, 437052.0, 438422.0, 440385.0, 440424.0, 30534.694653053186, 31450.0, 31465.528017079305, 31496.37007259888, 31704.524432133996, 51208.23187652263, 52193.7390313046, 52652.47347526476, 53154.46845531495, 53363.733181333846, 43533.78233108814, 43571.34642980421, 44145.0, 44432.77783611061, 45246.0, 43452.565474344854, 44079.11841763211, 44247.55752442563, 44266.3360049606, 44802.0, 43841.78079109584, 43849.34225986677, 43970.78014609907, 45617.31574026459, 46574.83562910819, 38841.0, 40559.0, 40680.38979415252, 41190.1761964765, 41544.58455415407, 30080.435119502963, 30667.0, 30738.692613073585, 30891.845540772152, 30995.019920318726], "value": 21695.34554491987, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.57616019739953, 425.2833974752544, 426.3668452348263, 427.1030835245984, 440.42744432238084, 18044.458666240018, 19237.23051077955, 19833.206671733133, 20210.49473763153, 20257.290994815183, 19222.51943701408, 19318.32385866495, 20325.39023829284, 20342.898285508552, 20432.0, 4946.876328091799, 5268.5521730652945, 5276.472352764731, 5317.601179911522, 5352.866178345553, 5231.712255825928, 5246.370435547739, 5297.841064768044, 5467.043267428204, 5471.781128754845, 5444.292242008533, 5444.8366549003595, 5454.7272636368225, 5467.972660136714, 5484.287042684446, 5246.816361427351, 5404.270423492818, 5428.077226871422, 5441.319835020622, 5446.700431476267, 5299.231611416361, 5305.920411193835, 5308.230306605559, 5312.893742125175, 5315.0, 13836.723265534734, 14576.344064517121, 14579.92710036443, 14624.415023399051, 14658.706825863423, 2307.8730669813153, 2356.4580146566386, 2388.4864754077785, 2567.3838278813137, 2840.0343883079854, 10891.237613367068, 14664.12015279076, 14963.551093467213, 14983.475578354706, 15276.854235932238, 2081.354780018199, 2136.83973701973, 2168.9023993920155, 2177.1835561664375, 2180.324099529151, 59899.0, 68246.317536825, 77906.83139752851, 78913.21086789116, 89605.55197224037, 11244.381559014333, 11328.0, 11601.651950441501, 11608.941955290169, 11629.709257268636, 15757.68484630301, 15797.605059873365, 15924.522264332089, 15998.76001859973, 16100.597485062734, 8664.61009254585, 8750.0, 8812.95593522041, 8916.375853690179, 9019.098090191059, 8535.615897284513, 8560.91439085601, 8639.395242332976, 8642.654293828302, 8810.86783698245, 4871.3667223260745, 4873.244647079709, 4873.975630121827, 4879.975600121977, 4919.877003074952, 1951.2585217617216, 1964.3517639179322, 1998.490384951828, 2039.697335234104, 2061.701053347256, 884.920357167858, 965.2471072563412, 1017.2878984710732, 1040.4433628008978, 1091.44881834673, 19799.505012374804, 19942.30201942925, 19982.70025949612, 20314.695279570817, 20389.0, 1011.2163073617858, 1020.9081182693593, 1025.7230547752065, 1037.3516552154904, 1039.0596510158318, 10897.782044359226, 11130.49912753928, 11263.887361126448, 11409.60066397672, 11484.94257528707, 533460.0, 538747.0, 540170.0, 546526.0, 549673.0, 92851.0, 95754.52122739342, 97148.0, 97266.51366743262, 97621.0, 1026.3482688492861, 1040.0743338428956, 1117.5529788084832, 1137.5790957345669, 1161.024739219043, 17567.648647026734, 18106.09469526545, 18117.184726687596, 18144.909275453538, 18263.0, 41584.792076039426, 42755.35866961936, 43260.35109473298, 44075.0, 44097.77951110224, 54518.72740636271, 54601.72699136479, 57298.7135064322, 64692.353076470514, 65411.0, 8892.777680558038, 8933.106689331014, 9069.863952040592, 9108.0, 9223.169914707709, 13936.0, 13936.372863221382, 14039.649008774863, 14080.929595351958, 14387.85612143907, 12197.878021220029, 12346.950509206818, 12379.381030948596, 12414.565490207799, 12555.811662825248, 11795.938365547141, 12327.568535101229, 12334.50661973511, 12445.688857778629, 12678.112532122663, 12173.330466824409, 12597.811032834332, 12631.715624094948, 12660.366981651065, 13016.804747928978, 9109.77225569366, 9169.633214671605, 9193.86209206849, 9298.349115561845, 9435.952820235854, 11896.286222826659, 12625.116241863348, 12762.68093297675, 12993.675158121123, 13016.15394999322], "value": 9474.288791225843, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1603.8716902647786, 1626.3332033866116, 1838.76096107506, 1941.3496478679642, 1952.4533130723403, 8525.616347264373, 9304.395214311066, 9307.720768376943, 9926.99999999999, 9936.0, 9312.441253524794, 9551.808963820737, 9643.132118109368, 9645.228381729463, 9650.517474126284, 2423.7091549014144, 2439.0365805506904, 2440.9633855492184, 2447.620618804079, 2465.0263146057296, 2456.115798312606, 2476.8761561921924, 2483.838550494212, 2487.4900645367657, 2489.2034548944343, 2408.494216214597, 2411.8191135664897, 2462.335169504233, 2476.3809047738064, 2481.367251350912, 2381.3332266965276, 2390.4023994001586, 2411.05968672218, 2428.283656321372, 2434.294054724128, 2363.3973336799177, 2390.5457962986975, 2390.9043638254448, 2394.916177933764, 2405.615101583756, 9649.469279189641, 9667.516624168833, 9678.370905891095, 9727.805443891153, 9747.122758951657, 5463.453654634544, 5639.802606908738, 5650.604457687963, 5650.858728531778, 5669.744861481243, 3395.5585773849243, 3483.9477407838904, 3500.0549851540195, 3502.667246611568, 3512.7892326460496, 1141.3551343490901, 1143.5997400909698, 1146.0430540498694, 1147.7532330548888, 1165.947532361046, 13573.11774734639, 13706.45174193031, 13755.656108597264, 13787.448502059906, 13788.93105534466, 4878.926816097691, 4905.681130726492, 4934.087193869145, 4953.876153096129, 5026.1204289249235, 11504.0, 11551.711207219887, 11555.306681599131, 11581.305121692725, 11583.305001699757, 5539.833804985857, 5606.299212598426, 5606.411326810708, 5616.9157462638095, 5624.775008999594, 4501.234790085693, 4874.683145595596, 5038.244263360452, 5041.6722913010535, 5043.344365232496, 4372.80322385487, 4391.143726973273, 4439.356293337448, 4470.731756094645, 4498.640108791355, 1332.5735764555443, 1338.5984204738734, 1339.370495866941, 1367.1865240182076, 1369.8493165751759, 1046.2885238038207, 1060.3478860500736, 1079.63292480557, 1080.3247970018738, 1082.24783775277, 6296.65368404733, 6623.801285961429, 6647.900281495781, 6683.933160668428, 6725.562838415487, 449.2609657114094, 452.75098695717224, 459.1116190171961, 461.1307685013706, 463.80056575672785, 4533.070720502289, 4590.127875703605, 4601.28680054592, 4611.146937816515, 4670.509596492319, 11608.24546404481, 11713.355765432812, 12037.939810300892, 12040.759184816254, 12080.21478603888, 11792.88207117935, 11826.408679566159, 11972.940135299443, 12025.098117641212, 12043.458044388024, 8971.416857904345, 9003.189712925738, 9030.593623286968, 9038.186563209343, 9080.545972701339, 6154.876902462015, 6183.072539119168, 6250.906236406367, 6253.218347706536, 6381.361863813581, 10774.191935604704, 10875.966783155587, 11097.33415995043, 11264.7747045057, 11309.264897781617, 11204.215704900578, 11319.377434240867, 11449.771004579696, 11554.364509952033, 11570.190086694187, 7678.424118191269, 7801.336886364723, 8077.838443230985, 8580.099089595502, 8643.0, 9393.765155870884, 9438.24494040489, 9445.905540944503, 9540.141387275178, 9562.0, 8364.665413383396, 8584.828303433771, 8608.0, 8681.739547813575, 8725.65097396097, 8407.0, 8428.747137585882, 8553.358498112791, 8591.87112193305, 8623.482591044558, 7541.660625271986, 7571.659275332513, 7632.427567932538, 7635.5800430974605, 7636.885446718415, 5413.945860541502, 5443.836684899459, 5534.335879694462, 5617.578681598815, 5686.65880047182, 5340.465953404629, 5419.3225846769155, 5452.100403433389, 5477.20580515815, 5537.141743029837], "value": 4909.086227937437, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.3289616831852, 354.42406090103583, 356.8322888242525, 364.63718599992995, 366.5326708446732, 5648.378678345389, 5652.406497317784, 5792.76828926843, 5841.7955371562, 5857.56068294878, 5620.690862002588, 5717.514011309044, 5722.136184243391, 5734.741936612852, 5745.597808153431, 4480.887977800565, 4511.864644060675, 4549.181147393468, 4560.677403552966, 4563.243151386723, 4474.306482495218, 4515.683902126836, 4516.864494065167, 4517.344984977176, 4604.97697511512, 4432.670198940321, 4449.39933109031, 4452.462096604936, 4470.865874023768, 4476.350929115276, 4460.553944605546, 4530.365748795188, 4545.613622842063, 4549.0, 4567.406237189178, 3949.7630142191274, 4005.719599628027, 4030.193961207741, 4033.3344998075577, 4043.2317859606583, 5514.641548299348, 5532.75102620383, 5538.806141785059, 5552.167174923753, 5563.610547261653, 5150.330457040561, 5156.20078887773, 5181.948180518184, 5190.71451070191, 5196.61025423091, 5846.21076154718, 5987.790427335022, 6016.639001659914, 6037.335893051761, 6072.848178795522, 4367.563243675607, 4388.934165987513, 4420.093880754438, 4438.800253988546, 4458.8885277867985, 6177.490431984676, 6321.24145102586, 6345.206849143857, 6365.395287447685, 6366.612520304886, 4445.3998710174355, 4515.616172625364, 4524.502304746476, 4536.183486972377, 4546.886327841764, 4541.296099104644, 4544.409226800495, 4593.885152871138, 4631.791569379386, 4653.6509761768, 5237.371515418175, 5239.449857764956, 5279.52484276417, 5302.522772950453, 5304.151335786256, 5897.823065308047, 5949.851253718692, 6019.0068638675, 6077.9392206078255, 6117.021276595812, 1533.1874106723455, 1546.234613866121, 1546.5746919596975, 1552.2781906413572, 1555.564441956254, 972.8783902012249, 985.7831277119022, 988.6539711101201, 990.9108180263811, 1003.6236411345745, 5244.816431424882, 5275.5515781158265, 5276.34045744282, 5338.386085600128, 5340.679559226459, 5487.945120548823, 5524.309461317335, 5570.526505247019, 5589.888202235932, 5662.462066103713, 5027.296178535008, 5206.609504287194, 5318.654287471302, 5346.625736198429, 5350.571954243651, 2959.334149816317, 3011.623547056618, 3014.553014553027, 3016.069715382157, 3037.711417415342, 5692.971535142297, 5778.306603207642, 5792.855178620568, 5806.68063256525, 5823.854403639943, 5980.700964951735, 6395.712192951329, 6426.550141490145, 6441.420272175433, 6454.548181627335, 6432.678366081771, 6467.417932386108, 6470.22357317125, 6477.57895736785, 6483.351664833479, 5941.851453713692, 6275.592086514362, 6301.873962520633, 6305.117283580211, 6315.778947736807, 6174.320824709189, 6235.004798464448, 6253.249610046823, 6259.405356491126, 6259.436650701276, 6410.0, 6463.127477790438, 6519.218853065116, 6525.673716314071, 6551.901721474086, 4423.8894027648275, 4426.867193984185, 4459.308807134899, 4464.772187648423, 4518.43519560055, 6031.306399764083, 6168.9691551541955, 6372.0, 6382.393672601095, 6394.8808958431155, 6095.75616975316, 6101.359357267601, 6109.633421994695, 6124.938750612437, 6143.784967526115, 6045.244344456943, 6077.817665470043, 6087.786927457518, 6136.539759518143, 6149.169862068742, 5698.515626171656, 6017.789377371771, 6042.697865106815, 6051.334353221054, 6051.5461340400525, 6157.676099638599, 6186.845328866814, 6197.597156184834, 6219.129321895029, 6219.533534984804, 5916.585838991229, 5960.791372301948, 6046.3046749624355, 6057.818725348691, 6072.574919755752], "value": 4578.345084965451, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [776.3478677910557, 786.4927122006306, 862.65493802479, 866.6576702202634, 893.7006102955512, 15746.133962632064, 15951.441699540519, 16042.197890105477, 16125.596860078504, 16204.594885127877, 15699.921500392511, 15890.52328430146, 15901.1254381009, 15916.204189790493, 15926.283317250722, 713.5540287320425, 846.4244313866568, 858.7552547523957, 860.1699360117485, 860.7073594977707, 866.9133086691344, 871.9956400218022, 874.4185116897256, 875.155474966657, 875.8379699755534, 915.5925613102172, 922.2437601167046, 927.6753136402273, 930.7905721212741, 935.5135329628589, 917.7980844214262, 920.397139873384, 921.3918813583036, 929.4655573045526, 931.6785708930408, 680.8059702984675, 682.5870348439222, 683.8324610470416, 687.5874475314821, 688.2119972631309, 14623.78064329036, 14697.632559186106, 14698.632534186623, 14726.926365368212, 14875.107493550422, 15794.368225270975, 16057.91971040149, 16104.436344727996, 16579.171041448, 16809.6638067238, 13152.934235328858, 13313.400896959563, 13447.932760336234, 13464.461421543127, 13526.729465410735, 4049.311617025113, 4124.628783409507, 4155.646770024551, 4169.833206671729, 4236.279832428495, 81737.0, 84155.15844841534, 84682.57658711667, 84930.0, 85626.14373856244, 12716.936415318049, 12815.359232038361, 12862.228266304051, 12935.611931642066, 12938.417771200131, 24308.0, 24330.635040474408, 24358.756412436003, 24417.0, 24473.14343997987, 8512.829743405096, 8562.48625082497, 8567.357448191286, 8656.26421754158, 9025.684101056431, 5027.0, 5075.213341932006, 5107.41264754558, 5152.793888244503, 5213.322268105122, 3564.1267889366754, 3675.026118078711, 3695.9630403696156, 4011.438398624195, 4184.4141820145205, 682.6450245872218, 683.0505597219868, 683.1153656015472, 692.3215249055853, 695.25954858077, 782.8991051165983, 791.0407963736584, 794.7417089445953, 817.840521098392, 823.8269963307772, 10911.83632245517, 11311.321320720783, 11421.828672569918, 11442.885571144181, 11500.0, 687.4191308344541, 691.7751730687446, 715.1918332284589, 807.4226927746572, 815.8980127484065, 5037.748112594429, 5152.536271735562, 5161.535461808456, 5184.7926082956255, 5220.216967454836, 86511.56744216238, 108715.45642271738, 116777.0, 127636.36181819031, 133581.0, 72557.6372118136, 113125.0, 113806.0, 114530.0, 115254.0, 94119.52940235255, 94915.52542237245, 95382.52308738412, 97049.51475242579, 99795.00204997858, 10279.331843430153, 10331.32846364984, 10375.377477351383, 10475.42385168823, 10487.423191724529, 51644.48355516397, 54193.45806541884, 55777.72111139418, 56764.43235567756, 56801.71599141978, 84007.0, 85518.0, 85911.5704421499, 87227.0, 88417.0, 12547.184433011826, 12847.03647226443, 13733.587992360246, 13754.587362378745, 13841.30793460343, 15107.848921510644, 15262.3895044197, 15276.0, 15487.380504779683, 16138.0, 14130.293485325486, 14290.142591444546, 14585.489507867174, 14740.631484212552, 15062.096274223582, 13670.794938075738, 14008.229547374585, 14026.649333766329, 14214.21821799811, 14326.57020289393, 13451.865481345452, 13577.32113394346, 13864.861351386357, 13869.167849929037, 14145.080569762933, 11159.944200278947, 11729.648110556696, 11796.76406471883, 11862.0, 11862.525498979945, 12001.459934302802, 12255.387230638255, 12258.693532661398, 12355.196912200678, 12499.875001249871], "value": 8145.377072433612, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1514.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 112.0, 112.0, 113.0, 111.0, 113.0, 111.0, 111.0, 112.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 133.0, 153.0, 170.0, 127.0, 153.0, 157.0, 129.0, 188.0, 124.0], "value": 149.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 163.0, 163.0, 163.0, 164.0, 162.0, 163.0, 164.0, 162.0, 162.0], "value": 163.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 76.0, 76.0, 172.0, 76.0, 76.0, 74.0, 75.0, 160.0, 76.0], "value": 94.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 145.0, 85.0, 85.0, 86.0, 143.0, 84.0, 88.0, 144.0], "value": 103.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 118.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 147.0, 149.0, 148.0, 146.0, 148.0, 147.0, 149.0, 146.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 100.0, 95.0, 95.0, 95.0, 96.0, 97.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 51.0, 51.0, 53.0, 56.0, 52.0, 51.0, 51.0, 52.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 61.0, 61.0, 60.0, 61.0, 60.0, 60.0, 61.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 76.0, 80.0, 77.0, 80.0, 77.0, 80.0, 111.0, 77.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 119.0, 119.0, 119.0, 128.0, 117.0, 125.0, 139.0, 124.0, 126.0], "value": 123.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 153.0, 158.0, 154.0, 154.0, 150.0, 152.0, 155.0, 153.0, 152.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 62.0, 67.0, 59.0, 67.0, 60.0, 60.0, 63.0, 59.0], "value": 62.0, "unit": "score"}]}]} 03:53:00 INFO - Return code: 0 03:53:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:53:00 ERROR - # TBPL SUCCESS # 03:53:00 INFO - Running post-action listener: _resource_record_post_action 03:53:00 INFO - ##### 03:53:00 INFO - ##### Finished run-tests step (success) 03:53:00 INFO - ##### 03:53:00 INFO - Running post-run listener: _resource_record_post_run 03:53:00 INFO - Total resource usage - Wall time: 1191s; CPU: 14.0%; Read bytes: 4096; Write bytes: 504422400; Read time: 108; Write time: 1682996 03:53:00 INFO - TinderboxPrint: CPU usage
13.5% 03:53:00 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 108 03:53:00 INFO - TinderboxPrint: I/O write bytes / time
504,422,400 / 1,682,996 03:53:00 INFO - TinderboxPrint: CPU idle
8,218.4 (86.5%) 03:53:00 INFO - TinderboxPrint: CPU user
1,213.5 (12.8%) 03:53:00 INFO - TinderboxPrint: Swap in / out
0 / 0 03:53:00 INFO - install - Wall time: 16s; CPU: 21.0%; Read bytes: 0; Write bytes: 253218816; Read time: 0; Write time: 1276412 03:53:00 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 251203584; Read time: 108; Write time: 406584 03:53:00 INFO - Running post-run listener: _upload_blobber_files 03:53:00 INFO - Blob upload gear active. 03:53:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:53:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:53:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:53:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:53:01 INFO - (blobuploader) - INFO - Open directory for files ... 03:53:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:53:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:53:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:53:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:53:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:53:04 INFO - (blobuploader) - INFO - Done attempting. 03:53:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:53:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:53:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:53:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:53:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:53:05 INFO - (blobuploader) - INFO - Done attempting. 03:53:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:53:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:53:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:53:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:53:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:53:05 INFO - (blobuploader) - INFO - Done attempting. 03:53:05 INFO - (blobuploader) - INFO - Iteration through files over. 03:53:05 INFO - Return code: 0 03:53:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:53:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:53:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e35f613417cbfc68f81ea8bf15c6379eaea940dea6c5d568aeb86566a494b296292222ae7aab1214f3e04f3c32f1dd759236707fb220a419bd13a960ccea4b78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3690981147ba752d3d10c7c8593f69e2c31e7f822f54e4edef98f959d6d7021391e3ec434f3151511a3a0406f840cd570e267c23182bcd5334cbe150d333fd26", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ab53e9512927dbbb1244fd6f239cd1a034b5aa12683db0b8558d40a378f069985096d73553eabc9a970994da19db8e794a8ec4a1b4d849ec36b5e9b816e5ca6"} 03:53:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:53:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:53:05 INFO - Contents: 03:53:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e35f613417cbfc68f81ea8bf15c6379eaea940dea6c5d568aeb86566a494b296292222ae7aab1214f3e04f3c32f1dd759236707fb220a419bd13a960ccea4b78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3690981147ba752d3d10c7c8593f69e2c31e7f822f54e4edef98f959d6d7021391e3ec434f3151511a3a0406f840cd570e267c23182bcd5334cbe150d333fd26", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ab53e9512927dbbb1244fd6f239cd1a034b5aa12683db0b8558d40a378f069985096d73553eabc9a970994da19db8e794a8ec4a1b4d849ec36b5e9b816e5ca6"} 03:53:05 INFO - Running post-run listener: copy_logs_to_upload_dir 03:53:05 INFO - Copying logs to upload dir... 03:53:05 INFO - mkdir: /builds/slave/test/build/upload/logs 03:53:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.594286 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-06-19 03:53:04.386257) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-19 03:53:04.389947) ========= 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=df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e35f613417cbfc68f81ea8bf15c6379eaea940dea6c5d568aeb86566a494b296292222ae7aab1214f3e04f3c32f1dd759236707fb220a419bd13a960ccea4b78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3690981147ba752d3d10c7c8593f69e2c31e7f822f54e4edef98f959d6d7021391e3ec434f3151511a3a0406f840cd570e267c23182bcd5334cbe150d333fd26", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ab53e9512927dbbb1244fd6f239cd1a034b5aa12683db0b8558d40a378f069985096d73553eabc9a970994da19db8e794a8ec4a1b4d849ec36b5e9b816e5ca6"} build_url:https://queue.taskcluster.net/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011195 build_url: 'https://queue.taskcluster.net/v1/task/HpmdxIvOS7GCZ8YY-W9Bww/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e35f613417cbfc68f81ea8bf15c6379eaea940dea6c5d568aeb86566a494b296292222ae7aab1214f3e04f3c32f1dd759236707fb220a419bd13a960ccea4b78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3690981147ba752d3d10c7c8593f69e2c31e7f822f54e4edef98f959d6d7021391e3ec434f3151511a3a0406f840cd570e267c23182bcd5334cbe150d333fd26", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ab53e9512927dbbb1244fd6f239cd1a034b5aa12683db0b8558d40a378f069985096d73553eabc9a970994da19db8e794a8ec4a1b4d849ec36b5e9b816e5ca6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-19 03:53:04.416941) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-19 03:53:04.417296) ========= 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=df17445c3590ff7897617a0e00000225-1466330963.836902-1285021813 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004581 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-19 03:53:04.502671) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-19 03:53:04.503026) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-19 03:53:04.503340) ========= ========= Total master_lag: 0.18 =========