builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-006 starttime: 1465310964.82 results: success (0) buildid: 20160607062931 builduid: e5181fa809be4ec3b0547efd89456d65 revision: 7f7c7d24700eb80ce328b05fd260ec58e9725ca4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 07:49:24.817616) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 07:49:24.818630) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 07:49:24.819283) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 07:49:24.837286) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 07:49:24.837670) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-07 07:49:25-- 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% 224M=0s 2016-06-07 07:49:25 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.087865 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 07:49:24.941242) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 07:49:24.941631) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015775 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 07:49:24.991807) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-07 07:49:24.992235) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7f7c7d24700eb80ce328b05fd260ec58e9725ca4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 7f7c7d24700eb80ce328b05fd260ec58e9725ca4 --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=e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-07 07:49:25,554 truncating revision to first 12 chars 2016-06-07 07:49:25,555 Setting DEBUG logging. 2016-06-07 07:49:25,555 attempt 1/10 2016-06-07 07:49:25,555 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7f7c7d24700e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-07 07:49:25,883 unpacking tar archive at: mozilla-central-7f7c7d24700e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.124681 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-07 07:49:26.133610) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 07:49:26.133987) ========= script_repo_revision: 7f7c7d24700eb80ce328b05fd260ec58e9725ca4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 07:49:26.134458) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 07:49:26.134792) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 07:49:26.145047) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-06-07 07:49:26.145361) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:49:26 INFO - MultiFileLogger online at 20160607 07:49:26 in /builds/slave/test 07:49:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 07:49:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:49:26 INFO - {'append_to_log': False, 07:49:26 INFO - 'base_work_dir': '/builds/slave/test', 07:49:26 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:49:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:49:26 INFO - 'branch': 'Firefox-Non-PGO', 07:49:26 INFO - 'buildbot_json_path': 'buildprops.json', 07:49:26 INFO - 'config_files': ('talos/linux_config.py',), 07:49:26 INFO - 'default_actions': ('clobber', 07:49:26 INFO - 'read-buildbot-config', 07:49:26 INFO - 'download-and-extract', 07:49:26 INFO - 'populate-webroot', 07:49:26 INFO - 'create-virtualenv', 07:49:26 INFO - 'install', 07:49:26 INFO - 'run-tests'), 07:49:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:49:26 INFO - 'download_minidump_stackwalk': True, 07:49:26 INFO - 'download_symbols': 'ondemand', 07:49:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:49:26 INFO - 'tooltool.py': '/tools/tooltool.py', 07:49:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:49:26 INFO - '/tools/misc-python/virtualenv.py')}, 07:49:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:49:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:49:26 INFO - 'installer_path': 'installer.exe', 07:49:26 INFO - 'log_level': 'info', 07:49:26 INFO - 'log_name': 'talos', 07:49:26 INFO - 'log_to_console': True, 07:49:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:49:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:49:26 INFO - 'opt_config_files': (), 07:49:26 INFO - 'pip_index': False, 07:49:26 INFO - 'sps_profile': False, 07:49:26 INFO - 'sps_profile_interval': 0, 07:49:26 INFO - 'suite': 'dromaeojs', 07:49:26 INFO - 'system_bits': '32', 07:49:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:49:26 INFO - 'title': 'talos-linux64-ix-006', 07:49:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:49:26 INFO - 'use_talos_json': True, 07:49:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:49:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:49:26 INFO - 'work_dir': 'build'} 07:49:26 INFO - ##### 07:49:26 INFO - ##### Running clobber step. 07:49:26 INFO - ##### 07:49:26 INFO - Running pre-action listener: _resource_record_pre_action 07:49:26 INFO - Running main action method: clobber 07:49:26 INFO - rmtree: /builds/slave/test/build 07:49:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:49:27 INFO - Running post-action listener: _resource_record_post_action 07:49:27 INFO - ##### 07:49:27 INFO - ##### Finished clobber step (success) 07:49:27 INFO - ##### 07:49:27 INFO - ##### 07:49:27 INFO - ##### Running read-buildbot-config step. 07:49:27 INFO - ##### 07:49:27 INFO - Running pre-action listener: _resource_record_pre_action 07:49:27 INFO - Running main action method: read_buildbot_config 07:49:27 INFO - Using buildbot properties: 07:49:27 INFO - { 07:49:27 INFO - "project": "", 07:49:27 INFO - "product": "firefox", 07:49:27 INFO - "script_repo_revision": "production", 07:49:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:49:27 INFO - "repository": "", 07:49:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 07:49:27 INFO - "buildid": "20160607062931", 07:49:27 INFO - "pgo_build": "False", 07:49:27 INFO - "basedir": "/builds/slave/test", 07:49:27 INFO - "buildnumber": 110, 07:49:27 INFO - "slavename": "talos-linux64-ix-006", 07:49:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:49:27 INFO - "platform": "ubuntu64_hw", 07:49:27 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 07:49:27 INFO - "branch": "mozilla-central", 07:49:27 INFO - "repo_path": "mozilla-central", 07:49:27 INFO - "revision": "7f7c7d24700eb80ce328b05fd260ec58e9725ca4", 07:49:27 INFO - "stage_platform": "linux64", 07:49:27 INFO - "builduid": "e5181fa809be4ec3b0547efd89456d65", 07:49:27 INFO - "slavebuilddir": "test" 07:49:27 INFO - } 07:49:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 07:49:27 INFO - Running post-action listener: _resource_record_post_action 07:49:27 INFO - ##### 07:49:27 INFO - ##### Finished read-buildbot-config step (success) 07:49:27 INFO - ##### 07:49:27 INFO - ##### 07:49:27 INFO - ##### Running download-and-extract step. 07:49:27 INFO - ##### 07:49:27 INFO - Running pre-action listener: _resource_record_pre_action 07:49:27 INFO - Running main action method: download_and_extract 07:49:27 INFO - mkdir: /builds/slave/test/build/tests 07:49:27 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')]} 07:49:27 INFO - https://queue.taskcluster.net/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:49:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:49:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:49:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:49:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:49:28 INFO - Downloaded 1361 bytes. 07:49:28 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:49:28 INFO - Using the following test package requirements: 07:49:28 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 07:49:28 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:49:28 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:49:28 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:28 INFO - u'jsshell-linux-x86_64.zip'], 07:49:28 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:49:28 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 07:49:28 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:49:28 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:49:28 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:49:28 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:49:28 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:49:28 INFO - https://queue.taskcluster.net/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:49:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:49:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:49:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:49:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:49:29 INFO - Downloaded 22873594 bytes. 07:49:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:49:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:49:30 INFO - Return code: 0 07:49:30 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:49:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:49:30 INFO - https://queue.taskcluster.net/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:49:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:49:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:49:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:49:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:49:32 INFO - Downloaded 22873594 bytes. 07:49:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:49:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:49:33 INFO - Return code: 0 07:49:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:49:33 INFO - https://queue.taskcluster.net/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:49:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 07:49:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 07:49:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 07:49:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:49:34 INFO - Downloaded 10833369 bytes. 07:49:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:49:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 07:49:34 INFO - Return code: 0 07:49: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')]} 07:49:34 INFO - https://queue.taskcluster.net/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:49:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:49:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:49:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:49:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:49:36 INFO - Downloaded 55204146 bytes. 07:49:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:49:36 INFO - mkdir: /builds/slave/test/properties 07:49:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:49:36 INFO - Writing to file /builds/slave/test/properties/build_url 07:49:36 INFO - Contents: 07:49:36 INFO - build_url:https://queue.taskcluster.net/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:49:41 INFO - Running post-action listener: _resource_record_post_action 07:49:41 INFO - Running post-action listener: set_extra_try_arguments 07:49:41 INFO - ##### 07:49:41 INFO - ##### Finished download-and-extract step (success) 07:49:41 INFO - ##### 07:49:41 INFO - ##### 07:49:41 INFO - ##### Running populate-webroot step. 07:49:41 INFO - ##### 07:49:41 INFO - Running pre-action listener: _resource_record_pre_action 07:49:41 INFO - Running main action method: populate_webroot 07:49:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:49:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:49:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:49:41 INFO - '--tppagecycles', 07:49:41 INFO - '7'], 07:49:41 INFO - 'tests': ['tsvgm']}}, 07:49:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:49:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:49:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:49:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:49:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:49:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:49:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:41 INFO - 'talos_options': ['--disable-e10s'], 07:49:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:49:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:49:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:41 INFO - 'talos_options': ['--disable-e10s'], 07:49:41 INFO - 'tests': ['damp', 'tps']}, 07:49:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:41 INFO - 'tests': ['damp', 'tps']}, 07:49:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:49:41 INFO - 'tests': ['dromaeo_dom']}, 07:49:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:49:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:49:41 INFO - 'tests': ['a11yr', 07:49:41 INFO - 'ts_paint', 07:49:41 INFO - 'tpaint', 07:49:41 INFO - 'sessionrestore', 07:49:41 INFO - 'sessionrestore_no_auto_restore', 07:49:41 INFO - 'tabpaint']}, 07:49:41 INFO - 'other-e10s': {'tests': ['a11yr', 07:49:41 INFO - 'ts_paint', 07:49:41 INFO - 'tpaint', 07:49:41 INFO - 'sessionrestore', 07:49:41 INFO - 'sessionrestore_no_auto_restore', 07:49:41 INFO - 'tabpaint']}, 07:49:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:49:41 INFO - 'ts_paint', 07:49:41 INFO - 'tpaint', 07:49:41 INFO - 'sessionrestore', 07:49:41 INFO - 'sessionrestore_no_auto_restore', 07:49:41 INFO - 'tabpaint']}, 07:49:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:49:41 INFO - 'ts_paint', 07:49:41 INFO - 'tpaint', 07:49:41 INFO - 'sessionrestore', 07:49:41 INFO - 'sessionrestore_no_auto_restore', 07:49:41 INFO - 'tabpaint']}, 07:49:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:49:41 INFO - 'tests': ['a11yr', 07:49:41 INFO - 'ts_paint', 07:49:41 INFO - 'tpaint', 07:49:41 INFO - 'sessionrestore', 07:49:41 INFO - 'sessionrestore_no_auto_restore', 07:49:41 INFO - 'tabpaint']}, 07:49:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:49:41 INFO - 'tests': ['a11yr', 07:49:41 INFO - 'ts_paint', 07:49:41 INFO - 'tpaint', 07:49:41 INFO - 'sessionrestore', 07:49:41 INFO - 'sessionrestore_no_auto_restore', 07:49:41 INFO - 'tabpaint']}, 07:49:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:49:41 INFO - 'tests': ['tsvgx', 07:49:41 INFO - 'tsvgr_opacity', 07:49:41 INFO - 'tart', 07:49:41 INFO - 'tscrollx', 07:49:41 INFO - 'cart']}, 07:49:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:49:41 INFO - 'tsvgr_opacity', 07:49:41 INFO - 'tart', 07:49:41 INFO - 'tscrollx', 07:49:41 INFO - 'cart']}, 07:49:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:41 INFO - 'talos_options': ['--disable-e10s'], 07:49:41 INFO - 'tests': ['tp5o']}, 07:49:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:41 INFO - 'tests': ['tp5o']}, 07:49:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:41 INFO - 'talos_options': ['--disable-e10s', 07:49:41 INFO - '--xperf_path', 07:49:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:49:41 INFO - 'tests': ['tp5n']}, 07:49:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:41 INFO - 'talos_options': ['--xperf_path', 07:49:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:49:41 INFO - 'tests': ['tp5n']}}, 07:49:41 INFO - 'talos.zip': {'path': '', 07:49:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:49:41 INFO - Running post-action listener: _resource_record_post_action 07:49:41 INFO - ##### 07:49:41 INFO - ##### Finished populate-webroot step (success) 07:49:41 INFO - ##### 07:49:41 INFO - ##### 07:49:41 INFO - ##### Running create-virtualenv step. 07:49:41 INFO - ##### 07:49:41 INFO - Running pre-action listener: _resource_record_pre_action 07:49:41 INFO - Running main action method: create_virtualenv 07:49:41 INFO - Creating virtualenv /builds/slave/test/build/venv 07:49:41 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 07:49:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:49:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:49:41 INFO - Using real prefix '/usr' 07:49:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:49:42 INFO - Installing distribute.............................................................................................................................................................................................done. 07:49:44 INFO - Installing pip.................done. 07:49:44 INFO - Return code: 0 07:49:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:49:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd378321f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2497350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25261c0>, '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 0x25270c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2527550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', '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 07:49:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:49:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:49:44 INFO - Using env: {'DISPLAY': ':0', 07:49:44 INFO - 'HOME': '/home/cltbld', 07:49:44 INFO - 'LANG': 'en_US.UTF-8', 07:49:44 INFO - 'LANGUAGE': 'en_US:en', 07:49:44 INFO - 'LOGNAME': 'cltbld', 07:49:44 INFO - 'MAIL': '/var/mail/cltbld', 07:49:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:44 INFO - 'MOZ_NO_REMOTE': '1', 07:49:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:44 INFO - 'NO_EM_RESTART': '1', 07:49:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:44 INFO - 'PWD': '/builds/slave/test', 07:49:44 INFO - 'SHELL': '/bin/bash', 07:49:44 INFO - 'SHLVL': '1', 07:49:44 INFO - 'TERM': 'linux', 07:49:44 INFO - 'TMOUT': '86400', 07:49:44 INFO - 'USER': 'cltbld', 07:49:44 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', 07:49:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:44 INFO - '_': '/tools/buildbot/bin/python'} 07:49:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:44 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 07:49:44 INFO - Cleaning up... 07:49:44 INFO - Return code: 0 07:49:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:49:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fd378321f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2497350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25261c0>, '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 0x25270c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2527550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', '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 07:49:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:49:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:49:44 INFO - Using env: {'DISPLAY': ':0', 07:49:44 INFO - 'HOME': '/home/cltbld', 07:49:44 INFO - 'LANG': 'en_US.UTF-8', 07:49:44 INFO - 'LANGUAGE': 'en_US:en', 07:49:44 INFO - 'LOGNAME': 'cltbld', 07:49:44 INFO - 'MAIL': '/var/mail/cltbld', 07:49:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:44 INFO - 'MOZ_NO_REMOTE': '1', 07:49:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:44 INFO - 'NO_EM_RESTART': '1', 07:49:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:44 INFO - 'PWD': '/builds/slave/test', 07:49:44 INFO - 'SHELL': '/bin/bash', 07:49:44 INFO - 'SHLVL': '1', 07:49:44 INFO - 'TERM': 'linux', 07:49:44 INFO - 'TMOUT': '86400', 07:49:44 INFO - 'USER': 'cltbld', 07:49:44 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', 07:49:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:44 INFO - '_': '/tools/buildbot/bin/python'} 07:49:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:44 INFO - Downloading/unpacking psutil>=3.1.1 07:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:49:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:49:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:49:46 INFO - Installing collected packages: psutil 07:49:46 INFO - Running setup.py install for psutil 07:49:46 INFO - building 'psutil._psutil_linux' extension 07:49:46 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 07:49:47 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 07:49:47 INFO - building 'psutil._psutil_posix' extension 07:49:47 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 07:49:47 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 07:49:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:49:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:49:47 INFO - Successfully installed psutil 07:49:47 INFO - Cleaning up... 07:49:47 INFO - Return code: 0 07:49:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:49: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')]} 07:49:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49: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')]} 07:49:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd378321f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2497350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25261c0>, '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 0x25270c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2527550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', '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 07:49: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:49: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 mozsystemmonitor==0.3 07:49:47 INFO - Using env: {'DISPLAY': ':0', 07:49:47 INFO - 'HOME': '/home/cltbld', 07:49:47 INFO - 'LANG': 'en_US.UTF-8', 07:49:47 INFO - 'LANGUAGE': 'en_US:en', 07:49:47 INFO - 'LOGNAME': 'cltbld', 07:49:47 INFO - 'MAIL': '/var/mail/cltbld', 07:49:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:47 INFO - 'MOZ_NO_REMOTE': '1', 07:49:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:47 INFO - 'NO_EM_RESTART': '1', 07:49:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:47 INFO - 'PWD': '/builds/slave/test', 07:49:47 INFO - 'SHELL': '/bin/bash', 07:49:47 INFO - 'SHLVL': '1', 07:49:47 INFO - 'TERM': 'linux', 07:49:47 INFO - 'TMOUT': '86400', 07:49:47 INFO - 'USER': 'cltbld', 07:49:47 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', 07:49:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:47 INFO - '_': '/tools/buildbot/bin/python'} 07:49:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:49: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 07:49: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 07:49: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 07:49: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 07:49: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 07:49: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 07:49:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:49:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:49:49 INFO - Installing collected packages: mozsystemmonitor 07:49:49 INFO - Running setup.py install for mozsystemmonitor 07:49:49 INFO - Successfully installed mozsystemmonitor 07:49:49 INFO - Cleaning up... 07:49:49 INFO - Return code: 0 07:49:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:49: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')]} 07:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49: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')]} 07:49:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49: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', '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 0x7fd378321f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2497350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25261c0>, '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 0x25270c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2527550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', '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 07:49: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:49: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 jsonschema==2.5.1 07:49:49 INFO - Using env: {'DISPLAY': ':0', 07:49:49 INFO - 'HOME': '/home/cltbld', 07:49:49 INFO - 'LANG': 'en_US.UTF-8', 07:49:49 INFO - 'LANGUAGE': 'en_US:en', 07:49:49 INFO - 'LOGNAME': 'cltbld', 07:49:49 INFO - 'MAIL': '/var/mail/cltbld', 07:49:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:49 INFO - 'MOZ_NO_REMOTE': '1', 07:49:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:49 INFO - 'NO_EM_RESTART': '1', 07:49:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:49 INFO - 'PWD': '/builds/slave/test', 07:49:49 INFO - 'SHELL': '/bin/bash', 07:49:49 INFO - 'SHLVL': '1', 07:49:49 INFO - 'TERM': 'linux', 07:49:49 INFO - 'TMOUT': '86400', 07:49:49 INFO - 'USER': 'cltbld', 07:49:49 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', 07:49:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:49 INFO - '_': '/tools/buildbot/bin/python'} 07:49:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:49 INFO - Downloading/unpacking jsonschema==2.5.1 07:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:49:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:52 INFO - Downloading functools32-3.2.3-2.tar.gz 07:49:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:49:52 INFO - warning: no files found matching '*.txt' 07:49:52 INFO - no previously-included directories found matching 'build' 07:49:52 INFO - no previously-included directories found matching 'dist' 07:49:52 INFO - no previously-included directories found matching '.git*' 07:49:52 INFO - Installing collected packages: jsonschema, functools32 07:49:52 INFO - Running setup.py install for functools32 07:49:52 INFO - warning: no files found matching '*.txt' 07:49:52 INFO - no previously-included directories found matching 'build' 07:49:52 INFO - no previously-included directories found matching 'dist' 07:49:52 INFO - no previously-included directories found matching '.git*' 07:49:52 INFO - Successfully installed jsonschema functools32 07:49:52 INFO - Cleaning up... 07:49:52 INFO - Return code: 0 07:49:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:49: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')]} 07:49:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49: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')]} 07:49:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49: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', '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 0x7fd378321f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2497350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25261c0>, '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 0x25270c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2527550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', '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 07:49: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:49: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 blobuploader==1.2.4 07:49:52 INFO - Using env: {'DISPLAY': ':0', 07:49:52 INFO - 'HOME': '/home/cltbld', 07:49:52 INFO - 'LANG': 'en_US.UTF-8', 07:49:52 INFO - 'LANGUAGE': 'en_US:en', 07:49:52 INFO - 'LOGNAME': 'cltbld', 07:49:52 INFO - 'MAIL': '/var/mail/cltbld', 07:49:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:52 INFO - 'MOZ_NO_REMOTE': '1', 07:49:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:52 INFO - 'NO_EM_RESTART': '1', 07:49:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:52 INFO - 'PWD': '/builds/slave/test', 07:49:52 INFO - 'SHELL': '/bin/bash', 07:49:52 INFO - 'SHLVL': '1', 07:49:52 INFO - 'TERM': 'linux', 07:49:52 INFO - 'TMOUT': '86400', 07:49:52 INFO - 'USER': 'cltbld', 07:49:52 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', 07:49:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:52 INFO - '_': '/tools/buildbot/bin/python'} 07:49:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:52 INFO - Downloading/unpacking blobuploader==1.2.4 07:49: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 07:49: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 07:49: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 07:49: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 07:49: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 07:49: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 07:49:54 INFO - Downloading blobuploader-1.2.4.tar.gz 07:49:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:49:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:49: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 07:49: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 07:49: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 07:49: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 07:49: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 07:49: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 07:49:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:49:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:49:55 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 07:49:55 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 07:49:55 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 07:49:55 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 07:49:55 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 07:49:55 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 07:49:55 INFO - Downloading docopt-0.6.1.tar.gz 07:49:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:49:55 INFO - Installing collected packages: blobuploader, requests, docopt 07:49:55 INFO - Running setup.py install for blobuploader 07:49:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:49:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:49:55 INFO - Running setup.py install for requests 07:49:55 INFO - Running setup.py install for docopt 07:49:55 INFO - Successfully installed blobuploader requests docopt 07:49:55 INFO - Cleaning up... 07:49:55 INFO - Return code: 0 07:49:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:49:55 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')]} 07:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:55 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')]} 07:49:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:55 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 0x7fd378321f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2497350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25261c0>, '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 0x25270c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2527550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', '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 07:49:55 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 07:49:55 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 07:49:55 INFO - Using env: {'DISPLAY': ':0', 07:49:55 INFO - 'HOME': '/home/cltbld', 07:49:55 INFO - 'LANG': 'en_US.UTF-8', 07:49:55 INFO - 'LANGUAGE': 'en_US:en', 07:49:55 INFO - 'LOGNAME': 'cltbld', 07:49:55 INFO - 'MAIL': '/var/mail/cltbld', 07:49:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:55 INFO - 'MOZ_NO_REMOTE': '1', 07:49:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:55 INFO - 'NO_EM_RESTART': '1', 07:49:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:55 INFO - 'PWD': '/builds/slave/test', 07:49:55 INFO - 'SHELL': '/bin/bash', 07:49:55 INFO - 'SHLVL': '1', 07:49:55 INFO - 'TERM': 'linux', 07:49:55 INFO - 'TMOUT': '86400', 07:49:55 INFO - 'USER': 'cltbld', 07:49:55 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', 07:49:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:55 INFO - '_': '/tools/buildbot/bin/python'} 07:49:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:49:56 INFO - Running setup.py (path:/tmp/pip-ajgXEn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:49:56 INFO - Running setup.py (path:/tmp/pip-iTrxfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:49:56 INFO - Running setup.py (path:/tmp/pip-tNU1MN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:49:56 INFO - Running setup.py (path:/tmp/pip-YjOcBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:49:56 INFO - Running setup.py (path:/tmp/pip-f4dyzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:49:56 INFO - Running setup.py (path:/tmp/pip-JJWlp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:49:56 INFO - Running setup.py (path:/tmp/pip-Yiqpba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:49:56 INFO - Running setup.py (path:/tmp/pip-yrnNR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:49:56 INFO - Running setup.py (path:/tmp/pip-BD8ZRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:49:56 INFO - Running setup.py (path:/tmp/pip-JAyNcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:49:56 INFO - Running setup.py (path:/tmp/pip-iDSEfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:49:56 INFO - Running setup.py (path:/tmp/pip-aToU8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:49:56 INFO - Running setup.py (path:/tmp/pip-pIE8UH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:49:57 INFO - Running setup.py (path:/tmp/pip-TnrnST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:57 INFO - Running setup.py (path:/tmp/pip-_hwq28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:49:57 INFO - Running setup.py (path:/tmp/pip-X8Nl4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:49:57 INFO - Running setup.py (path:/tmp/pip-sr5vpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:49:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:49:57 INFO - Running setup.py install for manifestparser 07:49:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:49:57 INFO - Running setup.py install for mozcrash 07:49:57 INFO - Running setup.py install for mozdebug 07:49:57 INFO - Running setup.py install for mozdevice 07:49:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:49:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:49:57 INFO - Running setup.py install for mozfile 07:49:57 INFO - Running setup.py install for mozhttpd 07:49:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:49:57 INFO - Running setup.py install for mozinfo 07:49:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:49:58 INFO - Running setup.py install for mozInstall 07:49:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:49:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:49:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:49:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:49:58 INFO - Running setup.py install for mozleak 07:49:58 INFO - Running setup.py install for mozlog 07:49:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:49:58 INFO - Running setup.py install for moznetwork 07:49:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:49:58 INFO - Running setup.py install for mozprocess 07:49:58 INFO - Running setup.py install for mozprofile 07:49:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:49:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:49:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:49:58 INFO - Running setup.py install for mozrunner 07:49:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:49:58 INFO - Running setup.py install for mozscreenshot 07:49:58 INFO - Running setup.py install for moztest 07:49:58 INFO - Running setup.py install for mozversion 07:49:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:49:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:49:59 INFO - Cleaning up... 07:49:59 INFO - Return code: 0 07:49:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:49:59 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')]} 07:49:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:59 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')]} 07:49:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:59 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 0x7fd378321f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2497350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25261c0>, '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 0x25270c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2527550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', '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 07:49:59 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 07:49:59 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 07:49:59 INFO - Using env: {'DISPLAY': ':0', 07:49:59 INFO - 'HOME': '/home/cltbld', 07:49:59 INFO - 'LANG': 'en_US.UTF-8', 07:49:59 INFO - 'LANGUAGE': 'en_US:en', 07:49:59 INFO - 'LOGNAME': 'cltbld', 07:49:59 INFO - 'MAIL': '/var/mail/cltbld', 07:49:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:59 INFO - 'MOZ_NO_REMOTE': '1', 07:49:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:59 INFO - 'NO_EM_RESTART': '1', 07:49:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:59 INFO - 'PWD': '/builds/slave/test', 07:49:59 INFO - 'SHELL': '/bin/bash', 07:49:59 INFO - 'SHLVL': '1', 07:49:59 INFO - 'TERM': 'linux', 07:49:59 INFO - 'TMOUT': '86400', 07:49:59 INFO - 'USER': 'cltbld', 07:49:59 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', 07:49:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:59 INFO - '_': '/tools/buildbot/bin/python'} 07:49:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:49:59 INFO - Running setup.py (path:/tmp/pip-IvqAY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:49:59 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)) 07:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:49:59 INFO - Running setup.py (path:/tmp/pip-t7YHOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:49:59 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)) 07:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:49:59 INFO - Running setup.py (path:/tmp/pip-L8hnci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:49:59 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)) 07:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:49:59 INFO - Running setup.py (path:/tmp/pip-vU3uCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:49:59 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)) 07:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:49:59 INFO - Running setup.py (path:/tmp/pip-ZGLv5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:49:59 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)) 07:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:49:59 INFO - Running setup.py (path:/tmp/pip-E_QJuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:49:59 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)) 07:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:49:59 INFO - Running setup.py (path:/tmp/pip-95ZPy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:49:59 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)) 07:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:49:59 INFO - Running setup.py (path:/tmp/pip-wcGTCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:49:59 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)) 07:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:49:59 INFO - Running setup.py (path:/tmp/pip-odRS6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:49:59 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)) 07:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:49:59 INFO - Running setup.py (path:/tmp/pip-Om6rCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:50:00 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)) 07:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:50:00 INFO - Running setup.py (path:/tmp/pip-cHvO3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:50:00 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)) 07:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:50:00 INFO - Running setup.py (path:/tmp/pip-3WaEKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:50:00 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)) 07:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:50:00 INFO - Running setup.py (path:/tmp/pip-ljfjzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:50:00 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)) 07:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:50:00 INFO - Running setup.py (path:/tmp/pip-sGxaio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:50:00 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)) 07:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:50:00 INFO - Running setup.py (path:/tmp/pip-GpUDwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:50:00 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)) 07:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:50:00 INFO - Running setup.py (path:/tmp/pip-1hHPrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:50:00 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)) 07:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:50:00 INFO - Running setup.py (path:/tmp/pip-do_URn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:50:00 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)) 07:50:00 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)) 07:50:00 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)) 07:50:00 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)) 07:50:00 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)) 07:50:00 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)) 07:50:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:50:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:02 INFO - Downloading blessings-1.6.tar.gz 07:50:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:50:02 INFO - Installing collected packages: blessings 07:50:02 INFO - Running setup.py install for blessings 07:50:02 INFO - Successfully installed blessings 07:50:02 INFO - Cleaning up... 07:50:02 INFO - Return code: 0 07:50:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:50:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:50:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:50:02 INFO - Reading from file tmpfile_stdout 07:50:02 INFO - Current package versions: 07:50:02 INFO - argparse == 1.2.1 07:50:02 INFO - blessings == 1.6 07:50:02 INFO - blobuploader == 1.2.4 07:50:02 INFO - docopt == 0.6.1 07:50:02 INFO - functools32 == 3.2.3-2 07:50:02 INFO - jsonschema == 2.5.1 07:50:02 INFO - manifestparser == 1.1 07:50:02 INFO - mozInstall == 1.12 07:50:02 INFO - mozcrash == 0.17 07:50:02 INFO - mozdebug == 0.1 07:50:02 INFO - mozdevice == 0.48 07:50:02 INFO - mozfile == 1.2 07:50:02 INFO - mozhttpd == 0.7 07:50:02 INFO - mozinfo == 0.9 07:50:02 INFO - mozleak == 0.1 07:50:02 INFO - mozlog == 3.2 07:50:02 INFO - moznetwork == 0.27 07:50:02 INFO - mozprocess == 0.22 07:50:02 INFO - mozprofile == 0.28 07:50:02 INFO - mozrunner == 6.11 07:50:02 INFO - mozscreenshot == 0.1 07:50:02 INFO - mozsystemmonitor == 0.3 07:50:02 INFO - moztest == 0.7 07:50:02 INFO - mozversion == 1.4 07:50:02 INFO - psutil == 3.1.1 07:50:02 INFO - requests == 1.2.3 07:50:02 INFO - wsgiref == 0.1.2 07:50:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:50:02 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')]} 07:50:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:02 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')]} 07:50:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:02 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 0x7fd378321f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2497350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25261c0>, '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 0x25270c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2527550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', '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 07:50:02 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 07:50:02 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 07:50:02 INFO - Using env: {'DISPLAY': ':0', 07:50:02 INFO - 'HOME': '/home/cltbld', 07:50:02 INFO - 'LANG': 'en_US.UTF-8', 07:50:02 INFO - 'LANGUAGE': 'en_US:en', 07:50:02 INFO - 'LOGNAME': 'cltbld', 07:50:02 INFO - 'MAIL': '/var/mail/cltbld', 07:50:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:02 INFO - 'MOZ_NO_REMOTE': '1', 07:50:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:02 INFO - 'NO_EM_RESTART': '1', 07:50:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:02 INFO - 'PWD': '/builds/slave/test', 07:50:02 INFO - 'SHELL': '/bin/bash', 07:50:02 INFO - 'SHLVL': '1', 07:50:02 INFO - 'TERM': 'linux', 07:50:02 INFO - 'TMOUT': '86400', 07:50:02 INFO - 'USER': 'cltbld', 07:50:02 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', 07:50:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:02 INFO - '_': '/tools/buildbot/bin/python'} 07:50:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:03 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)) 07:50:03 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)) 07:50:03 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)) 07:50:03 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)) 07:50:03 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)) 07:50:03 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)) 07:50:03 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)) 07:50:03 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)) 07:50:03 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)) 07:50:03 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)) 07:50:03 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)) 07:50:03 INFO - Cleaning up... 07:50:03 INFO - Return code: 0 07:50:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:50:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:03 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 0x7fd378321f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2497350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25261c0>, '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 0x25270c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2527550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', '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 07:50:03 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 07:50:03 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 07:50:03 INFO - Using env: {'DISPLAY': ':0', 07:50:03 INFO - 'HOME': '/home/cltbld', 07:50:03 INFO - 'LANG': 'en_US.UTF-8', 07:50:03 INFO - 'LANGUAGE': 'en_US:en', 07:50:03 INFO - 'LOGNAME': 'cltbld', 07:50:03 INFO - 'MAIL': '/var/mail/cltbld', 07:50:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:03 INFO - 'MOZ_NO_REMOTE': '1', 07:50:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:03 INFO - 'NO_EM_RESTART': '1', 07:50:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:03 INFO - 'PWD': '/builds/slave/test', 07:50:03 INFO - 'SHELL': '/bin/bash', 07:50:03 INFO - 'SHLVL': '1', 07:50:03 INFO - 'TERM': 'linux', 07:50:03 INFO - 'TMOUT': '86400', 07:50:03 INFO - 'USER': 'cltbld', 07:50:03 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', 07:50:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:03 INFO - '_': '/tools/buildbot/bin/python'} 07:50:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:50:03 INFO - Cleaning up... 07:50:03 INFO - Return code: 0 07:50:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:50:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:03 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 0x7fd378321f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2497350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25261c0>, '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 0x25270c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2527550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', '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 07:50:03 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 07:50:03 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 07:50:03 INFO - Using env: {'DISPLAY': ':0', 07:50:03 INFO - 'HOME': '/home/cltbld', 07:50:03 INFO - 'LANG': 'en_US.UTF-8', 07:50:03 INFO - 'LANGUAGE': 'en_US:en', 07:50:03 INFO - 'LOGNAME': 'cltbld', 07:50:03 INFO - 'MAIL': '/var/mail/cltbld', 07:50:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:03 INFO - 'MOZ_NO_REMOTE': '1', 07:50:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:03 INFO - 'NO_EM_RESTART': '1', 07:50:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:03 INFO - 'PWD': '/builds/slave/test', 07:50:03 INFO - 'SHELL': '/bin/bash', 07:50:03 INFO - 'SHLVL': '1', 07:50:03 INFO - 'TERM': 'linux', 07:50:03 INFO - 'TMOUT': '86400', 07:50:03 INFO - 'USER': 'cltbld', 07:50:03 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', 07:50:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:03 INFO - '_': '/tools/buildbot/bin/python'} 07:50:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:03 INFO - Downloading/unpacking flake8 07:50:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:50:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:50:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:50:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:50: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 07:50: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 07:50: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 07:50: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 07:50: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 07:50: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 07:50:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:50:05 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:50: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 07:50: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 07:50: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 07:50: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 07:50: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 07:50: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 07:50:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:50:06 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 07:50:06 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 07:50:06 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 07:50:06 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 07:50:06 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 07:50:06 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 07:50:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:50:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:50:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:50:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:50:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:50:06 INFO - no previously-included directories found matching 'docs/_build' 07:50:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:50:06 INFO - Running setup.py install for pep8 07:50:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:50:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:50:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:50:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:50:06 INFO - no previously-included directories found matching 'docs/_build' 07:50:07 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:50:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:50:07 INFO - Cleaning up... 07:50:07 INFO - Return code: 0 07:50:07 INFO - Running post-action listener: _resource_record_post_action 07:50:07 INFO - Running post-action listener: _start_resource_monitoring 07:50:07 INFO - Starting resource monitoring. 07:50:07 INFO - ##### 07:50:07 INFO - ##### Finished create-virtualenv step (success) 07:50:07 INFO - ##### 07:50:07 INFO - ##### 07:50:07 INFO - ##### Running install step. 07:50:07 INFO - ##### 07:50:07 INFO - Running pre-action listener: _resource_record_pre_action 07:50:07 INFO - Running main action method: install 07:50:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:50:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:50:07 INFO - Reading from file tmpfile_stdout 07:50:07 INFO - Detecting whether we're running mozinstall >=1.0... 07:50:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:50:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:50:07 INFO - Reading from file tmpfile_stdout 07:50:07 INFO - Output received: 07:50:07 INFO - Usage: mozinstall [options] installer 07:50:07 INFO - Options: 07:50:07 INFO - -h, --help show this help message and exit 07:50:07 INFO - -d DEST, --destination=DEST 07:50:07 INFO - Directory to install application into. [default: 07:50:07 INFO - "/builds/slave/test"] 07:50:07 INFO - --app=APP Application being installed. [default: firefox] 07:50:07 INFO - mkdir: /builds/slave/test/build/application 07:50:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:50:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:50:23 INFO - Reading from file tmpfile_stdout 07:50:23 INFO - Output received: 07:50:23 INFO - /builds/slave/test/build/application/firefox/firefox 07:50:23 INFO - Running post-action listener: _resource_record_post_action 07:50:23 INFO - ##### 07:50:23 INFO - ##### Finished install step (success) 07:50:23 INFO - ##### 07:50:23 INFO - ##### 07:50:23 INFO - ##### Running run-tests step. 07:50:23 INFO - ##### 07:50:23 INFO - Running pre-action listener: _resource_record_pre_action 07:50:23 INFO - Running main action method: run_tests 07:50:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:50:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:50:23 INFO - Python 2.7.3 07:50:23 INFO - Return code: 0 07:50:23 INFO - grabbing minidump binary from tooltool 07:50:23 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')]} 07:50:23 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 0x25261c0>, '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 0x25270c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2527550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:50:23 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 07:50:23 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 07:50:23 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 07:50:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:50:23 INFO - Return code: 0 07:50:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:50:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:50:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:50:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:50:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:50:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:50:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:50:24 INFO - Return code: 0 07:50:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:50:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:50:24 INFO - Using env: {'DISPLAY': ':0', 07:50:24 INFO - 'HOME': '/home/cltbld', 07:50:24 INFO - 'LANG': 'en_US.UTF-8', 07:50:24 INFO - 'LANGUAGE': 'en_US:en', 07:50:24 INFO - 'LOGNAME': 'cltbld', 07:50:24 INFO - 'MAIL': '/var/mail/cltbld', 07:50:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:50:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:50:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:24 INFO - 'MOZ_NO_REMOTE': '1', 07:50:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:50:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:24 INFO - 'NO_EM_RESTART': '1', 07:50:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:24 INFO - 'PWD': '/builds/slave/test', 07:50:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:50:24 INFO - 'SHELL': '/bin/bash', 07:50:24 INFO - 'SHLVL': '1', 07:50:24 INFO - 'TERM': 'linux', 07:50:24 INFO - 'TMOUT': '86400', 07:50:24 INFO - 'USER': 'cltbld', 07:50:24 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049', 07:50:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:24 INFO - '_': '/tools/buildbot/bin/python'} 07:50:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:50:25 INFO - mozversion application_buildid: 20160607062931 07:50:25 INFO - mozversion application_changeset: 7f7c7d24700eb80ce328b05fd260ec58e9725ca4 07:50:25 INFO - mozversion application_display_name: Nightly 07:50:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:50:25 INFO - mozversion application_name: Firefox 07:50:25 INFO - mozversion application_remotingname: firefox 07:50:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:50:25 INFO - mozversion application_vendor: Mozilla 07:50:25 INFO - mozversion application_version: 50.0a1 07:50:25 INFO - mozversion platform_buildid: 20160607062931 07:50:25 INFO - mozversion platform_changeset: 7f7c7d24700eb80ce328b05fd260ec58e9725ca4 07:50:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:50:25 INFO - mozversion platform_version: 50.0a1 07:50:25 INFO - using testdate: 1465311025 07:50:25 INFO - actual date: 1465311025 07:50:25 INFO - starting webserver on 'localhost:51681' 07:50:25 INFO - SUITE-START | Running 2 tests 07:50:25 INFO - TEST-START | dromaeo_css 07:50:25 INFO - operating with platform_type : linux_ 07:50:25 INFO - Initialising browser for dromaeo_css test... 07:50:25 INFO - TEST-INFO | started process 19533 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmytpGX/profile http://localhost:51681/getInfo.html) 07:50:29 INFO - PROCESS | 19533 | __metrics Screen width/height:1600/1200 07:50:29 INFO - PROCESS | 19533 | colorDepth:24 07:50:29 INFO - PROCESS | 19533 | Browser inner width/height: 1024/697 07:50:29 INFO - PROCESS | 19533 | __metrics 07:50:30 ERROR - PROCESS | 19533 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:50:30 INFO - PROCESS | 19533 | console.error: PushService: 07:50:30 INFO - PROCESS | 19533 | stateChangeProcessEnqueue: Error transitioning state 07:50:30 INFO - PROCESS | 19533 | UnknownError 07:50:31 INFO - TEST-INFO | 19533: exit 0 07:50:31 INFO - Browser initialized. 07:50:31 INFO - Running cycle 1/1 for dromaeo_css test... 07:50:31 INFO - TEST-INFO | started process 19615 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmytpGX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:50:43 INFO - PROCESS | 19615 | RSS: Main: 157401088 07:50:43 INFO - PROCESS | 19615 | 07:51:34 INFO - PROCESS | 19615 | 1465311094519 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] 07:53:46 INFO - PROCESS | 19615 | Cycle 1(1): loaded http://localhost:51681/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51681/tests/dromaeo/cssquery-ext.html) 07:53:47 INFO - PROCESS | 19615 | RSS: Main: 265302016 07:53:47 INFO - PROCESS | 19615 | 07:56:49 INFO - PROCESS | 19615 | Cycle 1(1): loaded http://localhost:51681/tests/dromaeo/cssquery-ext.html (next: http://localhost:51681/tests/dromaeo/cssquery-jquery.html) 07:56:50 INFO - PROCESS | 19615 | RSS: Main: 244297728 07:56:50 INFO - PROCESS | 19615 | 07:59:52 INFO - PROCESS | 19615 | Cycle 1(1): loaded http://localhost:51681/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51681/tests/dromaeo/cssquery-mootools.html) 07:59:53 INFO - PROCESS | 19615 | RSS: Main: 244215808 07:59:53 INFO - PROCESS | 19615 | 08:02:55 INFO - PROCESS | 19615 | Cycle 1(1): loaded http://localhost:51681/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51681/tests/dromaeo/cssquery-prototype.html) 08:02:56 INFO - PROCESS | 19615 | RSS: Main: 233611264 08:02:56 INFO - PROCESS | 19615 | 08:05:59 INFO - PROCESS | 19615 | Cycle 1(1): loaded http://localhost:51681/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51681/tests/dromaeo/cssquery-yui.html) 08:05:59 INFO - PROCESS | 19615 | RSS: Main: 216727552 08:05:59 INFO - PROCESS | 19615 | 08:09:02 INFO - PROCESS | 19615 | Cycle 1(1): loaded http://localhost:51681/tests/dromaeo/cssquery-yui.html (next: http://localhost:51681/tests/dromaeo/cssquery-dojo.html) 08:09:03 INFO - PROCESS | 19615 | RSS: Main: 273117184 08:09:03 INFO - PROCESS | 19615 | 08:09:03 INFO - PROCESS | 19615 | __start_tp_report 08:09:03 INFO - PROCESS | 19615 | _x_x_mozilla_page_load 08:09:03 INFO - PROCESS | 19615 | _x_x_mozilla_page_load_details 08:09:03 INFO - PROCESS | 19615 | |i|pagename|runs| 08:09:03 INFO - PROCESS | 19615 | |0;dojo.html;2641.960370594441;2654.8407095574266;2655.203438968309;2667.7065522792477;2685.9328516787077;4498.302763071721;4607.470140933785;4667;4716.1746694328485;4757.286407038941;4665.2768820832825;4697.365855609501;4711.575958163765;4747.264174053019;4750.5724484796365;4114.23886580982;4280.828766849322;4339.522652508222;4384.539623339535;4462.419885414892;4373.278409062501;4406.052698669785;4417.955820441803;4426.203283408985;4458.353538736881;4182.35173548099;4226.957730422687;4309.310510318365;4344.109457561224;4370.781460926973;4241.766702831343;4304.78476076195;4329.112531930978;4364.410804541378;4417.425734654475;4328.588784065509;4347.478302603676;4351.477822661301;4387.802548885308;4394.12117576486;4420.3369494575745;4548.295014272793;4561.315802629599;4625.398698169251;4631.722096674211;5010.849674509771;5075.264086707443;5083.822066227664;5088.313077734532;5089.05852417304;5080.872978175538;5098.719570423626;5099.745012749385;5108.131617624976;5115.462876397962;4524.23088075028;4638.350630911675;4704.764761761967;4706.129366067288;4770.356001939694;5209.921851172236;5214.973925130426;5226.660267082628;5228.137357336073;5237.6071794615555;4956.281339205867;4960.131976904027;4997;5002.224655178453;5012.649114562018;4799.232122860326;4966.056449274626;5025.120603894304;5045.722485263273;5091.981603679279;5016.573591244712;5021.322121513622;5027.2207807789855;5034.521720436553;5070.61970352225;5008.774605142778;5014.04733100717;5015.724135172528;5021.648484606116;5048.5203905628905;4102.077032667626;4150.896227594334;4155.168966206771;4195.035141917315;4299.07569872479;3693.2244228711734;3724.832382542792;3760.9247815043545;3761.736678432538;3799.5440547133967;4396.934045989313;4398.824047038147;4399.032212913154;4433.600975912119;4446.73319600825;4831.710097394168;5097.082525145435;5105.795768169231;5109.923351149736;5112.130937740593;4247.086876321606;4291.270484017725;4337.7397356158735;4344.152890186447;4360.41134446852;4502.662300327423;4507.684462087623;4510.436195475566;4524.049949510574;4551.544845515488;4534.115847409724;4708.505606911293;4751.049790041936;4753.049390121921;4780.569748722632;5001.299818025406;5043.092243396224;5055.368078990126;5079.6698214615935;5094.923576146287;4776.379070720784;4786.8085276588545;4788.233882578771;4789.736564488987;4836.758162091811;4731.432228132646;4921.557059864629;5013.147764880052;5017.347744793154;5063.265826455217;5022.748862556785;5038.1435156022835;5067.41724701664;5067.442581316126;5134.9486505135965;4865.464798872051;4894.314795928502;4896.338994235733;4915.705057696549;4930.926036109389;4689.812407503799;4855.5387238212315;4916.434610019894;4918.704877707349;4979.875503112451;4823.541763532458;4843.685160464559;4880.682755620874;4894.192458244493;4897.681650692693;4998.450170481171;5000.824971125993;5001.799928002984;5029.572486338702;5046.29351890743;4610.492845786894;4795.328653988375;4869.196582563979;4878.56092951636;4909.337239472626 08:09:03 INFO - PROCESS | 19615 | |1;ext.html;544.0058527526228;571.9084946408575;590.5275779376495;591.8194950540084;594.8096609085093;26170.214893553177;26705.198844034687;38944;39693.60306396935;39822.20355592886;39799.204015919735;40304.395434068436;40383;40496.59503404965;40520;7668.194839541851;7871.252231038042;7872;7874.3700503959835;7877.527348359088;7674.884876726854;7842.137364889858;7842.568658723768;7843.254890785366;7845.568493732842;7833.255840695153;7894.2105789421175;7898.368130549571;7899.170587088359;7903.3282171015535;7834.882476762825;7905.288524032807;7905.881411778828;7908.051033875971;7908.9604551977445;7676.193999630014;7759.146493885668;7795.259450352264;7827.96086019572;7828.843423131562;37206.44190337147;37568.24863502714;37584.43623345652;37736.622633773586;37750.62249377499;2999.355138645202;3102.860371283298;3132.4674805283157;3232.2888964428025;3747.925041499182;28106.859465702542;28374.52990265645;28422.573661395094;28918.566221506695;28937.42125157485;3543.273628906094;3560.092176494977;3564.7682900611376;3564.946525802115;3577.105723569108;256089;257495;257758;258209;258375.70812146008;50853.23720144201;51477;51722.48277517252;51763.223551646755;51802;32620.347593048005;34062.318753624786;34103.31793364169;35816.820915895776;35947.4607880882;15381.769273460906;15480;15553.533393998197;15561.922190389201;15563.22183890801;11888.167828252112;11971.700707482209;11987.34069626179;11996.280223186637;12000.57997970067;18273.725894111598;18277.3602923897;18312.359067432575;18404.26382944667;18489.7226541602;6981.997740971329;7039.753608623673;7136.393406560397;7165.390941769903;7201.243869393743;817.3052905030795;894.5169608411516;933.4166146158651;957.951043607249;996.9950150249202;26849.86575067112;27069.458610827674;27627;27646;28177.57733633997;846.6486408140676;949.7910459698855;958.8657587937695;973.7176218896441;1004.7689031522791;13972.231527266098;14548.85451145475;14588.927055364655;14841.257937103102;14893.32980015902;393979;394106;396948;397357;397954;45741.313880291826;46908.53091469042;46933.5306646936;46952;47098.76450617793;1109.6948339206783;1202.7594481103815;1230.870758570355;1234.0867757859119;1236.8948639365756;27780.166595001374;29585.112446626637;29811.70188298089;30262;30304.84847575748;42485.787571063185;43351.566484336014;43397.78301108474;43417.56582434135;43461.565384345755;351114;354225;356461;361728;588572;38170.236595267386;39197.804010979766;39855.601443985186;41839.1632167361;42410.363844542975;42562;42951.57048429476;43046.784766077224;43363.78318108389;43931.560684392745;35363.823180883934;36865.44701829529;37299;37494.812525937195;37808.62191378051;36028.27943441169;36652.26695466129;36657.63342366542;36978.815105924295;36979.07552311214;35924.46113308355;36081;36820.26359472742;37022.44466333061;39470.40794388029;30329.393412132074;33267.33465330728;33514.329713406085;33545.83227083849;33606.831965840014;25045.74954250434;25175.37061573475;25420.237392878244;25605.231843044738;25783.226503204933 08:09:03 INFO - PROCESS | 19615 | |2;jquery.html;366.571111799195;366.5922856232069;374.47386422076994;382.71296527604295;385.2622228432552;15489.612759681;16526.669466610674;17357.7396339055;17367.957922524656;17383.043932583718;15769;16221.91889040556;16985.405510807093;17006.06466644337;17050.82949170508;5047.318611987381;5185.585153187755;5230.163173892159;5238.423773384925;5238.580913526908;3671.926561468769;3874.6319099685484;5123.180291153416;5768.740406681688;6075.635461872302;6100.206973093492;6110.938890611082;6115.327313995457;6132.233470816148;6136.877262454748;5866.853328666774;6119.602225855305;6120.296165940929;6122.1735065766;6124.81625551232;3331.3004269103517;4952.851414457572;5251.002309561171;5255.159174532057;5341.759620817073;19654.213831446727;20048.69926951097;20231.190752369886;20634.27780027692;20659.070341834653;1931.4785008047888;2044.080163926239;2107.504736386948;2132.680097985299;2487.9004839806385;13206.471741130343;13232.73534529304;13246.139000964906;13293.933530332286;13407.932960335233;1710.7006273902018;1726.6891959447294;1749.3352526040023;1751.693453645607;1773.1931970953185;63733;63737.36262637407;63882.680586597235;64576.031359529174;65303;9482.810343793086;9636.807263854684;9861.802763944681;9942.30403871722;10052.74868128288;13335;13369.398377073056;13434.798478022838;13462.798058029137;13493.122947008413;7718.343940765098;7718.807029824299;7791.493552919155;7890.566018869017;7911.841763164704;7563.432742544332;7571.167171611119;7595.278448547379;7695.769126926201;7705.113911900204;6086.847828804315;6157.630542167395;6201.875962480725;6210.347913469111;6300.653464059521;1555;1686.2917574618689;1757.5606098475382;1767.1782621081002;1770.5131088950384;834.0950069175013;879.3448880584006;884.9911500885038;970.6020531582159;1008.2639673038723;15200.46798362052;16411.753823692656;17200.1399930003;17363.392281270095;17541.210645520983;867.9956600216958;876.5573385440439;895.3240303570892;927.4157280912982;956.9043095690375;9677.129058384779;10005.899941000496;10074.395536267848;10115.356670083987;10177.745556361002;407347;420929;422037;424139;428152;78980;82162;82355.17644823594;83037.16962830415;83885;887.1483375959023;916.083916083916;931.3945935142211;932.0306880843847;941.8163458125599;15180.24098795078;15525.689486210438;15601;15781;15946.521604351889;35022.54842161126;35254.29491410106;35391.82304088463;35945.64054359423;36648.45027324645;49676;50660.49339506705;50915.49084509256;51157.744211280195;51416;6968.860622787617;7083.707472812614;7209.3872020876715;7237.963810180915;7299.963500182465;9010.50442225684;11664.94167529157;12001.699957501132;12023.879761202275;12075.396230188631;9855.11303982645;10047.598096076075;10509.789804204025;10516.369017858953;10633;8759.649614015369;10123.088921996761;10430.322029068086;10502.36985780825;10693.358398496115;10220.02909723575;10417.37495750227;10550.366977981346;10695.57217711314;11141.665750027512;6933.861322773415;7151.42788576923;7168.139823221247;7194.2086370498155;7356.816079597838;9915.950420247853;9958.800823983334;10174.694759157237;10189;10722 08:09:03 INFO - PROCESS | 19615 | |3;mootools.html;1259.9937000314997;1263.6714454241894;1319.4128612767322;1347.4408120629937;1711.4352263752962;6195.31851496335;7179;7452.478326517133;7470.925290747097;7626.542407455557;7357.889631655529;7533.547987120763;7536;7539.39684825214;7542.736004239852;2073.9274125405577;2104.7263855698816;2107.230860735836;2108.736407949006;2109.7784732603086;2127.7872212778752;2144.3245377706053;2159.74083110027;2166.664167054101;2167.2522979572022;2078.376487053878;2093.173196587347;2096.758872729633;2105.3052492677457;2158.9892050539725;2021.6361055010093;2034.3591768592846;2034.3693455028915;2038.9796102038938;2040.5918816236738;1983.4347211044783;2053.3224036068063;2070.151237992431;2083.156321689718;2094.3821572636107;7224.7110115595315;7341.119065712149;7409.703611855573;7411.592362420119;7433.739819106359;4301.354796780477;4390.165868484977;4390.846320378803;4394.494633117169;4399.670024748157;2601.7658410743124;2684.2349930269975;2732.6857411397746;2732.79504037198;2733.494303553849;897.1701176411858;914.4833169259414;916.8258030974093;922.4557511068434;926.5645146781002;9851.852222216672;9938.652147174886;9949.75125621858;9956;9963.551640176138;3862.092408284051;3913.5499417566775;3996.8601098961976;4000.3399439092805;4024.476818013698;7935.047794264609;8006.959965200137;8008.679652813822;8012.559309237931;8019.478733882279;4366.432363792686;4368.803403846835;4371.519132895379;4373.2128216921255;4376.365427013129;3513.9648603514147;3994.1013272014147;4123.670106391482;4135.731177473454;4191.664666826647;3206.070239630528;3221.2430078931434;3305.2067503799394;3329.0025984409162;3384.424647809878;937.5312343828086;953.5422996961497;962.5139304651246;962.9807403851884;969.4231932000448;808.401782680812;823.9835203295901;826.4917075998218;828.2730506893196;842.9114942930904;4993.400791904922;5019.698818070928;5060.342155519759;5060.84817455477;5280.234366016906;805.8509175802378;832.725200683785;832.9000519937525;870.0777176193255;883.8806761087171;3390.69483746464;3475.878344257939;3544.0431083607796;3563.6970857476886;3566.251087271702;8876.600552975131;8956.552172391355;9199.678011269707;9218.815623687622;9223.677171298972;9094.499802510793;9104.544772761335;9110.453372797654;9111.04334044929;9115.407498512575;7001.089858318488;7016.649167541603;7088.574685518885;7134.964325178445;7163.534370265916;4976.776045077908;5036.093503169464;5113.53978141969;5123.743812809419;5169.276301317744;8513.914860851311;8520.446170998865;8624.482531048157;8758.518281494578;8766.91233087661;8684.349973505014;8791.472511649321;8794.736157915273;8807.779805504913;8810.647574096964;5302.469753024667;5860.501857342174;5985.610935289193;6082.60463069899;6088.36072212429;6948.826279342855;7091.1490621125795;7157.926311053384;7165.605891676008;7169.103862017248;6340.9365906340345;6500.934990650222;6562.704678289393;6583;6613.074169616161;6269.373062693694;6369.299377068612;6389.9680501597195;6469.7412103515335;6479.1901012373455;5726.141078838207;5763.769449221984;5792.710364481674;5800.158976948234;5805.303363596396;4329.437173167594;4372.2348588996165;4420.557944205554;4468.79890404939;4517.322401639781;4125.339945608688;4338.696291259582;4371.956280437155;4404.779761012001;4517.254652982221 08:09:03 INFO - PROCESS | 19615 | |4;prototype.html;278.30423940149615;286.13729605240206;291.6587592516755;292.9428761391528;294.2144474253742;4533.3426653135275;4696.990147118368;4731.911660318129;4793.952060479394;4801.303810947411;4525.886852828681;4638.165130276558;4689.296605509176;4691.390119284497;4727.692699974499;3679.932819482347;3682.8895133146048;3685.949504391249;3714.4028067930712;3732.3841566141555;3506.6142724300307;3544.8050357230472;3721.423179407196;3728.7762734236035;3740.8590379934108;3654.4518322251743;3672.192117734094;3676.1361080146144;3679.1354031802507;3690.6309369063147;3654.397024490956;3657.1771351445846;3659.835307411173;3667.963320366789;3673.1918977824835;3258.1365938026443;3260.347930413927;3270.3132342208164;3270.509423586457;3281.9835900820344;4223.704340696153;4489.932651010237;4494.752788596626;4507.211238033331;4533.954660453386;4115.58844115589;4120.320147175712;4156.542780294165;4158.688098392633;4166.7708276044805;4939.876503087452;4940.90118197634;4942.481039490874;4955.677880937727;4970.676906001098;3266.526353678727;3462.480627905809;3463.307338532278;3464.8094354810473;3494.2836718472654;5183.792648294064;5191.584673226171;5215.478452154792;5225.242339860737;5240.082985477527;3563.982180089135;3569.6787289144104;3572.517710109102;3574.2136729919375;3576.4277715565386;3591.5095235477506;3597.226596281812;3604.585472670624;3618.5476815398074;3619.3485172668647;3934.881953541398;4137.77242251673;4147.854825081107;4152.854650087233;4201.802486291393;4652.464966528893;4937.876553086202;4944.5166450064835;4950.381202349707;4962.9007419851405;1187.3884949251142;1223.9082068844873;1227.9386030698608;1234.2471092633486;1236.7279198576298;756.1870988687183;774;791.5132193700832;792.3740245206222;793.8332950080433;3894.123822139996;3937.1928754605237;3939.4484772131923;3988.12261302513;3989.942665193726;4582.770861456914;4598.471175814824;4612.262038073892;4612.331211974245;4613;3922.8626998054933;4005.796811753524;4016.096378314856;4024.7383920045104;4034.838606455709;2306.261996161212;2362.11420717231;2402.0183348737014;2405.7233418157134;2428.405040765024;4265.829366825355;4267.850625228164;4292.785360731951;4294.484661840536;4322.481302243751;4518.299663552155;4560.244305942236;4566.862994110182;4621.2837010263465;4630.745309008037;4324.308110702335;4330.220560299176;4333.458317710286;4439.600435960714;4842.201036828884;5096.872578185575;5097.7960881565805;5101.489851015017;5109.437961824122;5119.616028797929;5058.645894787477;5060.696358218519;5065.56942659878;5073.086844368049;5077.162268225701;4662.277347011219;4811.687240329367;4831.73425461603;4888.364512613338;4901.313816065683;3521.436570148764;3530.2057037166433;3533.5406397168204;3537.6816086551303;3542.75200735946;4881.389826271716;5115.5396014358885;5125.154349532286;5125.282460455464;5132.46109158533;4781.330613714013;4958.537231516783;4963.156263435297;4969.776360063878;4982.950170498249;4935.938773163787;4961;4961.355023846877;4967.329410529533;4972.453030166751;4590.540945905516;4856;4859.757012149449;4861.104169374018;4866.659333846596;4948.505149485023;4993.625478089085;4995.975020124876;5001.224320502156;5030.396352437585;4891.388576427947;4892.657513973988;4935.1998960799265;4943.505649435172;4953.405591329064 08:09:03 INFO - PROCESS | 19615 | |5;yui.html;610.7386555792069;625.4996003197442;661.2065521374349;680;727.9235680253574;12266.938665306672;13141.605751827448;13148;13158.473661053571;13162.276074815882;12993.61019169424;13150.079494435393;13176.407061682223;13298.534551290706;13307.733845323088;639.11163482759;786.2098590916121;796.1242633103569;796.9242921922408;797.9680812767511;778.8364443466879;801.418971245845;808.8382323535287;808.8584497712906;809.1988930958338;838.3670328901701;839.8530257204995;840.8108175660458;840.9663613455455;841.4194205997873;655.8589903170794;807.0436532708759;832.7543374704477;833.2958649940821;833.6623667414707;611.2481647573466;614.3672017821651;614.3886832601545;614.7387360371869;616.2604874151036;12225;12253.816192757116;12256.81614775779;12267.079969002361;12283.14018018739;12587.181833180814;13080.411381487856;13281.203127812361;13438.596842094752;13462.865371346357;11343.262687925258;11857.347845868473;11914;11951.641450756491;12089.818652720216;4125.1131006833675;4125.1749650070105;4166.291730405838;4229.133027729339;4230.51349094855;59562.40437595612;60309.396906030815;62301.6884915577;62440.68779656073;62494;9062.909370906207;9749.756256093655;10099.798004039878;10249.795004099877;10551.050405463546;17268.309267629153;17422.128893555273;17540.386086486913;17557.91221043912;17563.0340331283;6305.306416294204;6450.741970321228;6455;6545.181852268466;6549.705263263165;3483.5645544306963;3584;3609.296187243515;3638.6361363863402;3685.465607486953;2875.7699384049224;2983.298924752681;2987.14866263114;2989.8355590442297;2990.611220541359;462.3896456677154;467.3387157172631;468.49168652012946;469.9271612900006;471.530826827309;423.7521050185676;508.9338386009795;532.9973350133224;533.7224643185599;577.4831525784399;7954.681812727542;8131.837363252702;8181.304589109874;8238.958805205935;8256.587170641444;515.2297315513257;556.2129586634948;568.4145330309788;578.8205656246577;604.9334573196945;3722.739408241451;3896.2597106549665;3904.8438062477185;3966.286068507696;4049.4330793688905;85961;88271;88777;89426;89709;50882;58406.415935840945;71310;72638.63680681563;78965.60517197377;63051.05423418652;69193.3080669197;69860.65069674619;71950.64024679948;74903.62548187224;8273.089960104264;8292.460990035628;8437;8627.611757471055;8657.307415406878;38716.61283387243;42551.29794808173;42657.36013959855;43974.560254398326;44078.33882491829;51687.48312516827;59343.703281483315;60244.397556023876;60326.396736032075;60532;11271.943640282074;11370.658880233606;11448.313101213955;11519.76960460803;11996.040316774812;12594;12942;13162.47350105985;13242.668933276746;13516.189028658311;10819.080378167944;11494.425278736197;11684.989903522752;12115.091368147248;12224.388780561114;11763.47064382122;12003.699907502383;12499.875001249871;12506.312152831317;12773.61679149627;12034.338111403957;12403.62789116328;12453.1905426147;12665.556705515262;12935.223886566837;10052.145567626834;10292.536835842255;10480.423576703355;10604.787904241717;10860.456977151269;9721.513924303616;10618.469076546296;10619.1504679627;10678;10702.839457408301 08:09:03 INFO - PROCESS | 19615 | __end_tp_report 08:09:03 INFO - PROCESS | 19615 | __start_cc_report 08:09:03 INFO - PROCESS | 19615 | _x_x_mozilla_cycle_collect,993 08:09:03 INFO - PROCESS | 19615 | __end_cc_report 08:09:03 INFO - PROCESS | 19615 | __startTimestamp1465312143081__endTimestamp 08:09:03 INFO - PROCESS | 19615 | 08:09:03 INFO - PROCESS | 19615 | ------- Summary: start ------- 08:09:03 INFO - PROCESS | 19615 | Number of tests: 6 08:09:03 INFO - PROCESS | 19615 | 08:09:03 INFO - PROCESS | 19615 | [#0] dojo.html Cycles:165 Average:4656.20 Median:4776.38 stddev:492.15 (10.3%) stddev-sans-first:467.61 08:09:03 INFO - PROCESS | 19615 | Values: 2642.0 2654.8 2655.2 2667.7 2685.9 4498.3 4607.5 4667.0 4716.2 4757.3 4665.3 4697.4 4711.6 4747.3 4750.6 4114.2 4280.8 4339.5 4384.5 4462.4 4373.3 4406.1 4418.0 4426.2 4458.4 4182.4 4227.0 4309.3 4344.1 4370.8 4241.8 4304.8 4329.1 4364.4 4417.4 4328.6 4347.5 4351.5 4387.8 4394.1 4420.3 4548.3 4561.3 4625.4 4631.7 5010.8 5075.3 5083.8 5088.3 5089.1 5080.9 5098.7 5099.7 5108.1 5115.5 4524.2 4638.4 4704.8 4706.1 4770.4 5209.9 5215.0 5226.7 5228.1 5237.6 4956.3 4960.1 4997.0 5002.2 5012.6 4799.2 4966.1 5025.1 5045.7 5092.0 5016.6 5021.3 5027.2 5034.5 5070.6 5008.8 5014.0 5015.7 5021.6 5048.5 4102.1 4150.9 4155.2 4195.0 4299.1 3693.2 3724.8 3760.9 3761.7 3799.5 4396.9 4398.8 4399.0 4433.6 4446.7 4831.7 5097.1 5105.8 5109.9 5112.1 4247.1 4291.3 4337.7 4344.2 4360.4 4502.7 4507.7 4510.4 4524.0 4551.5 4534.1 4708.5 4751.0 4753.0 4780.6 5001.3 5043.1 5055.4 5079.7 5094.9 4776.4 4786.8 4788.2 4789.7 4836.8 4731.4 4921.6 5013.1 5017.3 5063.3 5022.7 5038.1 5067.4 5067.4 5134.9 4865.5 4894.3 4896.3 4915.7 4930.9 4689.8 4855.5 4916.4 4918.7 4979.9 4823.5 4843.7 4880.7 4894.2 4897.7 4998.5 5000.8 5001.8 5029.6 5046.3 4610.5 4795.3 4869.2 4878.6 4909.3 08:09:03 INFO - PROCESS | 19615 | 08:09:03 INFO - PROCESS | 19615 | [#1] ext.html Cycles:180 Average:49981.57 Median:37531.53 stddev:95882.09 (255.5%) stddev-sans-first:96079.22 08:09:03 INFO - PROCESS | 19615 | Values: 544.0 571.9 590.5 591.8 594.8 26170.2 26705.2 38944.0 39693.6 39822.2 39799.2 40304.4 40383.0 40496.6 40520.0 7668.2 7871.3 7872.0 7874.4 7877.5 7674.9 7842.1 7842.6 7843.3 7845.6 7833.3 7894.2 7898.4 7899.2 7903.3 7834.9 7905.3 7905.9 7908.1 7909.0 7676.2 7759.1 7795.3 7828.0 7828.8 37206.4 37568.2 37584.4 37736.6 37750.6 2999.4 3102.9 3132.5 3232.3 3747.9 28106.9 28374.5 28422.6 28918.6 28937.4 3543.3 3560.1 3564.8 3564.9 3577.1 256089.0 257495.0 257758.0 258209.0 258375.7 50853.2 51477.0 51722.5 51763.2 51802.0 32620.3 34062.3 34103.3 35816.8 35947.5 15381.8 15480.0 15553.5 15561.9 15563.2 11888.2 11971.7 11987.3 11996.3 12000.6 18273.7 18277.4 18312.4 18404.3 18489.7 6982.0 7039.8 7136.4 7165.4 7201.2 817.3 894.5 933.4 958.0 997.0 26849.9 27069.5 27627.0 27646.0 28177.6 846.6 949.8 958.9 973.7 1004.8 13972.2 14548.9 14588.9 14841.3 14893.3 393979.0 394106.0 396948.0 397357.0 397954.0 45741.3 46908.5 46933.5 46952.0 47098.8 1109.7 1202.8 1230.9 1234.1 1236.9 27780.2 29585.1 29811.7 30262.0 30304.8 42485.8 43351.6 43397.8 43417.6 43461.6 351114.0 354225.0 356461.0 361728.0 588572.0 38170.2 39197.8 39855.6 41839.2 42410.4 42562.0 42951.6 43046.8 43363.8 43931.6 35363.8 36865.4 37299.0 37494.8 37808.6 36028.3 36652.3 36657.6 36978.8 36979.1 35924.5 36081.0 36820.3 37022.4 39470.4 30329.4 33267.3 33514.3 33545.8 33606.8 25045.7 25175.4 25420.2 25605.2 25783.2 08:09:03 INFO - PROCESS | 19615 | 08:09:03 INFO - PROCESS | 19615 | [#2] jquery.html Cycles:180 Average:25281.22 Median:421483.00 stddev:69238.03 (16.4%) stddev-sans-first:69406.99 08:09:03 INFO - PROCESS | 19615 | Values: 366.6 366.6 374.5 382.7 385.3 15489.6 16526.7 17357.7 17368.0 17383.0 15769.0 16221.9 16985.4 17006.1 17050.8 5047.3 5185.6 5230.2 5238.4 5238.6 3671.9 3874.6 5123.2 5768.7 6075.6 6100.2 6110.9 6115.3 6132.2 6136.9 5866.9 6119.6 6120.3 6122.2 6124.8 3331.3 4952.9 5251.0 5255.2 5341.8 19654.2 20048.7 20231.2 20634.3 20659.1 1931.5 2044.1 2107.5 2132.7 2487.9 13206.5 13232.7 13246.1 13293.9 13407.9 1710.7 1726.7 1749.3 1751.7 1773.2 63733.0 63737.4 63882.7 64576.0 65303.0 9482.8 9636.8 9861.8 9942.3 10052.7 13335.0 13369.4 13434.8 13462.8 13493.1 7718.3 7718.8 7791.5 7890.6 7911.8 7563.4 7571.2 7595.3 7695.8 7705.1 6086.8 6157.6 6201.9 6210.3 6300.7 1555.0 1686.3 1757.6 1767.2 1770.5 834.1 879.3 885.0 970.6 1008.3 15200.5 16411.8 17200.1 17363.4 17541.2 868.0 876.6 895.3 927.4 956.9 9677.1 10005.9 10074.4 10115.4 10177.7 407347.0 420929.0 422037.0 424139.0 428152.0 78980.0 82162.0 82355.2 83037.2 83885.0 887.1 916.1 931.4 932.0 941.8 15180.2 15525.7 15601.0 15781.0 15946.5 35022.5 35254.3 35391.8 35945.6 36648.5 49676.0 50660.5 50915.5 51157.7 51416.0 6968.9 7083.7 7209.4 7238.0 7300.0 9010.5 11664.9 12001.7 12023.9 12075.4 9855.1 10047.6 10509.8 10516.4 10633.0 8759.6 10123.1 10430.3 10502.4 10693.4 10220.0 10417.4 10550.4 10695.6 11141.7 6933.9 7151.4 7168.1 7194.2 7356.8 9916.0 9958.8 10174.7 10189.0 10722.0 08:09:03 INFO - PROCESS | 19615 | 08:09:03 INFO - PROCESS | 19615 | [#3] mootools.html Cycles:180 Average:4856.91 Median:5832.90 stddev:2708.06 (46.4%) stddev-sans-first:2702.17 08:09:03 INFO - PROCESS | 19615 | Values: 1260.0 1263.7 1319.4 1347.4 1711.4 6195.3 7179.0 7452.5 7470.9 7626.5 7357.9 7533.5 7536.0 7539.4 7542.7 2073.9 2104.7 2107.2 2108.7 2109.8 2127.8 2144.3 2159.7 2166.7 2167.3 2078.4 2093.2 2096.8 2105.3 2159.0 2021.6 2034.4 2034.4 2039.0 2040.6 1983.4 2053.3 2070.2 2083.2 2094.4 7224.7 7341.1 7409.7 7411.6 7433.7 4301.4 4390.2 4390.8 4394.5 4399.7 2601.8 2684.2 2732.7 2732.8 2733.5 897.2 914.5 916.8 922.5 926.6 9851.9 9938.7 9949.8 9956.0 9963.6 3862.1 3913.5 3996.9 4000.3 4024.5 7935.0 8007.0 8008.7 8012.6 8019.5 4366.4 4368.8 4371.5 4373.2 4376.4 3514.0 3994.1 4123.7 4135.7 4191.7 3206.1 3221.2 3305.2 3329.0 3384.4 937.5 953.5 962.5 963.0 969.4 808.4 824.0 826.5 828.3 842.9 4993.4 5019.7 5060.3 5060.8 5280.2 805.9 832.7 832.9 870.1 883.9 3390.7 3475.9 3544.0 3563.7 3566.3 8876.6 8956.6 9199.7 9218.8 9223.7 9094.5 9104.5 9110.5 9111.0 9115.4 7001.1 7016.6 7088.6 7135.0 7163.5 4976.8 5036.1 5113.5 5123.7 5169.3 8513.9 8520.4 8624.5 8758.5 8766.9 8684.3 8791.5 8794.7 8807.8 8810.6 5302.5 5860.5 5985.6 6082.6 6088.4 6948.8 7091.1 7157.9 7165.6 7169.1 6340.9 6500.9 6562.7 6583.0 6613.1 6269.4 6369.3 6390.0 6469.7 6479.2 5726.1 5763.8 5792.7 5800.2 5805.3 4329.4 4372.2 4420.6 4468.8 4517.3 4125.3 4338.7 4372.0 4404.8 4517.3 08:09:03 INFO - PROCESS | 19615 | 08:09:03 INFO - PROCESS | 19615 | [#4] prototype.html Cycles:180 Average:4012.52 Median:4492.34 stddev:1184.86 (26.4%) stddev-sans-first:1154.56 08:09:03 INFO - PROCESS | 19615 | Values: 278.3 286.1 291.7 292.9 294.2 4533.3 4697.0 4731.9 4794.0 4801.3 4525.9 4638.2 4689.3 4691.4 4727.7 3679.9 3682.9 3685.9 3714.4 3732.4 3506.6 3544.8 3721.4 3728.8 3740.9 3654.5 3672.2 3676.1 3679.1 3690.6 3654.4 3657.2 3659.8 3668.0 3673.2 3258.1 3260.3 3270.3 3270.5 3282.0 4223.7 4489.9 4494.8 4507.2 4534.0 4115.6 4120.3 4156.5 4158.7 4166.8 4939.9 4940.9 4942.5 4955.7 4970.7 3266.5 3462.5 3463.3 3464.8 3494.3 5183.8 5191.6 5215.5 5225.2 5240.1 3564.0 3569.7 3572.5 3574.2 3576.4 3591.5 3597.2 3604.6 3618.5 3619.3 3934.9 4137.8 4147.9 4152.9 4201.8 4652.5 4937.9 4944.5 4950.4 4962.9 1187.4 1223.9 1227.9 1234.2 1236.7 756.2 774.0 791.5 792.4 793.8 3894.1 3937.2 3939.4 3988.1 3989.9 4582.8 4598.5 4612.3 4612.3 4613.0 3922.9 4005.8 4016.1 4024.7 4034.8 2306.3 2362.1 2402.0 2405.7 2428.4 4265.8 4267.9 4292.8 4294.5 4322.5 4518.3 4560.2 4566.9 4621.3 4630.7 4324.3 4330.2 4333.5 4439.6 4842.2 5096.9 5097.8 5101.5 5109.4 5119.6 5058.6 5060.7 5065.6 5073.1 5077.2 4662.3 4811.7 4831.7 4888.4 4901.3 3521.4 3530.2 3533.5 3537.7 3542.8 4881.4 5115.5 5125.2 5125.3 5132.5 4781.3 4958.5 4963.2 4969.8 4983.0 4935.9 4961.0 4961.4 4967.3 4972.5 4590.5 4856.0 4859.8 4861.1 4866.7 4948.5 4993.6 4996.0 5001.2 5030.4 4891.4 4892.7 4935.2 4943.5 4953.4 08:09:03 INFO - PROCESS | 19615 | 08:09:03 INFO - PROCESS | 19615 | [#5] yui.html Cycles:180 Average:16763.68 Median:43315.96 stddev:22749.35 (52.5%) stddev-sans-first:22780.84 08:09:03 INFO - PROCESS | 19615 | Values: 610.7 625.5 661.2 680.0 727.9 12266.9 13141.6 13148.0 13158.5 13162.3 12993.6 13150.1 13176.4 13298.5 13307.7 639.1 786.2 796.1 796.9 798.0 778.8 801.4 808.8 808.9 809.2 838.4 839.9 840.8 841.0 841.4 655.9 807.0 832.8 833.3 833.7 611.2 614.4 614.4 614.7 616.3 12225.0 12253.8 12256.8 12267.1 12283.1 12587.2 13080.4 13281.2 13438.6 13462.9 11343.3 11857.3 11914.0 11951.6 12089.8 4125.1 4125.2 4166.3 4229.1 4230.5 59562.4 60309.4 62301.7 62440.7 62494.0 9062.9 9749.8 10099.8 10249.8 10551.1 17268.3 17422.1 17540.4 17557.9 17563.0 6305.3 6450.7 6455.0 6545.2 6549.7 3483.6 3584.0 3609.3 3638.6 3685.5 2875.8 2983.3 2987.1 2989.8 2990.6 462.4 467.3 468.5 469.9 471.5 423.8 508.9 533.0 533.7 577.5 7954.7 8131.8 8181.3 8239.0 8256.6 515.2 556.2 568.4 578.8 604.9 3722.7 3896.3 3904.8 3966.3 4049.4 85961.0 88271.0 88777.0 89426.0 89709.0 50882.0 58406.4 71310.0 72638.6 78965.6 63051.1 69193.3 69860.7 71950.6 74903.6 8273.1 8292.5 8437.0 8627.6 8657.3 38716.6 42551.3 42657.4 43974.6 44078.3 51687.5 59343.7 60244.4 60326.4 60532.0 11271.9 11370.7 11448.3 11519.8 11996.0 12594.0 12942.0 13162.5 13242.7 13516.2 10819.1 11494.4 11685.0 12115.1 12224.4 11763.5 12003.7 12499.9 12506.3 12773.6 12034.3 12403.6 12453.2 12665.6 12935.2 10052.1 10292.5 10480.4 10604.8 10860.5 9721.5 10618.5 10619.2 10678.0 10702.8 08:09:03 INFO - PROCESS | 19615 | -------- Summary: end -------- 08:09:03 INFO - PROCESS | 19615 | 08:09:03 INFO - TEST-INFO | 19615: exit 0 08:09:03 INFO - TEST-OK | dromaeo_css | took 1118809ms 08:09:03 INFO - TEST-START | kraken 08:09:03 INFO - operating with platform_type : linux_ 08:09:03 INFO - Initialising browser for kraken test... 08:09:03 INFO - TEST-INFO | started process 19798 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyRKt7a/profile http://localhost:51681/getInfo.html) 08:09:08 INFO - PROCESS | 19798 | __metrics Screen width/height:1600/1200 08:09:08 INFO - PROCESS | 19798 | colorDepth:24 08:09:08 INFO - PROCESS | 19798 | Browser inner width/height: 1024/697 08:09:08 INFO - PROCESS | 19798 | __metrics 08:09:08 ERROR - PROCESS | 19798 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:09:09 INFO - PROCESS | 19798 | console.error: PushService: 08:09:09 INFO - PROCESS | 19798 | stateChangeProcessEnqueue: Error transitioning state 08:09:09 INFO - PROCESS | 19798 | UnknownError 08:09:09 INFO - TEST-INFO | 19798: exit 0 08:09:09 INFO - Browser initialized. 08:09:09 INFO - Running cycle 1/1 for kraken test... 08:09:09 INFO - TEST-INFO | started process 19878 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyRKt7a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:09:21 INFO - PROCESS | 19878 | RSS: Main: 157253632 08:09:21 INFO - PROCESS | 19878 | 08:09:24 INFO - PROCESS | 19878 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51681/tests/kraken/driver.html?testName=audio-beat-detection) 08:09:25 INFO - PROCESS | 19878 | RSS: Main: 214675456 08:09:25 INFO - PROCESS | 19878 | 08:09:28 INFO - PROCESS | 19878 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51681/tests/kraken/driver.html?testName=audio-dft) 08:09:28 INFO - PROCESS | 19878 | RSS: Main: 202285056 08:09:28 INFO - PROCESS | 19878 | 08:09:32 INFO - PROCESS | 19878 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51681/tests/kraken/driver.html?testName=audio-fft) 08:09:32 INFO - PROCESS | 19878 | RSS: Main: 270323712 08:09:32 INFO - PROCESS | 19878 | 08:09:35 INFO - PROCESS | 19878 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51681/tests/kraken/driver.html?testName=audio-oscillator) 08:09:35 INFO - PROCESS | 19878 | RSS: Main: 180146176 08:09:35 INFO - PROCESS | 19878 | 08:09:37 INFO - PROCESS | 19878 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51681/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:09:38 INFO - PROCESS | 19878 | RSS: Main: 183738368 08:09:38 INFO - PROCESS | 19878 | 08:09:41 INFO - PROCESS | 19878 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51681/tests/kraken/driver.html?testName=imaging-darkroom) 08:09:42 INFO - PROCESS | 19878 | RSS: Main: 220450816 08:09:42 INFO - PROCESS | 19878 | 08:09:45 INFO - PROCESS | 19878 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51681/tests/kraken/driver.html?testName=imaging-desaturate) 08:09:46 INFO - PROCESS | 19878 | RSS: Main: 216236032 08:09:46 INFO - PROCESS | 19878 | 08:09:49 INFO - PROCESS | 19878 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51681/tests/kraken/driver.html?testName=json-parse-financial) 08:09:49 INFO - PROCESS | 19878 | RSS: Main: 219299840 08:09:49 INFO - PROCESS | 19878 | 08:09:51 INFO - PROCESS | 19878 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51681/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:09:52 INFO - PROCESS | 19878 | RSS: Main: 209633280 08:09:52 INFO - PROCESS | 19878 | 08:09:54 INFO - PROCESS | 19878 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51681/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:09:54 INFO - PROCESS | 19878 | RSS: Main: 209494016 08:09:54 INFO - PROCESS | 19878 | 08:09:56 INFO - PROCESS | 19878 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51681/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:09:57 INFO - PROCESS | 19878 | RSS: Main: 217223168 08:09:57 INFO - PROCESS | 19878 | 08:10:00 INFO - PROCESS | 19878 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51681/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:10:00 INFO - PROCESS | 19878 | RSS: Main: 223211520 08:10:00 INFO - PROCESS | 19878 | 08:10:03 INFO - PROCESS | 19878 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51681/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:10:04 INFO - PROCESS | 19878 | RSS: Main: 223186944 08:10:04 INFO - PROCESS | 19878 | 08:10:06 INFO - PROCESS | 19878 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51681/tests/kraken/driver.html?testName=ai-astar) 08:10:06 INFO - PROCESS | 19878 | RSS: Main: 265482240 08:10:06 INFO - PROCESS | 19878 | 08:10:06 INFO - PROCESS | 19878 | __start_tp_report 08:10:06 INFO - PROCESS | 19878 | _x_x_mozilla_page_load 08:10:06 INFO - PROCESS | 19878 | _x_x_mozilla_page_load_details 08:10:06 INFO - PROCESS | 19878 | |i|pagename|runs| 08:10:06 INFO - PROCESS | 19878 | |0;ai-astar;114;110;116;114;115;115;115;115;114;115 08:10:06 INFO - PROCESS | 19878 | |1;audio-beat-detection;157;134;133;189;131;131;159;130;193;132 08:10:06 INFO - PROCESS | 19878 | |2;audio-dft;160;159;162;160;161;161;162;160;163;160 08:10:06 INFO - PROCESS | 19878 | |3;audio-fft;76;75;77;107;76;77;74;76;101;74 08:10:06 INFO - PROCESS | 19878 | |4;audio-oscillator;86;87;107;86;87;86;120;86;86;121 08:10:06 INFO - PROCESS | 19878 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;116;116 08:10:06 INFO - PROCESS | 19878 | |6;imaging-darkroom;146;146;146;145;146;146;146;146;146;145 08:10:06 INFO - PROCESS | 19878 | |7;imaging-desaturate;95;98;95;95;95;95;95;95;95;95 08:10:06 INFO - PROCESS | 19878 | |8;json-parse-financial;70;61;63;62;64;74;63;63;64;62 08:10:06 INFO - PROCESS | 19878 | |9;json-stringify-tinderbox;65;65;65;65;66;64;64;65;65;64 08:10:06 INFO - PROCESS | 19878 | |10;stanford-crypto-aes;84;84;90;86;86;85;85;86;116;84 08:10:06 INFO - PROCESS | 19878 | |11;stanford-crypto-ccm;134;130;130;129;128;130;134;129;144;134 08:10:06 INFO - PROCESS | 19878 | |12;stanford-crypto-pbkdf2;181;170;177;168;167;170;172;170;169;167 08:10:06 INFO - PROCESS | 19878 | |13;stanford-crypto-sha256-iterative;70;67;67;70;68;70;67;66;67;66 08:10:06 INFO - PROCESS | 19878 | __end_tp_report 08:10:06 INFO - PROCESS | 19878 | __start_cc_report 08:10:06 INFO - PROCESS | 19878 | _x_x_mozilla_cycle_collect,1898 08:10:06 INFO - PROCESS | 19878 | __end_cc_report 08:10:06 INFO - PROCESS | 19878 | __startTimestamp1465312206395__endTimestamp 08:10:06 INFO - PROCESS | 19878 | 08:10:06 INFO - PROCESS | 19878 | ------- Summary: start ------- 08:10:06 INFO - PROCESS | 19878 | Number of tests: 14 08:10:06 INFO - PROCESS | 19878 | 08:10:06 INFO - PROCESS | 19878 | [#0] ai-astar Cycles:10 Average:114.30 Median:115.00 stddev:1.64 (1.4%) stddev-sans-first:1.73 08:10:06 INFO - PROCESS | 19878 | Values: 114.0 110.0 116.0 114.0 115.0 115.0 115.0 115.0 114.0 115.0 08:10:06 INFO - PROCESS | 19878 | 08:10:06 INFO - PROCESS | 19878 | [#1] audio-beat-detection Cycles:10 Average:148.90 Median:145.50 stddev:24.67 (17.0%) stddev-sans-first:26.00 08:10:06 INFO - PROCESS | 19878 | Values: 157.0 134.0 133.0 189.0 131.0 131.0 159.0 130.0 193.0 132.0 08:10:06 INFO - PROCESS | 19878 | 08:10:06 INFO - PROCESS | 19878 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.23 (0.8%) stddev-sans-first:1.27 08:10:06 INFO - PROCESS | 19878 | Values: 160.0 159.0 162.0 160.0 161.0 161.0 162.0 160.0 163.0 160.0 08:10:06 INFO - PROCESS | 19878 | 08:10:06 INFO - PROCESS | 19878 | [#3] audio-fft Cycles:10 Average:81.30 Median:76.00 stddev:12.09 (15.9%) stddev-sans-first:12.67 08:10:06 INFO - PROCESS | 19878 | Values: 76.0 75.0 77.0 107.0 76.0 77.0 74.0 76.0 101.0 74.0 08:10:06 INFO - PROCESS | 19878 | 08:10:06 INFO - PROCESS | 19878 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:86.00 stddev:14.82 (17.2%) stddev-sans-first:15.34 08:10:06 INFO - PROCESS | 19878 | Values: 86.0 87.0 107.0 86.0 87.0 86.0 120.0 86.0 86.0 121.0 08:10:06 INFO - PROCESS | 19878 | 08:10:06 INFO - PROCESS | 19878 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 08:10:06 INFO - PROCESS | 19878 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 08:10:06 INFO - PROCESS | 19878 | 08:10:06 INFO - PROCESS | 19878 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 08:10:06 INFO - PROCESS | 19878 | Values: 146.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 146.0 145.0 08:10:06 INFO - PROCESS | 19878 | 08:10:06 INFO - PROCESS | 19878 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.95 (1.0%) stddev-sans-first:1.00 08:10:06 INFO - PROCESS | 19878 | Values: 95.0 98.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 08:10:06 INFO - PROCESS | 19878 | 08:10:06 INFO - PROCESS | 19878 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:63.50 stddev:4.12 (6.5%) stddev-sans-first:3.87 08:10:06 INFO - PROCESS | 19878 | Values: 70.0 61.0 63.0 62.0 64.0 74.0 63.0 63.0 64.0 62.0 08:10:06 INFO - PROCESS | 19878 | 08:10:06 INFO - PROCESS | 19878 | [#9] json-stringify-tinderbox Cycles:10 Average:64.80 Median:65.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 08:10:06 INFO - PROCESS | 19878 | Values: 65.0 65.0 65.0 65.0 66.0 64.0 64.0 65.0 65.0 64.0 08:10:06 INFO - PROCESS | 19878 | 08:10:06 INFO - PROCESS | 19878 | [#10] stanford-crypto-aes Cycles:10 Average:88.60 Median:85.50 stddev:9.79 (11.4%) stddev-sans-first:10.24 08:10:06 INFO - PROCESS | 19878 | Values: 84.0 84.0 90.0 86.0 86.0 85.0 85.0 86.0 116.0 84.0 08:10:06 INFO - PROCESS | 19878 | 08:10:06 INFO - PROCESS | 19878 | [#11] stanford-crypto-ccm Cycles:10 Average:132.20 Median:132.00 stddev:4.73 (3.6%) stddev-sans-first:4.97 08:10:06 INFO - PROCESS | 19878 | Values: 134.0 130.0 130.0 129.0 128.0 130.0 134.0 129.0 144.0 134.0 08:10:06 INFO - PROCESS | 19878 | 08:10:06 INFO - PROCESS | 19878 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.10 Median:170.00 stddev:4.53 (2.7%) stddev-sans-first:3.08 08:10:06 INFO - PROCESS | 19878 | Values: 181.0 170.0 177.0 168.0 167.0 170.0 172.0 170.0 169.0 167.0 08:10:06 INFO - PROCESS | 19878 | 08:10:06 INFO - PROCESS | 19878 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:67.50 stddev:1.62 (2.4%) stddev-sans-first:1.51 08:10:06 INFO - PROCESS | 19878 | Values: 70.0 67.0 67.0 70.0 68.0 70.0 67.0 66.0 67.0 66.0 08:10:06 INFO - PROCESS | 19878 | -------- Summary: end -------- 08:10:06 INFO - PROCESS | 19878 | 08:10:07 INFO - TEST-INFO | 19878: exit 0 08:10:07 INFO - TEST-OK | kraken | took 63597ms 08:10:07 INFO - SUITE-END | took 1182s 08:10:07 INFO - Completed test suite (00:19:42) 08:10:07 INFO - javascript benchmark 08:10:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2641.960370594441, 2654.8407095574266, 2655.203438968309, 2667.7065522792477, 2685.9328516787077, 4498.302763071721, 4607.470140933785, 4667.0, 4716.1746694328485, 4757.286407038941, 4665.2768820832825, 4697.365855609501, 4711.575958163765, 4747.264174053019, 4750.5724484796365, 4114.23886580982, 4280.828766849322, 4339.522652508222, 4384.539623339535, 4462.419885414892, 4373.278409062501, 4406.052698669785, 4417.955820441803, 4426.203283408985, 4458.353538736881, 4182.35173548099, 4226.957730422687, 4309.310510318365, 4344.109457561224, 4370.781460926973, 4241.766702831343, 4304.78476076195, 4329.112531930978, 4364.410804541378, 4417.425734654475, 4328.588784065509, 4347.478302603676, 4351.477822661301, 4387.802548885308, 4394.12117576486, 4420.3369494575745, 4548.295014272793, 4561.315802629599, 4625.398698169251, 4631.722096674211, 5010.849674509771, 5075.264086707443, 5083.822066227664, 5088.313077734532, 5089.05852417304, 5080.872978175538, 5098.719570423626, 5099.745012749385, 5108.131617624976, 5115.462876397962, 4524.23088075028, 4638.350630911675, 4704.764761761967, 4706.129366067288, 4770.356001939694, 5209.921851172236, 5214.973925130426, 5226.660267082628, 5228.137357336073, 5237.6071794615555, 4956.281339205867, 4960.131976904027, 4997.0, 5002.224655178453, 5012.649114562018, 4799.232122860326, 4966.056449274626, 5025.120603894304, 5045.722485263273, 5091.981603679279, 5016.573591244712, 5021.322121513622, 5027.2207807789855, 5034.521720436553, 5070.61970352225, 5008.774605142778, 5014.04733100717, 5015.724135172528, 5021.648484606116, 5048.5203905628905, 4102.077032667626, 4150.896227594334, 4155.168966206771, 4195.035141917315, 4299.07569872479, 3693.2244228711734, 3724.832382542792, 3760.9247815043545, 3761.736678432538, 3799.5440547133967, 4396.934045989313, 4398.824047038147, 4399.032212913154, 4433.600975912119, 4446.73319600825, 4831.710097394168, 5097.082525145435, 5105.795768169231, 5109.923351149736, 5112.130937740593, 4247.086876321606, 4291.270484017725, 4337.7397356158735, 4344.152890186447, 4360.41134446852, 4502.662300327423, 4507.684462087623, 4510.436195475566, 4524.049949510574, 4551.544845515488, 4534.115847409724, 4708.505606911293, 4751.049790041936, 4753.049390121921, 4780.569748722632, 5001.299818025406, 5043.092243396224, 5055.368078990126, 5079.6698214615935, 5094.923576146287, 4776.379070720784, 4786.8085276588545, 4788.233882578771, 4789.736564488987, 4836.758162091811, 4731.432228132646, 4921.557059864629, 5013.147764880052, 5017.347744793154, 5063.265826455217, 5022.748862556785, 5038.1435156022835, 5067.41724701664, 5067.442581316126, 5134.9486505135965, 4865.464798872051, 4894.314795928502, 4896.338994235733, 4915.705057696549, 4930.926036109389, 4689.812407503799, 4855.5387238212315, 4916.434610019894, 4918.704877707349, 4979.875503112451, 4823.541763532458, 4843.685160464559, 4880.682755620874, 4894.192458244493, 4897.681650692693, 4998.450170481171, 5000.824971125993, 5001.799928002984, 5029.572486338702, 5046.29351890743, 4610.492845786894, 4795.328653988375, 4869.196582563979, 4878.56092951636, 4909.337239472626], "value": 4625.129854404334, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.0058527526228, 571.9084946408575, 590.5275779376495, 591.8194950540084, 594.8096609085093, 26170.214893553177, 26705.198844034687, 38944.0, 39693.60306396935, 39822.20355592886, 39799.204015919735, 40304.395434068436, 40383.0, 40496.59503404965, 40520.0, 7668.194839541851, 7871.252231038042, 7872.0, 7874.3700503959835, 7877.527348359088, 7674.884876726854, 7842.137364889858, 7842.568658723768, 7843.254890785366, 7845.568493732842, 7833.255840695153, 7894.2105789421175, 7898.368130549571, 7899.170587088359, 7903.3282171015535, 7834.882476762825, 7905.288524032807, 7905.881411778828, 7908.051033875971, 7908.9604551977445, 7676.193999630014, 7759.146493885668, 7795.259450352264, 7827.96086019572, 7828.843423131562, 37206.44190337147, 37568.24863502714, 37584.43623345652, 37736.622633773586, 37750.62249377499, 2999.355138645202, 3102.860371283298, 3132.4674805283157, 3232.2888964428025, 3747.925041499182, 28106.859465702542, 28374.52990265645, 28422.573661395094, 28918.566221506695, 28937.42125157485, 3543.273628906094, 3560.092176494977, 3564.7682900611376, 3564.946525802115, 3577.105723569108, 256089.0, 257495.0, 257758.0, 258209.0, 258375.70812146008, 50853.23720144201, 51477.0, 51722.48277517252, 51763.223551646755, 51802.0, 32620.347593048005, 34062.318753624786, 34103.31793364169, 35816.820915895776, 35947.4607880882, 15381.769273460906, 15480.0, 15553.533393998197, 15561.922190389201, 15563.22183890801, 11888.167828252112, 11971.700707482209, 11987.34069626179, 11996.280223186637, 12000.57997970067, 18273.725894111598, 18277.3602923897, 18312.359067432575, 18404.26382944667, 18489.7226541602, 6981.997740971329, 7039.753608623673, 7136.393406560397, 7165.390941769903, 7201.243869393743, 817.3052905030795, 894.5169608411516, 933.4166146158651, 957.951043607249, 996.9950150249202, 26849.86575067112, 27069.458610827674, 27627.0, 27646.0, 28177.57733633997, 846.6486408140676, 949.7910459698855, 958.8657587937695, 973.7176218896441, 1004.7689031522791, 13972.231527266098, 14548.85451145475, 14588.927055364655, 14841.257937103102, 14893.32980015902, 393979.0, 394106.0, 396948.0, 397357.0, 397954.0, 45741.313880291826, 46908.53091469042, 46933.5306646936, 46952.0, 47098.76450617793, 1109.6948339206783, 1202.7594481103815, 1230.870758570355, 1234.0867757859119, 1236.8948639365756, 27780.166595001374, 29585.112446626637, 29811.70188298089, 30262.0, 30304.84847575748, 42485.787571063185, 43351.566484336014, 43397.78301108474, 43417.56582434135, 43461.565384345755, 351114.0, 354225.0, 356461.0, 361728.0, 588572.0, 38170.236595267386, 39197.804010979766, 39855.601443985186, 41839.1632167361, 42410.363844542975, 42562.0, 42951.57048429476, 43046.784766077224, 43363.78318108389, 43931.560684392745, 35363.823180883934, 36865.44701829529, 37299.0, 37494.812525937195, 37808.62191378051, 36028.27943441169, 36652.26695466129, 36657.63342366542, 36978.815105924295, 36979.07552311214, 35924.46113308355, 36081.0, 36820.26359472742, 37022.44466333061, 39470.40794388029, 30329.393412132074, 33267.33465330728, 33514.329713406085, 33545.83227083849, 33606.831965840014, 25045.74954250434, 25175.37061573475, 25420.237392878244, 25605.231843044738, 25783.226503204933], "value": 17757.441734182506, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.571111799195, 366.5922856232069, 374.47386422076994, 382.71296527604295, 385.2622228432552, 15489.612759681, 16526.669466610674, 17357.7396339055, 17367.957922524656, 17383.043932583718, 15769.0, 16221.91889040556, 16985.405510807093, 17006.06466644337, 17050.82949170508, 5047.318611987381, 5185.585153187755, 5230.163173892159, 5238.423773384925, 5238.580913526908, 3671.926561468769, 3874.6319099685484, 5123.180291153416, 5768.740406681688, 6075.635461872302, 6100.206973093492, 6110.938890611082, 6115.327313995457, 6132.233470816148, 6136.877262454748, 5866.853328666774, 6119.602225855305, 6120.296165940929, 6122.1735065766, 6124.81625551232, 3331.3004269103517, 4952.851414457572, 5251.002309561171, 5255.159174532057, 5341.759620817073, 19654.213831446727, 20048.69926951097, 20231.190752369886, 20634.27780027692, 20659.070341834653, 1931.4785008047888, 2044.080163926239, 2107.504736386948, 2132.680097985299, 2487.9004839806385, 13206.471741130343, 13232.73534529304, 13246.139000964906, 13293.933530332286, 13407.932960335233, 1710.7006273902018, 1726.6891959447294, 1749.3352526040023, 1751.693453645607, 1773.1931970953185, 63733.0, 63737.36262637407, 63882.680586597235, 64576.031359529174, 65303.0, 9482.810343793086, 9636.807263854684, 9861.802763944681, 9942.30403871722, 10052.74868128288, 13335.0, 13369.398377073056, 13434.798478022838, 13462.798058029137, 13493.122947008413, 7718.343940765098, 7718.807029824299, 7791.493552919155, 7890.566018869017, 7911.841763164704, 7563.432742544332, 7571.167171611119, 7595.278448547379, 7695.769126926201, 7705.113911900204, 6086.847828804315, 6157.630542167395, 6201.875962480725, 6210.347913469111, 6300.653464059521, 1555.0, 1686.2917574618689, 1757.5606098475382, 1767.1782621081002, 1770.5131088950384, 834.0950069175013, 879.3448880584006, 884.9911500885038, 970.6020531582159, 1008.2639673038723, 15200.46798362052, 16411.753823692656, 17200.1399930003, 17363.392281270095, 17541.210645520983, 867.9956600216958, 876.5573385440439, 895.3240303570892, 927.4157280912982, 956.9043095690375, 9677.129058384779, 10005.899941000496, 10074.395536267848, 10115.356670083987, 10177.745556361002, 407347.0, 420929.0, 422037.0, 424139.0, 428152.0, 78980.0, 82162.0, 82355.17644823594, 83037.16962830415, 83885.0, 887.1483375959023, 916.083916083916, 931.3945935142211, 932.0306880843847, 941.8163458125599, 15180.24098795078, 15525.689486210438, 15601.0, 15781.0, 15946.521604351889, 35022.54842161126, 35254.29491410106, 35391.82304088463, 35945.64054359423, 36648.45027324645, 49676.0, 50660.49339506705, 50915.49084509256, 51157.744211280195, 51416.0, 6968.860622787617, 7083.707472812614, 7209.3872020876715, 7237.963810180915, 7299.963500182465, 9010.50442225684, 11664.94167529157, 12001.699957501132, 12023.879761202275, 12075.396230188631, 9855.11303982645, 10047.598096076075, 10509.789804204025, 10516.369017858953, 10633.0, 8759.649614015369, 10123.088921996761, 10430.322029068086, 10502.36985780825, 10693.358398496115, 10220.02909723575, 10417.37495750227, 10550.366977981346, 10695.57217711314, 11141.665750027512, 6933.861322773415, 7151.42788576923, 7168.139823221247, 7194.2086370498155, 7356.816079597838, 9915.950420247853, 9958.800823983334, 10174.694759157237, 10189.0, 10722.0], "value": 8425.449142977612, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1259.9937000314997, 1263.6714454241894, 1319.4128612767322, 1347.4408120629937, 1711.4352263752962, 6195.31851496335, 7179.0, 7452.478326517133, 7470.925290747097, 7626.542407455557, 7357.889631655529, 7533.547987120763, 7536.0, 7539.39684825214, 7542.736004239852, 2073.9274125405577, 2104.7263855698816, 2107.230860735836, 2108.736407949006, 2109.7784732603086, 2127.7872212778752, 2144.3245377706053, 2159.74083110027, 2166.664167054101, 2167.2522979572022, 2078.376487053878, 2093.173196587347, 2096.758872729633, 2105.3052492677457, 2158.9892050539725, 2021.6361055010093, 2034.3591768592846, 2034.3693455028915, 2038.9796102038938, 2040.5918816236738, 1983.4347211044783, 2053.3224036068063, 2070.151237992431, 2083.156321689718, 2094.3821572636107, 7224.7110115595315, 7341.119065712149, 7409.703611855573, 7411.592362420119, 7433.739819106359, 4301.354796780477, 4390.165868484977, 4390.846320378803, 4394.494633117169, 4399.670024748157, 2601.7658410743124, 2684.2349930269975, 2732.6857411397746, 2732.79504037198, 2733.494303553849, 897.1701176411858, 914.4833169259414, 916.8258030974093, 922.4557511068434, 926.5645146781002, 9851.852222216672, 9938.652147174886, 9949.75125621858, 9956.0, 9963.551640176138, 3862.092408284051, 3913.5499417566775, 3996.8601098961976, 4000.3399439092805, 4024.476818013698, 7935.047794264609, 8006.959965200137, 8008.679652813822, 8012.559309237931, 8019.478733882279, 4366.432363792686, 4368.803403846835, 4371.519132895379, 4373.2128216921255, 4376.365427013129, 3513.9648603514147, 3994.1013272014147, 4123.670106391482, 4135.731177473454, 4191.664666826647, 3206.070239630528, 3221.2430078931434, 3305.2067503799394, 3329.0025984409162, 3384.424647809878, 937.5312343828086, 953.5422996961497, 962.5139304651246, 962.9807403851884, 969.4231932000448, 808.401782680812, 823.9835203295901, 826.4917075998218, 828.2730506893196, 842.9114942930904, 4993.400791904922, 5019.698818070928, 5060.342155519759, 5060.84817455477, 5280.234366016906, 805.8509175802378, 832.725200683785, 832.9000519937525, 870.0777176193255, 883.8806761087171, 3390.69483746464, 3475.878344257939, 3544.0431083607796, 3563.6970857476886, 3566.251087271702, 8876.600552975131, 8956.552172391355, 9199.678011269707, 9218.815623687622, 9223.677171298972, 9094.499802510793, 9104.544772761335, 9110.453372797654, 9111.04334044929, 9115.407498512575, 7001.089858318488, 7016.649167541603, 7088.574685518885, 7134.964325178445, 7163.534370265916, 4976.776045077908, 5036.093503169464, 5113.53978141969, 5123.743812809419, 5169.276301317744, 8513.914860851311, 8520.446170998865, 8624.482531048157, 8758.518281494578, 8766.91233087661, 8684.349973505014, 8791.472511649321, 8794.736157915273, 8807.779805504913, 8810.647574096964, 5302.469753024667, 5860.501857342174, 5985.610935289193, 6082.60463069899, 6088.36072212429, 6948.826279342855, 7091.1490621125795, 7157.926311053384, 7165.605891676008, 7169.103862017248, 6340.9365906340345, 6500.934990650222, 6562.704678289393, 6583.0, 6613.074169616161, 6269.373062693694, 6369.299377068612, 6389.9680501597195, 6469.7412103515335, 6479.1901012373455, 5726.141078838207, 5763.769449221984, 5792.710364481674, 5800.158976948234, 5805.303363596396, 4329.437173167594, 4372.2348588996165, 4420.557944205554, 4468.79890404939, 4517.322401639781, 4125.339945608688, 4338.696291259582, 4371.956280437155, 4404.779761012001, 4517.254652982221], "value": 3917.160686892433, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [278.30423940149615, 286.13729605240206, 291.6587592516755, 292.9428761391528, 294.2144474253742, 4533.3426653135275, 4696.990147118368, 4731.911660318129, 4793.952060479394, 4801.303810947411, 4525.886852828681, 4638.165130276558, 4689.296605509176, 4691.390119284497, 4727.692699974499, 3679.932819482347, 3682.8895133146048, 3685.949504391249, 3714.4028067930712, 3732.3841566141555, 3506.6142724300307, 3544.8050357230472, 3721.423179407196, 3728.7762734236035, 3740.8590379934108, 3654.4518322251743, 3672.192117734094, 3676.1361080146144, 3679.1354031802507, 3690.6309369063147, 3654.397024490956, 3657.1771351445846, 3659.835307411173, 3667.963320366789, 3673.1918977824835, 3258.1365938026443, 3260.347930413927, 3270.3132342208164, 3270.509423586457, 3281.9835900820344, 4223.704340696153, 4489.932651010237, 4494.752788596626, 4507.211238033331, 4533.954660453386, 4115.58844115589, 4120.320147175712, 4156.542780294165, 4158.688098392633, 4166.7708276044805, 4939.876503087452, 4940.90118197634, 4942.481039490874, 4955.677880937727, 4970.676906001098, 3266.526353678727, 3462.480627905809, 3463.307338532278, 3464.8094354810473, 3494.2836718472654, 5183.792648294064, 5191.584673226171, 5215.478452154792, 5225.242339860737, 5240.082985477527, 3563.982180089135, 3569.6787289144104, 3572.517710109102, 3574.2136729919375, 3576.4277715565386, 3591.5095235477506, 3597.226596281812, 3604.585472670624, 3618.5476815398074, 3619.3485172668647, 3934.881953541398, 4137.77242251673, 4147.854825081107, 4152.854650087233, 4201.802486291393, 4652.464966528893, 4937.876553086202, 4944.5166450064835, 4950.381202349707, 4962.9007419851405, 1187.3884949251142, 1223.9082068844873, 1227.9386030698608, 1234.2471092633486, 1236.7279198576298, 756.1870988687183, 774.0, 791.5132193700832, 792.3740245206222, 793.8332950080433, 3894.123822139996, 3937.1928754605237, 3939.4484772131923, 3988.12261302513, 3989.942665193726, 4582.770861456914, 4598.471175814824, 4612.262038073892, 4612.331211974245, 4613.0, 3922.8626998054933, 4005.796811753524, 4016.096378314856, 4024.7383920045104, 4034.838606455709, 2306.261996161212, 2362.11420717231, 2402.0183348737014, 2405.7233418157134, 2428.405040765024, 4265.829366825355, 4267.850625228164, 4292.785360731951, 4294.484661840536, 4322.481302243751, 4518.299663552155, 4560.244305942236, 4566.862994110182, 4621.2837010263465, 4630.745309008037, 4324.308110702335, 4330.220560299176, 4333.458317710286, 4439.600435960714, 4842.201036828884, 5096.872578185575, 5097.7960881565805, 5101.489851015017, 5109.437961824122, 5119.616028797929, 5058.645894787477, 5060.696358218519, 5065.56942659878, 5073.086844368049, 5077.162268225701, 4662.277347011219, 4811.687240329367, 4831.73425461603, 4888.364512613338, 4901.313816065683, 3521.436570148764, 3530.2057037166433, 3533.5406397168204, 3537.6816086551303, 3542.75200735946, 4881.389826271716, 5115.5396014358885, 5125.154349532286, 5125.282460455464, 5132.46109158533, 4781.330613714013, 4958.537231516783, 4963.156263435297, 4969.776360063878, 4982.950170498249, 4935.938773163787, 4961.0, 4961.355023846877, 4967.329410529533, 4972.453030166751, 4590.540945905516, 4856.0, 4859.757012149449, 4861.104169374018, 4866.659333846596, 4948.505149485023, 4993.625478089085, 4995.975020124876, 5001.224320502156, 5030.396352437585, 4891.388576427947, 4892.657513973988, 4935.1998960799265, 4943.505649435172, 4953.405591329064], "value": 3633.906106975988, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [610.7386555792069, 625.4996003197442, 661.2065521374349, 680.0, 727.9235680253574, 12266.938665306672, 13141.605751827448, 13148.0, 13158.473661053571, 13162.276074815882, 12993.61019169424, 13150.079494435393, 13176.407061682223, 13298.534551290706, 13307.733845323088, 639.11163482759, 786.2098590916121, 796.1242633103569, 796.9242921922408, 797.9680812767511, 778.8364443466879, 801.418971245845, 808.8382323535287, 808.8584497712906, 809.1988930958338, 838.3670328901701, 839.8530257204995, 840.8108175660458, 840.9663613455455, 841.4194205997873, 655.8589903170794, 807.0436532708759, 832.7543374704477, 833.2958649940821, 833.6623667414707, 611.2481647573466, 614.3672017821651, 614.3886832601545, 614.7387360371869, 616.2604874151036, 12225.0, 12253.816192757116, 12256.81614775779, 12267.079969002361, 12283.14018018739, 12587.181833180814, 13080.411381487856, 13281.203127812361, 13438.596842094752, 13462.865371346357, 11343.262687925258, 11857.347845868473, 11914.0, 11951.641450756491, 12089.818652720216, 4125.1131006833675, 4125.1749650070105, 4166.291730405838, 4229.133027729339, 4230.51349094855, 59562.40437595612, 60309.396906030815, 62301.6884915577, 62440.68779656073, 62494.0, 9062.909370906207, 9749.756256093655, 10099.798004039878, 10249.795004099877, 10551.050405463546, 17268.309267629153, 17422.128893555273, 17540.386086486913, 17557.91221043912, 17563.0340331283, 6305.306416294204, 6450.741970321228, 6455.0, 6545.181852268466, 6549.705263263165, 3483.5645544306963, 3584.0, 3609.296187243515, 3638.6361363863402, 3685.465607486953, 2875.7699384049224, 2983.298924752681, 2987.14866263114, 2989.8355590442297, 2990.611220541359, 462.3896456677154, 467.3387157172631, 468.49168652012946, 469.9271612900006, 471.530826827309, 423.7521050185676, 508.9338386009795, 532.9973350133224, 533.7224643185599, 577.4831525784399, 7954.681812727542, 8131.837363252702, 8181.304589109874, 8238.958805205935, 8256.587170641444, 515.2297315513257, 556.2129586634948, 568.4145330309788, 578.8205656246577, 604.9334573196945, 3722.739408241451, 3896.2597106549665, 3904.8438062477185, 3966.286068507696, 4049.4330793688905, 85961.0, 88271.0, 88777.0, 89426.0, 89709.0, 50882.0, 58406.415935840945, 71310.0, 72638.63680681563, 78965.60517197377, 63051.05423418652, 69193.3080669197, 69860.65069674619, 71950.64024679948, 74903.62548187224, 8273.089960104264, 8292.460990035628, 8437.0, 8627.611757471055, 8657.307415406878, 38716.61283387243, 42551.29794808173, 42657.36013959855, 43974.560254398326, 44078.33882491829, 51687.48312516827, 59343.703281483315, 60244.397556023876, 60326.396736032075, 60532.0, 11271.943640282074, 11370.658880233606, 11448.313101213955, 11519.76960460803, 11996.040316774812, 12594.0, 12942.0, 13162.47350105985, 13242.668933276746, 13516.189028658311, 10819.080378167944, 11494.425278736197, 11684.989903522752, 12115.091368147248, 12224.388780561114, 11763.47064382122, 12003.699907502383, 12499.875001249871, 12506.312152831317, 12773.61679149627, 12034.338111403957, 12403.62789116328, 12453.1905426147, 12665.556705515262, 12935.223886566837, 10052.145567626834, 10292.536835842255, 10480.423576703355, 10604.787904241717, 10860.456977151269, 9721.513924303616, 10618.469076546296, 10619.1504679627, 10678.0, 10702.839457408301], "value": 6501.47268260809, "unit": "score"}], "name": "dromaeo_css", "value": 6325.354765237425, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 110.0, 116.0, 114.0, 115.0, 115.0, 115.0, 115.0, 114.0, 115.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 134.0, 133.0, 189.0, 131.0, 131.0, 159.0, 130.0, 193.0, 132.0], "value": 148.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 162.0, 160.0, 161.0, 161.0, 162.0, 160.0, 163.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 77.0, 107.0, 76.0, 77.0, 74.0, 76.0, 101.0, 74.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 107.0, 86.0, 87.0, 86.0, 120.0, 86.0, 86.0, 121.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 98.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 61.0, 63.0, 62.0, 64.0, 74.0, 63.0, 63.0, 64.0, 62.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 66.0, 64.0, 64.0, 65.0, 65.0, 64.0], "value": 64.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 90.0, 86.0, 86.0, 85.0, 85.0, 86.0, 116.0, 84.0], "value": 88.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 130.0, 130.0, 129.0, 128.0, 130.0, 134.0, 129.0, 144.0, 134.0], "value": 132.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 170.0, 177.0, 168.0, 167.0, 170.0, 172.0, 170.0, 169.0, 167.0], "value": 171.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 67.0, 70.0, 68.0, 70.0, 67.0, 66.0, 67.0, 66.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1547.4999999999998, "alertThreshold": 2.0}]} 08:10:07 INFO - Return code: 0 08:10:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:10:07 ERROR - # TBPL SUCCESS # 08:10:07 INFO - Running post-action listener: _resource_record_post_action 08:10:07 INFO - ##### 08:10:07 INFO - ##### Finished run-tests step (success) 08:10:07 INFO - ##### 08:10:07 INFO - Running post-run listener: _resource_record_post_run 08:10:07 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 0; Write bytes: 345530368; Read time: 0; Write time: 277684 08:10:07 INFO - TinderboxPrint: CPU usage
13.1% 08:10:07 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 08:10:07 INFO - TinderboxPrint: I/O write bytes / time
345,530,368 / 277,684 08:10:07 INFO - TinderboxPrint: CPU idle
8,320.9 (86.9%) 08:10:07 INFO - TinderboxPrint: CPU user
1,215.7 (12.7%) 08:10:07 INFO - TinderboxPrint: Swap in / out
0 / 0 08:10:07 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 13684736; Read time: 0; Write time: 1904 08:10:07 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 285986816; Read time: 0; Write time: 221760 08:10:08 INFO - Running post-run listener: _upload_blobber_files 08:10:08 INFO - Blob upload gear active. 08:10:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:10:08 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 08:10:08 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:10:08 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:10:08 INFO - (blobuploader) - INFO - Open directory for files ... 08:10:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:10:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:10:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:10:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:10:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:10:12 INFO - (blobuploader) - INFO - Done attempting. 08:10:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:10:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:10:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:10:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:10:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:10:13 INFO - (blobuploader) - INFO - Done attempting. 08:10:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:10:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:10:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:10:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:10:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:10:14 INFO - (blobuploader) - INFO - Done attempting. 08:10:14 INFO - (blobuploader) - INFO - Iteration through files over. 08:10:14 INFO - Return code: 0 08:10:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:10:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:10:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc5490f7d46d5500e68eae39354cfede1a57f53d77b719c8e206d2acf23ffd01ad0f21ed3f66beb956ecbdc8b7da72a266fa172fdab0c94f0e3f9e51adc401bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/078ec75bad4be8815cc1345745cc24476ba1d241aa2324851740950145b7e2a4c36794c0a8dfd936d56c0a67a6f9dd2fdd509b739aa90042195d4fbe5f12d103", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/06a1c8b60209e493412f47197985c2d04bf98887048f9f9b09577d676e46ece07df50a4d57fdaf8e60eece4f21157ee7f5382297085c5626caf666ab161d6302"} 08:10:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:10:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:10:14 INFO - Contents: 08:10:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc5490f7d46d5500e68eae39354cfede1a57f53d77b719c8e206d2acf23ffd01ad0f21ed3f66beb956ecbdc8b7da72a266fa172fdab0c94f0e3f9e51adc401bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/078ec75bad4be8815cc1345745cc24476ba1d241aa2324851740950145b7e2a4c36794c0a8dfd936d56c0a67a6f9dd2fdd509b739aa90042195d4fbe5f12d103", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/06a1c8b60209e493412f47197985c2d04bf98887048f9f9b09577d676e46ece07df50a4d57fdaf8e60eece4f21157ee7f5382297085c5626caf666ab161d6302"} 08:10:14 INFO - Running post-run listener: copy_logs_to_upload_dir 08:10:14 INFO - Copying logs to upload dir... 08:10:14 INFO - mkdir: /builds/slave/test/build/upload/logs 08:10:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.780646 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-06-07 08:10:13.924194) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 08:10:13.928049) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc5490f7d46d5500e68eae39354cfede1a57f53d77b719c8e206d2acf23ffd01ad0f21ed3f66beb956ecbdc8b7da72a266fa172fdab0c94f0e3f9e51adc401bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/078ec75bad4be8815cc1345745cc24476ba1d241aa2324851740950145b7e2a4c36794c0a8dfd936d56c0a67a6f9dd2fdd509b739aa90042195d4fbe5f12d103", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/06a1c8b60209e493412f47197985c2d04bf98887048f9f9b09577d676e46ece07df50a4d57fdaf8e60eece4f21157ee7f5382297085c5626caf666ab161d6302"} build_url:https://queue.taskcluster.net/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011421 build_url: 'https://queue.taskcluster.net/v1/task/EEkP82iESuCf0IIG9Pk_uQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc5490f7d46d5500e68eae39354cfede1a57f53d77b719c8e206d2acf23ffd01ad0f21ed3f66beb956ecbdc8b7da72a266fa172fdab0c94f0e3f9e51adc401bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/078ec75bad4be8815cc1345745cc24476ba1d241aa2324851740950145b7e2a4c36794c0a8dfd936d56c0a67a6f9dd2fdd509b739aa90042195d4fbe5f12d103", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/06a1c8b60209e493412f47197985c2d04bf98887048f9f9b09577d676e46ece07df50a4d57fdaf8e60eece4f21157ee7f5382297085c5626caf666ab161d6302"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 08:10:13.953849) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 08:10:13.954240) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1465310964.619133-1441818049 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004457 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 08:10:14.038611) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 08:10:14.039188) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 08:10:14.039721) ========= ========= Total master_lag: 0.16 =========