builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-066 starttime: 1450226435.88 results: success (0) buildid: 20151215152858 builduid: bb4a550898f2439f882153cad50d1eda revision: 8f3a1bd74aee41509e0c3cb3e6f1ba1954e5fb0a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:35.883108) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:35.883522) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:35.883880) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] 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=004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006461 basedir: '/builds/slave/test' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:35.925788) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:35.926062) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:35.958683) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:35.958937) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', '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=004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004728 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:36.017390) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:36.017674) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:36.018164) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:36.018451) ========= 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=004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-15 16:40:36-- 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 2015-12-15 16:40:36 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112494 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:36.143834) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:36.144108) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] 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=004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021346 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:36.192842) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:36.193151) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8f3a1bd74aee41509e0c3cb3e6f1ba1954e5fb0a --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 8f3a1bd74aee41509e0c3cb3e6f1ba1954e5fb0a --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=004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-15 16:40:37,096 truncating revision to first 12 chars 2015-12-15 16:40:37,096 Setting DEBUG logging. 2015-12-15 16:40:37,097 attempt 1/10 2015-12-15 16:40:37,097 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8f3a1bd74aee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-15 16:40:37,343 unpacking tar archive at: mozilla-central-8f3a1bd74aee/testing/mozharness/ program finished with exit code 0 elapsedTime=0.545419 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:36.752004) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:36.752267) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:36.843060) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:36.843360) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-15 16:40:36.843777) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2015-12-15 16:40:36.844069) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --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', '--system-bits', '64', '--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=004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:40:37 INFO - MultiFileLogger online at 20151215 16:40:37 in /builds/slave/test 16:40:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 16:40:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:40:37 INFO - {'append_to_log': False, 16:40:37 INFO - 'base_work_dir': '/builds/slave/test', 16:40:37 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:40:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:40:37 INFO - 'branch': 'Firefox-Non-PGO', 16:40:37 INFO - 'buildbot_json_path': 'buildprops.json', 16:40:37 INFO - 'config_files': ('talos/linux_config.py',), 16:40:37 INFO - 'default_actions': ('clobber', 16:40:37 INFO - 'read-buildbot-config', 16:40:37 INFO - 'download-and-extract', 16:40:37 INFO - 'populate-webroot', 16:40:37 INFO - 'create-virtualenv', 16:40:37 INFO - 'install', 16:40:37 INFO - 'run-tests'), 16:40:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:40:37 INFO - 'download_minidump_stackwalk': True, 16:40:37 INFO - 'download_symbols': 'ondemand', 16:40:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:40:37 INFO - 'tooltool.py': '/tools/tooltool.py', 16:40:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:40:37 INFO - '/tools/misc-python/virtualenv.py')}, 16:40:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:40:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:40:37 INFO - 'installer_path': 'installer.exe', 16:40:37 INFO - 'log_level': 'info', 16:40:37 INFO - 'log_name': 'talos', 16:40:37 INFO - 'log_to_console': True, 16:40:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:40:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:40:37 INFO - 'opt_config_files': (), 16:40:37 INFO - 'pip_index': False, 16:40:37 INFO - 'sps_profile': False, 16:40:37 INFO - 'sps_profile_interval': 0, 16:40:37 INFO - 'suite': 'dromaeojs', 16:40:37 INFO - 'system_bits': '64', 16:40:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:40:37 INFO - 'title': 'talos-linux64-ix-066', 16:40:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:40:37 INFO - 'use_talos_json': True, 16:40:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:40:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:40:37 INFO - 'work_dir': 'build'} 16:40:37 INFO - ##### 16:40:37 INFO - ##### Running clobber step. 16:40:37 INFO - ##### 16:40:37 INFO - Running pre-action listener: _resource_record_pre_action 16:40:37 INFO - Running main action method: clobber 16:40:37 INFO - rmtree: /builds/slave/test/build 16:40:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:40:38 INFO - Running post-action listener: _resource_record_post_action 16:40:38 INFO - ##### 16:40:38 INFO - ##### Running read-buildbot-config step. 16:40:38 INFO - ##### 16:40:38 INFO - Running pre-action listener: _resource_record_pre_action 16:40:38 INFO - Running main action method: read_buildbot_config 16:40:38 INFO - Using buildbot properties: 16:40:38 INFO - { 16:40:38 INFO - "properties": { 16:40:38 INFO - "buildnumber": 241, 16:40:38 INFO - "product": "firefox", 16:40:38 INFO - "script_repo_revision": "production", 16:40:38 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:40:38 INFO - "repository": "", 16:40:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 16:40:38 INFO - "buildid": "20151215152858", 16:40:38 INFO - "slavename": "talos-linux64-ix-066", 16:40:38 INFO - "pgo_build": "False", 16:40:38 INFO - "basedir": "/builds/slave/test", 16:40:38 INFO - "project": "", 16:40:38 INFO - "platform": "ubuntu64_hw", 16:40:38 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:40:38 INFO - "slavebuilddir": "test", 16:40:38 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 16:40:38 INFO - "branch": "mozilla-central", 16:40:38 INFO - "repo_path": "mozilla-central", 16:40:38 INFO - "stage_platform": "linux64", 16:40:38 INFO - "builduid": "bb4a550898f2439f882153cad50d1eda", 16:40:38 INFO - "revision": "8f3a1bd74aee41509e0c3cb3e6f1ba1954e5fb0a" 16:40:38 INFO - }, 16:40:38 INFO - "sourcestamp": { 16:40:38 INFO - "repository": "", 16:40:38 INFO - "hasPatch": false, 16:40:38 INFO - "project": "", 16:40:38 INFO - "branch": "mozilla-central-linux64-talos", 16:40:38 INFO - "changes": [ 16:40:38 INFO - { 16:40:38 INFO - "category": null, 16:40:38 INFO - "files": [ 16:40:38 INFO - { 16:40:38 INFO - "url": null, 16:40:38 INFO - "name": "https://queue.taskcluster.net/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2" 16:40:38 INFO - } 16:40:38 INFO - ], 16:40:38 INFO - "repository": "", 16:40:38 INFO - "rev": "8f3a1bd74aee41509e0c3cb3e6f1ba1954e5fb0a", 16:40:38 INFO - "who": "kwierso@gmail.com", 16:40:38 INFO - "when": 1450226393, 16:40:38 INFO - "number": 7364044, 16:40:38 INFO - "comments": "Bumping manifests a=b2g-bump", 16:40:38 INFO - "project": "", 16:40:38 INFO - "at": "Tue 15 Dec 2015 16:39:53", 16:40:38 INFO - "branch": "mozilla-central-linux64-talos", 16:40:38 INFO - "revlink": "", 16:40:38 INFO - "properties": [ 16:40:38 INFO - [ 16:40:38 INFO - "buildid", 16:40:38 INFO - "20151215152858", 16:40:38 INFO - "Change" 16:40:38 INFO - ], 16:40:38 INFO - [ 16:40:38 INFO - "builduid", 16:40:38 INFO - "bb4a550898f2439f882153cad50d1eda", 16:40:38 INFO - "Change" 16:40:38 INFO - ], 16:40:38 INFO - [ 16:40:38 INFO - "pgo_build", 16:40:38 INFO - "False", 16:40:38 INFO - "Change" 16:40:38 INFO - ] 16:40:38 INFO - ], 16:40:38 INFO - "revision": "8f3a1bd74aee41509e0c3cb3e6f1ba1954e5fb0a" 16:40:38 INFO - } 16:40:38 INFO - ], 16:40:38 INFO - "revision": "8f3a1bd74aee41509e0c3cb3e6f1ba1954e5fb0a" 16:40:38 INFO - } 16:40:38 INFO - } 16:40:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 16:40:38 INFO - Running post-action listener: _resource_record_post_action 16:40:38 INFO - ##### 16:40:38 INFO - ##### Running download-and-extract step. 16:40:38 INFO - ##### 16:40:38 INFO - Running pre-action listener: _resource_record_pre_action 16:40:38 INFO - Running main action method: download_and_extract 16:40:38 INFO - mkdir: /builds/slave/test/build/tests 16:40:38 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')]} 16:40:38 INFO - https://queue.taskcluster.net/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 16:40:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/test_packages.json 16:40:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/test_packages.json 16:40:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 16:40:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 16:40:39 INFO - Downloaded 1302 bytes. 16:40:39 INFO - Reading from file /builds/slave/test/build/test_packages.json 16:40:39 INFO - Using the following test package requirements: 16:40:39 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 16:40:39 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:40:39 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:40:39 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:40:39 INFO - u'jsshell-linux-x86_64.zip'], 16:40:39 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:40:39 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:40:39 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 16:40:39 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:40:39 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:40:39 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:40:39 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:40:39 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:40:39 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:40:39 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 16:40:39 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:40:39 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:40:39 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:40:39 INFO - https://queue.taskcluster.net/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:40:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:40:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:40:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:40:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:40:41 INFO - Downloaded 21863113 bytes. 16:40:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 16:40:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:40:41 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 16:40:42 INFO - Return code: 0 16:40:42 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:40:42 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')]} 16:40:42 INFO - https://queue.taskcluster.net/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:40:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:40:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:40:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:40:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:40:52 INFO - Downloaded 21863113 bytes. 16:40:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 16:40:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:40:52 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 16:40:53 INFO - Return code: 0 16:40:53 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')]} 16:40:53 INFO - https://queue.taskcluster.net/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:40:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 16:40:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 16:40:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 16:40:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:40:54 INFO - Downloaded 11132052 bytes. 16:40:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 16:40:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 16:40:54 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 16:40:54 INFO - Return code: 0 16:40:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:40:54 INFO - https://queue.taskcluster.net/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:40:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:40:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:40:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:40:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:40:55 INFO - Downloaded 57734218 bytes. 16:40:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:40:55 INFO - mkdir: /builds/slave/test/properties 16:40:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:40:55 INFO - Writing to file /builds/slave/test/properties/build_url 16:40:55 INFO - Contents: 16:40:55 INFO - build_url:https://queue.taskcluster.net/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:40:55 INFO - Running post-action listener: _resource_record_post_action 16:40:55 INFO - Running post-action listener: set_extra_try_arguments 16:40:55 INFO - ##### 16:40:55 INFO - ##### Running populate-webroot step. 16:40:55 INFO - ##### 16:40:55 INFO - Running pre-action listener: _resource_record_pre_action 16:40:55 INFO - Running main action method: populate_webroot 16:40:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:40:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:40:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:40:55 INFO - '--tppagecycles', 16:40:55 INFO - '7'], 16:40:55 INFO - 'tests': ['tsvgm']}}, 16:40:55 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:40:55 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:40:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:40:55 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:40:55 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:40:55 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:40:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:40:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:55 INFO - 'talos_options': ['--e10s'], 16:40:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:40:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:55 INFO - 'tests': ['damp', 'tps']}, 16:40:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:55 INFO - 'talos_options': ['--e10s'], 16:40:55 INFO - 'tests': ['damp', 'tps']}, 16:40:55 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:40:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:40:55 INFO - 'other': {'tests': ['a11yr', 16:40:55 INFO - 'ts_paint', 16:40:55 INFO - 'tpaint', 16:40:55 INFO - 'sessionrestore', 16:40:55 INFO - 'sessionrestore_no_auto_restore']}, 16:40:55 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:40:55 INFO - 'tests': ['a11yr', 16:40:55 INFO - 'ts_paint', 16:40:55 INFO - 'tpaint', 16:40:55 INFO - 'sessionrestore', 16:40:55 INFO - 'sessionrestore_no_auto_restore']}, 16:40:55 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:40:55 INFO - 'tests': ['a11yr', 16:40:55 INFO - 'ts_paint', 16:40:55 INFO - 'tpaint', 16:40:55 INFO - 'sessionrestore', 16:40:55 INFO - 'sessionrestore_no_auto_restore']}, 16:40:55 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:40:55 INFO - 'tests': ['a11yr', 16:40:55 INFO - 'ts_paint', 16:40:55 INFO - 'tpaint', 16:40:55 INFO - 'sessionrestore', 16:40:55 INFO - 'sessionrestore_no_auto_restore']}, 16:40:55 INFO - 'other_l64': {'tests': ['a11yr', 16:40:55 INFO - 'ts_paint', 16:40:55 INFO - 'tpaint', 16:40:55 INFO - 'sessionrestore', 16:40:55 INFO - 'sessionrestore_no_auto_restore']}, 16:40:55 INFO - 'other_nol64': {'tests': ['a11yr', 16:40:55 INFO - 'ts_paint', 16:40:55 INFO - 'tpaint', 16:40:55 INFO - 'sessionrestore', 16:40:55 INFO - 'sessionrestore_no_auto_restore']}, 16:40:55 INFO - 'svgr': {'tests': ['tsvgx', 16:40:55 INFO - 'tsvgr_opacity', 16:40:55 INFO - 'tart', 16:40:55 INFO - 'tscrollx', 16:40:55 INFO - 'cart']}, 16:40:55 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:40:55 INFO - 'tests': ['tsvgx', 16:40:55 INFO - 'tsvgr_opacity', 16:40:55 INFO - 'tart', 16:40:55 INFO - 'tscrollx', 16:40:55 INFO - 'cart']}, 16:40:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:55 INFO - 'tests': ['tp5o']}, 16:40:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:55 INFO - 'talos_options': ['--e10s'], 16:40:55 INFO - 'tests': ['tp5o']}, 16:40:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:55 INFO - 'talos_options': ['--xperf_path', 16:40:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:40:55 INFO - 'tests': ['tp5n']}, 16:40:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:55 INFO - 'talos_options': ['--e10s', 16:40:55 INFO - '--xperf_path', 16:40:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:40:55 INFO - 'tests': ['tp5n']}}, 16:40:55 INFO - 'talos.zip': {'path': '', 16:40:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:40:55 INFO - Running post-action listener: _resource_record_post_action 16:40:55 INFO - ##### 16:40:55 INFO - ##### Running create-virtualenv step. 16:40:55 INFO - ##### 16:40:55 INFO - Running pre-action listener: _resource_record_pre_action 16:40:55 INFO - Running main action method: create_virtualenv 16:40:55 INFO - Creating virtualenv /builds/slave/test/build/venv 16:40:55 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 16:40:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:40:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:40:55 INFO - Using real prefix '/usr' 16:40:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:40:56 INFO - Installing distribute.............................................................................................................................................................................................done. 16:40:58 INFO - Installing pip.................done. 16:40:58 INFO - Return code: 0 16:40:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:40:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:40:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:40:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f31235b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f2240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11810b0>, '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 0x1181fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1182440>, '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': '004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190', '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 16:40:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 16:40:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 16:40:58 INFO - Using env: {'DISPLAY': ':0', 16:40:58 INFO - 'HOME': '/home/cltbld', 16:40:58 INFO - 'LANG': 'en_US.UTF-8', 16:40:58 INFO - 'LANGUAGE': 'en_US:en', 16:40:58 INFO - 'LOGNAME': 'cltbld', 16:40:58 INFO - 'MAIL': '/var/mail/cltbld', 16:40:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:58 INFO - 'MOZ_NO_REMOTE': '1', 16:40:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:58 INFO - 'NO_EM_RESTART': '1', 16:40:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:58 INFO - 'PWD': '/builds/slave/test', 16:40:58 INFO - 'SHELL': '/bin/bash', 16:40:58 INFO - 'SHLVL': '1', 16:40:58 INFO - 'TERM': 'linux', 16:40:58 INFO - 'TMOUT': '86400', 16:40:58 INFO - 'USER': 'cltbld', 16:40:58 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190', 16:40:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:58 INFO - '_': '/tools/buildbot/bin/python'} 16:40:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:58 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 16:40:58 INFO - Cleaning up... 16:40:58 INFO - Return code: 0 16:40:58 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:40:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:40:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:40:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f31235b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f2240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11810b0>, '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 0x1181fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1182440>, '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': '004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190', '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 16:40:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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>=0.7.1'] in /builds/slave/test/build 16:40:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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>=0.7.1 16:40:58 INFO - Using env: {'DISPLAY': ':0', 16:40:58 INFO - 'HOME': '/home/cltbld', 16:40:58 INFO - 'LANG': 'en_US.UTF-8', 16:40:58 INFO - 'LANGUAGE': 'en_US:en', 16:40:58 INFO - 'LOGNAME': 'cltbld', 16:40:58 INFO - 'MAIL': '/var/mail/cltbld', 16:40:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:58 INFO - 'MOZ_NO_REMOTE': '1', 16:40:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:58 INFO - 'NO_EM_RESTART': '1', 16:40:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:58 INFO - 'PWD': '/builds/slave/test', 16:40:58 INFO - 'SHELL': '/bin/bash', 16:40:58 INFO - 'SHLVL': '1', 16:40:58 INFO - 'TERM': 'linux', 16:40:58 INFO - 'TMOUT': '86400', 16:40:58 INFO - 'USER': 'cltbld', 16:40:58 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190', 16:40:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:58 INFO - '_': '/tools/buildbot/bin/python'} 16:40:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:58 INFO - Downloading/unpacking psutil>=0.7.1 16:40:58 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 16:40:58 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 16:40:58 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 16:40:58 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 16:40:58 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 16:40:58 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 16:41:00 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 16:41:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 16:41:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:41:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:41:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:41:00 INFO - Installing collected packages: psutil 16:41:00 INFO - Running setup.py install for psutil 16:41:00 INFO - building 'psutil._psutil_linux' extension 16:41:00 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 16:41:00 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 16:41:00 INFO - building 'psutil._psutil_posix' extension 16:41:00 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 16:41:01 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 16:41:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:41:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:41:01 INFO - Successfully installed psutil 16:41:01 INFO - Cleaning up... 16:41:01 INFO - Return code: 0 16:41:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:41:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f31235b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f2240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11810b0>, '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 0x1181fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1182440>, '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': '004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190', '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 16:41:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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.0.0'] in /builds/slave/test/build 16:41:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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.0.0 16:41:01 INFO - Using env: {'DISPLAY': ':0', 16:41:01 INFO - 'HOME': '/home/cltbld', 16:41:01 INFO - 'LANG': 'en_US.UTF-8', 16:41:01 INFO - 'LANGUAGE': 'en_US:en', 16:41:01 INFO - 'LOGNAME': 'cltbld', 16:41:01 INFO - 'MAIL': '/var/mail/cltbld', 16:41:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:01 INFO - 'MOZ_NO_REMOTE': '1', 16:41:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:01 INFO - 'NO_EM_RESTART': '1', 16:41:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:01 INFO - 'PWD': '/builds/slave/test', 16:41:01 INFO - 'SHELL': '/bin/bash', 16:41:01 INFO - 'SHLVL': '1', 16:41:01 INFO - 'TERM': 'linux', 16:41:01 INFO - 'TMOUT': '86400', 16:41:01 INFO - 'USER': 'cltbld', 16:41:01 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190', 16:41:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:01 INFO - '_': '/tools/buildbot/bin/python'} 16:41:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:41:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:03 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:41:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 16:41:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:41:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:41:03 INFO - Installing collected packages: mozsystemmonitor 16:41:03 INFO - Running setup.py install for mozsystemmonitor 16:41:03 INFO - Successfully installed mozsystemmonitor 16:41:03 INFO - Cleaning up... 16:41:03 INFO - Return code: 0 16:41:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:41: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')]} 16:41:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41: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')]} 16:41:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f31235b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f2240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11810b0>, '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 0x1181fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1182440>, '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': '004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190', '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 16:41:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 16:41:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 16:41:03 INFO - Using env: {'DISPLAY': ':0', 16:41:03 INFO - 'HOME': '/home/cltbld', 16:41:03 INFO - 'LANG': 'en_US.UTF-8', 16:41:03 INFO - 'LANGUAGE': 'en_US:en', 16:41:03 INFO - 'LOGNAME': 'cltbld', 16:41:03 INFO - 'MAIL': '/var/mail/cltbld', 16:41:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:03 INFO - 'MOZ_NO_REMOTE': '1', 16:41:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:03 INFO - 'NO_EM_RESTART': '1', 16:41:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:03 INFO - 'PWD': '/builds/slave/test', 16:41:03 INFO - 'SHELL': '/bin/bash', 16:41:03 INFO - 'SHLVL': '1', 16:41:03 INFO - 'TERM': 'linux', 16:41:03 INFO - 'TMOUT': '86400', 16:41:03 INFO - 'USER': 'cltbld', 16:41:03 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190', 16:41:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:03 INFO - '_': '/tools/buildbot/bin/python'} 16:41:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:03 INFO - Downloading/unpacking blobuploader==1.2.4 16:41: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 16:41: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 16:41: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 16:41: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 16:41: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 16:41: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 16:41:05 INFO - Downloading blobuploader-1.2.4.tar.gz 16:41:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 16:41:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:41:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:41: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 16:41: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 16:41: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 16:41: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 16:41: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 16:41: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 16:41:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 16:41:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:41:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:41: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 16:41: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 16:41: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 16:41: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 16:41: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 16:41: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 16:41:06 INFO - Downloading docopt-0.6.1.tar.gz 16:41:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 16:41:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:41:06 INFO - Installing collected packages: blobuploader, requests, docopt 16:41:06 INFO - Running setup.py install for blobuploader 16:41:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:41:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:41:06 INFO - Running setup.py install for requests 16:41:06 INFO - Running setup.py install for docopt 16:41:06 INFO - Successfully installed blobuploader requests docopt 16:41:06 INFO - Cleaning up... 16:41:06 INFO - Return code: 0 16:41:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:41:06 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')]} 16:41:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:06 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')]} 16:41:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f31235b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f2240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11810b0>, '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 0x1181fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1182440>, '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': '004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190', '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 16:41:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 16:41:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --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 16:41:06 INFO - Using env: {'DISPLAY': ':0', 16:41:06 INFO - 'HOME': '/home/cltbld', 16:41:06 INFO - 'LANG': 'en_US.UTF-8', 16:41:06 INFO - 'LANGUAGE': 'en_US:en', 16:41:06 INFO - 'LOGNAME': 'cltbld', 16:41:06 INFO - 'MAIL': '/var/mail/cltbld', 16:41:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:06 INFO - 'MOZ_NO_REMOTE': '1', 16:41:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:06 INFO - 'NO_EM_RESTART': '1', 16:41:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:06 INFO - 'PWD': '/builds/slave/test', 16:41:06 INFO - 'SHELL': '/bin/bash', 16:41:06 INFO - 'SHLVL': '1', 16:41:06 INFO - 'TERM': 'linux', 16:41:06 INFO - 'TMOUT': '86400', 16:41:06 INFO - 'USER': 'cltbld', 16:41:06 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190', 16:41:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:06 INFO - '_': '/tools/buildbot/bin/python'} 16:41:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:41:06 INFO - Running setup.py (path:/tmp/pip-QNRu0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:41:06 INFO - Running setup.py (path:/tmp/pip-70U_bR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:41:07 INFO - Running setup.py (path:/tmp/pip-Zisk5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:41:07 INFO - Running setup.py (path:/tmp/pip-TqrH07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:41:07 INFO - Running setup.py (path:/tmp/pip-E7wOgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:41:07 INFO - Running setup.py (path:/tmp/pip-Dkk472-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:41:07 INFO - Running setup.py (path:/tmp/pip-aLPvVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:41:07 INFO - Running setup.py (path:/tmp/pip-QGCADv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:41:07 INFO - Running setup.py (path:/tmp/pip-f1Pkfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:41:07 INFO - Running setup.py (path:/tmp/pip-vQTETh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:41:07 INFO - Running setup.py (path:/tmp/pip-70_had-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:41:07 INFO - Running setup.py (path:/tmp/pip-wkZ2BH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:41:07 INFO - Running setup.py (path:/tmp/pip-GZIGSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:41:07 INFO - Running setup.py (path:/tmp/pip-zF3DEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:41:07 INFO - Running setup.py (path:/tmp/pip-pt2PWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:41:08 INFO - Running setup.py (path:/tmp/pip-nsw8vI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:41:08 INFO - Running setup.py (path:/tmp/pip-AWWAVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:41:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:41:08 INFO - Running setup.py install for manifestparser 16:41:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:41:08 INFO - Running setup.py install for mozcrash 16:41:08 INFO - Running setup.py install for mozdebug 16:41:08 INFO - Running setup.py install for mozdevice 16:41:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:41:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:41:08 INFO - Running setup.py install for mozfile 16:41:08 INFO - Running setup.py install for mozhttpd 16:41:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:41:08 INFO - Running setup.py install for mozinfo 16:41:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:41:08 INFO - Running setup.py install for mozInstall 16:41:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:41:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:41:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:41:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:41:08 INFO - Running setup.py install for mozleak 16:41:09 INFO - Running setup.py install for mozlog 16:41:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:41:09 INFO - Running setup.py install for moznetwork 16:41:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:41:09 INFO - Running setup.py install for mozprocess 16:41:09 INFO - Running setup.py install for mozprofile 16:41:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:41:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:41:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:41:09 INFO - Running setup.py install for mozrunner 16:41:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:41:09 INFO - Running setup.py install for mozscreenshot 16:41:09 INFO - Running setup.py install for moztest 16:41:09 INFO - Running setup.py install for mozversion 16:41:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:41:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:41:09 INFO - Cleaning up... 16:41:09 INFO - Return code: 0 16:41:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:41:09 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')]} 16:41:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:09 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')]} 16:41:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f31235b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f2240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11810b0>, '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 0x1181fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1182440>, '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': '004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190', '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 16:41:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 16:41:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 16:41:09 INFO - Using env: {'DISPLAY': ':0', 16:41:09 INFO - 'HOME': '/home/cltbld', 16:41:09 INFO - 'LANG': 'en_US.UTF-8', 16:41:09 INFO - 'LANGUAGE': 'en_US:en', 16:41:09 INFO - 'LOGNAME': 'cltbld', 16:41:09 INFO - 'MAIL': '/var/mail/cltbld', 16:41:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:09 INFO - 'MOZ_NO_REMOTE': '1', 16:41:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:09 INFO - 'NO_EM_RESTART': '1', 16:41:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:09 INFO - 'PWD': '/builds/slave/test', 16:41:09 INFO - 'SHELL': '/bin/bash', 16:41:09 INFO - 'SHLVL': '1', 16:41:09 INFO - 'TERM': 'linux', 16:41:09 INFO - 'TMOUT': '86400', 16:41:09 INFO - 'USER': 'cltbld', 16:41:09 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190', 16:41:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:09 INFO - '_': '/tools/buildbot/bin/python'} 16:41:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:41:10 INFO - Running setup.py (path:/tmp/pip-_xCAO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:41:10 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)) 16:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:41:10 INFO - Running setup.py (path:/tmp/pip-kh8d8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:41:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 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)) 16:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:41:10 INFO - Running setup.py (path:/tmp/pip-iy53xK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:41:10 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)) 16:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:41:10 INFO - Running setup.py (path:/tmp/pip-6CCjLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:41:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 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)) 16:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:41:10 INFO - Running setup.py (path:/tmp/pip-Jk9rWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:41:10 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)) 16:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:41:10 INFO - Running setup.py (path:/tmp/pip-QV82vn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:41:10 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)) 16:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:41:10 INFO - Running setup.py (path:/tmp/pip-e5tU71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:41:10 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)) 16:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:41:10 INFO - Running setup.py (path:/tmp/pip-AcLC2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:41:10 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)) 16:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:41:10 INFO - Running setup.py (path:/tmp/pip-s_1s6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:41:10 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)) 16:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:41:10 INFO - Running setup.py (path:/tmp/pip-hsr1rB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:41:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 16:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:41:10 INFO - Running setup.py (path:/tmp/pip-GkcXjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:41:10 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)) 16:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:41:10 INFO - Running setup.py (path:/tmp/pip-rDlnUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:41:11 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)) 16:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:41:11 INFO - Running setup.py (path:/tmp/pip-jr2z45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:41:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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)) 16:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:41:11 INFO - Running setup.py (path:/tmp/pip-VTAq1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:41:11 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)) 16:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:41:11 INFO - Running setup.py (path:/tmp/pip-jAgRn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:41:11 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)) 16:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:41:11 INFO - Running setup.py (path:/tmp/pip-5Vj8r8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:41:11 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)) 16:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:41:11 INFO - Running setup.py (path:/tmp/pip-zKTDkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:41:11 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)) 16:41:11 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:41:11 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:41:11 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)) 16:41:11 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:41:11 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:41:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:41:11 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 16:41:11 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 16:41:11 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 16:41:11 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 16:41:11 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 16:41:11 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 16:41:13 INFO - Downloading blessings-1.5.1.tar.gz 16:41:13 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 16:41:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:41:13 INFO - Installing collected packages: blessings 16:41:13 INFO - Running setup.py install for blessings 16:41:13 INFO - Successfully installed blessings 16:41:13 INFO - Cleaning up... 16:41:13 INFO - Return code: 0 16:41:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:41:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:41:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:41:13 INFO - Reading from file tmpfile_stdout 16:41:13 INFO - Current package versions: 16:41:13 INFO - argparse == 1.2.1 16:41:13 INFO - blessings == 1.5.1 16:41:13 INFO - blobuploader == 1.2.4 16:41:13 INFO - docopt == 0.6.1 16:41:13 INFO - manifestparser == 1.1 16:41:13 INFO - mozInstall == 1.12 16:41:13 INFO - mozcrash == 0.16 16:41:13 INFO - mozdebug == 0.1 16:41:13 INFO - mozdevice == 0.47 16:41:13 INFO - mozfile == 1.2 16:41:13 INFO - mozhttpd == 0.7 16:41:13 INFO - mozinfo == 0.9 16:41:13 INFO - mozleak == 0.1 16:41:13 INFO - mozlog == 3.1 16:41:13 INFO - moznetwork == 0.27 16:41:13 INFO - mozprocess == 0.22 16:41:13 INFO - mozprofile == 0.27 16:41:13 INFO - mozrunner == 6.11 16:41:13 INFO - mozscreenshot == 0.1 16:41:13 INFO - mozsystemmonitor == 0.0 16:41:13 INFO - moztest == 0.7 16:41:13 INFO - mozversion == 1.4 16:41:13 INFO - psutil == 3.1.1 16:41:13 INFO - requests == 1.2.3 16:41:13 INFO - wsgiref == 0.1.2 16:41:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:41:13 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')]} 16:41:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:13 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')]} 16:41:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f31235b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f2240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11810b0>, '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 0x1181fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1182440>, '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': '004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190', '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 16:41:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 16:41:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 16:41:13 INFO - Using env: {'DISPLAY': ':0', 16:41:13 INFO - 'HOME': '/home/cltbld', 16:41:13 INFO - 'LANG': 'en_US.UTF-8', 16:41:13 INFO - 'LANGUAGE': 'en_US:en', 16:41:13 INFO - 'LOGNAME': 'cltbld', 16:41:13 INFO - 'MAIL': '/var/mail/cltbld', 16:41:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:13 INFO - 'MOZ_NO_REMOTE': '1', 16:41:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:13 INFO - 'NO_EM_RESTART': '1', 16:41:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:13 INFO - 'PWD': '/builds/slave/test', 16:41:13 INFO - 'SHELL': '/bin/bash', 16:41:13 INFO - 'SHLVL': '1', 16:41:13 INFO - 'TERM': 'linux', 16:41:13 INFO - 'TMOUT': '86400', 16:41:13 INFO - 'USER': 'cltbld', 16:41:13 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190', 16:41:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:13 INFO - '_': '/tools/buildbot/bin/python'} 16:41:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:13 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 1)) 16:41:13 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 2)) 16:41:13 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 3)) 16:41:13 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 4)) 16:41:13 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 5)) 16:41:13 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 6)) 16:41:13 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 7)) 16:41:13 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 8)) 16:41:13 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.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:41:13 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 3)) 16:41:13 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.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:41:13 INFO - Cleaning up... 16:41:13 INFO - Return code: 0 16:41:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:41:13 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')]} 16:41:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:13 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')]} 16:41:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f31235b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f2240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11810b0>, '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 0x1181fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1182440>, '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': '004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190', '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 16:41:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 16:41:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 16:41:13 INFO - Using env: {'DISPLAY': ':0', 16:41:13 INFO - 'HOME': '/home/cltbld', 16:41:13 INFO - 'LANG': 'en_US.UTF-8', 16:41:13 INFO - 'LANGUAGE': 'en_US:en', 16:41:13 INFO - 'LOGNAME': 'cltbld', 16:41:13 INFO - 'MAIL': '/var/mail/cltbld', 16:41:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:13 INFO - 'MOZ_NO_REMOTE': '1', 16:41:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:13 INFO - 'NO_EM_RESTART': '1', 16:41:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:13 INFO - 'PWD': '/builds/slave/test', 16:41:13 INFO - 'SHELL': '/bin/bash', 16:41:13 INFO - 'SHLVL': '1', 16:41:13 INFO - 'TERM': 'linux', 16:41:13 INFO - 'TMOUT': '86400', 16:41:13 INFO - 'USER': 'cltbld', 16:41:13 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190', 16:41:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:13 INFO - '_': '/tools/buildbot/bin/python'} 16:41:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:13 INFO - Downloading/unpacking jsonschema 16:41:13 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 16:41:13 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 16:41:13 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 16:41:13 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 16:41:13 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 16:41:13 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 16:41:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:41:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 16:41:15 INFO - Downloading/unpacking functools32 (from jsonschema) 16:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:15 INFO - Downloading functools32-3.2.3-2.tar.gz 16:41:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 16:41:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:41:15 INFO - warning: no files found matching '*.txt' 16:41:15 INFO - no previously-included directories found matching 'build' 16:41:15 INFO - no previously-included directories found matching 'dist' 16:41:15 INFO - no previously-included directories found matching '.git*' 16:41:15 INFO - Installing collected packages: jsonschema, functools32 16:41:15 INFO - Running setup.py install for functools32 16:41:16 INFO - warning: no files found matching '*.txt' 16:41:16 INFO - no previously-included directories found matching 'build' 16:41:16 INFO - no previously-included directories found matching 'dist' 16:41:16 INFO - no previously-included directories found matching '.git*' 16:41:16 INFO - Successfully installed jsonschema functools32 16:41:16 INFO - Cleaning up... 16:41:16 INFO - Return code: 0 16:41:16 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:41:16 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')]} 16:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:16 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')]} 16:41:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f31235b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f2240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11810b0>, '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 0x1181fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1182440>, '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': '004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190', '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 16:41:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 16:41:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 16:41:16 INFO - Using env: {'DISPLAY': ':0', 16:41:16 INFO - 'HOME': '/home/cltbld', 16:41:16 INFO - 'LANG': 'en_US.UTF-8', 16:41:16 INFO - 'LANGUAGE': 'en_US:en', 16:41:16 INFO - 'LOGNAME': 'cltbld', 16:41:16 INFO - 'MAIL': '/var/mail/cltbld', 16:41:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:16 INFO - 'MOZ_NO_REMOTE': '1', 16:41:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:16 INFO - 'NO_EM_RESTART': '1', 16:41:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:16 INFO - 'PWD': '/builds/slave/test', 16:41:16 INFO - 'SHELL': '/bin/bash', 16:41:16 INFO - 'SHLVL': '1', 16:41:16 INFO - 'TERM': 'linux', 16:41:16 INFO - 'TMOUT': '86400', 16:41:16 INFO - 'USER': 'cltbld', 16:41:16 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190', 16:41:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:16 INFO - '_': '/tools/buildbot/bin/python'} 16:41:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:16 INFO - Downloading/unpacking flake8 16:41:16 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 16:41:16 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 16:41:16 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 16:41:16 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 16:41:16 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 16:41:16 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 16:41:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:41:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 16:41:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:41:18 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 16:41:18 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 16:41:18 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 16:41:18 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 16:41:18 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 16:41:18 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 16:41:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:41:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 16:41:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:41:18 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 16:41:18 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 16:41:18 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 16:41:18 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 16:41:18 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 16:41:18 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 16:41:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 16:41:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:41:18 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 16:41:18 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 16:41:18 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 16:41:18 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 16:41:18 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 16:41:18 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 16:41:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 16:41:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:41:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:41:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:41:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:41:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:41:18 INFO - no previously-included directories found matching 'docs/_build' 16:41:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:41:18 INFO - Running setup.py install for pep8 16:41:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:41:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:41:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:41:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:41:18 INFO - no previously-included directories found matching 'docs/_build' 16:41:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:41:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:41:18 INFO - Cleaning up... 16:41:18 INFO - Return code: 0 16:41:18 INFO - Running post-action listener: _resource_record_post_action 16:41:18 INFO - Running post-action listener: _start_resource_monitoring 16:41:18 INFO - Starting resource monitoring. 16:41:18 INFO - ##### 16:41:18 INFO - ##### Running install step. 16:41:18 INFO - ##### 16:41:18 INFO - Running pre-action listener: _resource_record_pre_action 16:41:18 INFO - Running main action method: install 16:41:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:41:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:41:19 INFO - Reading from file tmpfile_stdout 16:41:19 INFO - Detecting whether we're running mozinstall >=1.0... 16:41:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:41:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:41:19 INFO - Reading from file tmpfile_stdout 16:41:19 INFO - Output received: 16:41:19 INFO - Usage: mozinstall [options] installer 16:41:19 INFO - Options: 16:41:19 INFO - -h, --help show this help message and exit 16:41:19 INFO - -d DEST, --destination=DEST 16:41:19 INFO - Directory to install application into. [default: 16:41:19 INFO - "/builds/slave/test"] 16:41:19 INFO - --app=APP Application being installed. [default: firefox] 16:41:19 INFO - mkdir: /builds/slave/test/build/application 16:41:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:41:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:41:33 INFO - Reading from file tmpfile_stdout 16:41:33 INFO - Output received: 16:41:33 INFO - /builds/slave/test/build/application/firefox/firefox 16:41:33 INFO - Running post-action listener: _resource_record_post_action 16:41:33 INFO - ##### 16:41:33 INFO - ##### Running run-tests step. 16:41:33 INFO - ##### 16:41:33 INFO - Running pre-action listener: _resource_record_pre_action 16:41:33 INFO - Running main action method: run_tests 16:41:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:41:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:41:33 INFO - Python 2.7.3 16:41:33 INFO - Return code: 0 16:41:33 INFO - grabbing minidump binary from tooltool 16:41: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')]} 16:41:33 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11810b0>, '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 0x1181fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1182440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:41:33 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 16:41:33 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 16:41:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:41:33 INFO - Return code: 0 16:41:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:41:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:41:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:41:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:41:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:41:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:41:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:41:34 INFO - Return code: 0 16:41:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost'] in /builds/slave/test/build 16:41:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --webServer localhost 16:41:34 INFO - Using env: {'DISPLAY': ':0', 16:41:34 INFO - 'HOME': '/home/cltbld', 16:41:34 INFO - 'LANG': 'en_US.UTF-8', 16:41:34 INFO - 'LANGUAGE': 'en_US:en', 16:41:34 INFO - 'LOGNAME': 'cltbld', 16:41:34 INFO - 'MAIL': '/var/mail/cltbld', 16:41:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:41:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:41:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:34 INFO - 'MOZ_NO_REMOTE': '1', 16:41:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:41:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:34 INFO - 'NO_EM_RESTART': '1', 16:41:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:34 INFO - 'PWD': '/builds/slave/test', 16:41:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:41:34 INFO - 'SHELL': '/bin/bash', 16:41:34 INFO - 'SHLVL': '1', 16:41:34 INFO - 'TERM': 'linux', 16:41:34 INFO - 'TMOUT': '86400', 16:41:34 INFO - 'USER': 'cltbld', 16:41:34 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190', 16:41:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:34 INFO - '_': '/tools/buildbot/bin/python'} 16:41:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost'] with output_timeout 3600 16:41:34 INFO - mozversion INFO | application_buildid: 20151215152858 16:41:34 INFO - mozversion INFO | application_changeset: 8f3a1bd74aee41509e0c3cb3e6f1ba1954e5fb0a 16:41:34 INFO - mozversion INFO | application_display_name: Nightly 16:41:34 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:41:34 INFO - mozversion INFO | application_name: Firefox 16:41:34 INFO - mozversion INFO | application_remotingname: firefox 16:41:34 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 16:41:34 INFO - mozversion INFO | application_vendor: Mozilla 16:41:34 INFO - mozversion INFO | application_version: 46.0a1 16:41:34 INFO - mozversion INFO | platform_buildid: 20151215152858 16:41:34 INFO - mozversion INFO | platform_changeset: 8f3a1bd74aee41509e0c3cb3e6f1ba1954e5fb0a 16:41:34 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 16:41:34 INFO - mozversion INFO | platform_version: 46.0a1 16:41:34 INFO - 2015-12-15 16:41:34,975 DEBUG : using testdate: 1450226494 16:41:34 INFO - 2015-12-15 16:41:34,975 DEBUG : actual date: 1450226494 16:41:34 INFO - 2015-12-15 16:41:34,984 INFO : starting webserver on 'localhost:60378' 16:41:34 INFO - 2015-12-15 16:41:34,985 INFO : Starting test suite talos-linux64-ix-066 16:41:34 INFO - 2015-12-15 16:41:34,985 INFO : Starting test dromaeo_css 16:41:34 INFO - 2015-12-15 16:41:34,985 DEBUG : operating with platform_type : linux_ 16:41:34 INFO - 2015-12-15 16:41:34,986 INFO : Initialising browser for dromaeo_css test... 16:41:35 INFO - 2015-12-15 16:41:35,000 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkeWZGq/profile http://localhost:60378/getInfo.html 16:41:39 INFO - 2015-12-15 16:41:39,457 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:41:39 INFO - 2015-12-15 16:41:39,457 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:41:39 INFO - 2015-12-15 16:41:39,457 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:41:39 INFO - 2015-12-15 16:41:39,457 DEBUG : BROWSER_OUTPUT: __metrics 16:41:39 INFO - 2015-12-15 16:41:39,687 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 16:41:39 INFO - 2015-12-15 16:41:39,755 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:41:40 INFO - 2015-12-15 16:41:40,569 INFO : Browser initialized. 16:41:40 INFO - 2015-12-15 16:41:40,569 INFO : Running cycle 1/1 for dromaeo_css test... 16:41:40 INFO - 2015-12-15 16:41:40,570 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkeWZGq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:41:52 INFO - 2015-12-15 16:41:52,607 DEBUG : BROWSER_OUTPUT: RSS: Main: 143130624 16:41:52 INFO - 2015-12-15 16:41:52,608 DEBUG : BROWSER_OUTPUT: 16:42:43 INFO - 2015-12-15 16:42:43,988 DEBUG : BROWSER_OUTPUT: 1450226563987 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] 16:44:55 INFO - 2015-12-15 16:44:55,964 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60378/tests/dromaeo/cssquery-ext.html) 16:44:57 INFO - 2015-12-15 16:44:57,282 DEBUG : BROWSER_OUTPUT: RSS: Main: 244056064 16:44:57 INFO - 2015-12-15 16:44:57,282 DEBUG : BROWSER_OUTPUT: 16:47:59 INFO - 2015-12-15 16:47:59,917 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/dromaeo/cssquery-ext.html (next: http://localhost:60378/tests/dromaeo/cssquery-jquery.html) 16:48:00 INFO - 2015-12-15 16:48:00,490 DEBUG : BROWSER_OUTPUT: RSS: Main: 220598272 16:48:00 INFO - 2015-12-15 16:48:00,490 DEBUG : BROWSER_OUTPUT: 16:51:02 INFO - 2015-12-15 16:51:02,719 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60378/tests/dromaeo/cssquery-mootools.html) 16:51:03 INFO - 2015-12-15 16:51:03,293 DEBUG : BROWSER_OUTPUT: RSS: Main: 222670848 16:51:03 INFO - 2015-12-15 16:51:03,293 DEBUG : BROWSER_OUTPUT: 16:54:05 INFO - 2015-12-15 16:54:05,196 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60378/tests/dromaeo/cssquery-prototype.html) 16:54:05 INFO - 2015-12-15 16:54:05,783 DEBUG : BROWSER_OUTPUT: RSS: Main: 204247040 16:54:05 INFO - 2015-12-15 16:54:05,784 DEBUG : BROWSER_OUTPUT: 16:57:08 INFO - 2015-12-15 16:57:08,514 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60378/tests/dromaeo/cssquery-yui.html) 16:57:09 INFO - 2015-12-15 16:57:09,127 DEBUG : BROWSER_OUTPUT: RSS: Main: 211599360 16:57:09 INFO - 2015-12-15 16:57:09,127 DEBUG : BROWSER_OUTPUT: 17:00:11 INFO - 2015-12-15 17:00:11,637 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/dromaeo/cssquery-yui.html (next: http://localhost:60378/tests/dromaeo/cssquery-dojo.html) 17:00:11 INFO - 2015-12-15 17:00:11,883 DEBUG : BROWSER_OUTPUT: RSS: Main: 254210048 17:00:11 INFO - 2015-12-15 17:00:11,883 DEBUG : BROWSER_OUTPUT: 17:00:11 INFO - 2015-12-15 17:00:11,884 DEBUG : BROWSER_OUTPUT: __start_tp_report 17:00:11 INFO - 2015-12-15 17:00:11,885 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 17:00:11 INFO - 2015-12-15 17:00:11,885 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 17:00:11 INFO - 2015-12-15 17:00:11,885 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 17:00:11 INFO - 2015-12-15 17:00:11,886 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2648.24525010372;2729.590561415786;2733.986330068349;2735.781137508998;2753.669559652842;4626.514216007321;4762.214234651287;4820.301056346832;4847.175980083381;4857.538533839289;4745.57289843914;4821.638377121712;4823.662343635938;4827.662063655545;4989.077020751154;4428.247197976351;4439.1787519308955;4444.577765112309;4539.45526536816;4581.748003859786;4506.909861802762;4513.14250292445;4582.656300777438;4588.7246765194195;4622.422197225347;4277.12318974611;4341.435613370273;4372.781360931973;4459.264221403465;4464.977675111636;4437.33439984005;4478.529754375776;4478.552144785528;4481.686281960264;4530.07133537624;4445.444319460067;4451.443569553806;4452.309891966718;4473.686841921067;4499.212637788374;4597.60920321773;4654.790534425959;4723.433188017426;4723.7874295656775;4727.550882666142;5217.921731174036;5222.451642577551;5224;5227.294315267466;5231.816886408996;5218.9739051304505;5240.659357141773;5247.449017853109;5247.553957913582;5295.947040529622;4572.1312950539295;4673.322368256584;4685.109829132454;4849;4856.17445034345;5269.789208431696;5340.732963351817;5347.598930080259;5366.409694933554;5415.970965516539;5100.38795344561;5107.719075450886;5109.6678715883345;5114.053900028507;5114.71869047206;4919.237518184687;4991.850244492671;5036.7733451994745;5218.269442278084;5265.710385928811;5032.72320022395;5106.259592359087;5113.923291150636;5123.180291153398;5161.767720452589;5091.439941606421;5113.53978141969;5114.15616423286;5115.258287548285;5129.512696293846;4073.7555746655294;4164.187983343219;4172.70791044624;4289.699721019561;4328.11273688893;3661.1213308805695;3712.480252764615;3737.177820879402;3747.081964918558;3764.228333191744;4446.644268458515;4464.799084041226;4469.463664360298;4476;4514;4989.950100498949;5119.8720031998755;5183.922241166386;5194.662346947436;5197.818076367309;4352.542982986741;4386.8025938832825;4406.25093734066;4420.270655341833;4456.22016147173;4578.748168850679;4581.335706322565;4583.495815460296;4610;4622.815087396467;4716.952830471651;4834.516548345137;4941.1847045237855;4941.357623509065;4941.530554597308;5022;5065.3161823153405;5119.74401279942;5136.512031357015;5174.404943431554;4884.78018489176;4893.608511319015;4898.314236007033;4909.950900491092;4942.258661200849;4840.080384726891;4906.411230652345;5047.823326183713;5063.594912406925;5087.949120508895;5043.142665746906;5059.949400505948;5075.974620126875;5199.142141546602;5203.271541984201;5007.998400320023;5049.823256186016;5050.924236136388;5059.645824792229;5103.030424219386;5058.4688607697135;5058.772355244097;5062.82280120194;5064.088464076502;5068.26510156018;4778;4854.05345957549;4864.756762161807;4911.3369695090705;4967.552920237196;5089.618278629192;5097.260897169964;5102.821401250939;5119.795208191779;5131.358580177478;4994.625403094855;5002.2496625506465;5003.299538064746;5005.199168133081;5045.2432135180015 17:00:11 INFO - 2015-12-15 17:00:11,887 DEBUG : BROWSER_OUTPUT: |1;ext.html;551.6965668882115;555.813802376204;565.5221337969417;569.1718549510463;579.3771695427412;33832.830835845816;34842.651573484254;34869.651303486986;34883.4767478488;34924.12689682759;37041.81479092607;37603.059923501925;37629.81185094077;37666.43500347497;37686;6985.930140698579;7088.893666595005;7771.106322772869;8005.759827205193;9612.567434465465;9935.155511781508;10093.343932644355;10100.090991810735;10102.74743131424;10104.04011618895;10024.047715467055;10146.64486742964;10185.134263587604;10204.132648724868;10207.846882296772;7248.275172482762;7446.553206807609;10079.244056695778;10378.221633377527;10408.115310198642;9899.90100098997;10065.194784417228;10077.294589378746;10077.748056298651;10083.949580252125;34799.826000869834;35168.47247291293;35217.29565408703;35228.823855880815;35249;3001.6247969003875;3340.7160391366765;3696.149885526344;3783.6027217142077;3784.3188226119287;27285.727142728516;27390.452190956068;27439.45121097567;27676.723232767617;28021.85989070062;2977.8511074446405;3013.758899288052;3015.793682526985;3017.6982301769867;3186.203449137716;207019;207523;208510;209246;209936;43667.56332436699;44784.552154478035;51724.741376293634;52647;52741.736291318295;45640;45908;45941.5405845944;45975;46055;12864.356782160854;13062.54281100157;13098.017648676387;13102.475900964046;13111.082224244212;9071.954640226757;9083.182513573678;9088.136627020422;9120;9134.360594758304;17611.55971100707;17870.731939020927;17879.37422190217;17884.105794710213;17925.910370448324;6947;7117.25268846774;7173.354398104091;7181.425485961111;7217.530860494051;731.3783284208487;808.4623725222779;871.302957633903;886.7605746448423;887.4630848336792;21765;22190.223342182948;25165.74834251671;26299.73700263011;26463.603045954325;950.3347656640241;960.1934375124929;979.5738853598707;984.7931934293879;1023.467796745688;12226.26642401441;12342.382880855921;12366.75266494665;12368.690782730324;12370.381480925918;252898;256237;259451;259464;259479;43208.351874721906;43305.13389732251;43595.78202108969;43623.56376436195;43811.780941095094;1228.0359917464748;1233.3031837012059;1239.114033466076;1241.7951038078797;1253.705379235897;23328;23921.880390597937;27012.72987270102;28623.284417889718;28652.85673571619;38235.23529529449;39353.40969885397;44339.55660443483;45648.771756142334;45709.54290457053;205457;205733;206737;206912;207514;23581.88209058944;24278.51442971166;27679.44641107126;28086.2978425541;28102.43795124127;35589.28821423505;35970.64029359673;36486.45270320894;36855.26289474249;36965.815170923976;30902.381952360378;31151.221219469695;32892.50661240036;35911;35933.64066359303;31441.685583143877;31569;31576.684233157375;31656.208594785316;31871.68128318687;30659.386812264074;31450.370992579563;31471.84264078665;31642.36715265728;31691.52462713107;27583.172504824888;28233.294167645974;28532.286692832844;28663.42673146567;28675.713242867303;22738.65892011654;22978.885105574365;23037.308880733606;23567.29298121059;23645.527089458457 17:00:11 INFO - 2015-12-15 17:00:11,887 DEBUG : BROWSER_OUTPUT: |2;jquery.html;335.0584856553086;338.2912797688842;338.8085731561668;339.6857906436547;341.3480252717307;12547.811782823243;12914.612561623166;14794.260286985647;15025.422330655289;16988.490345289647;11645.534178632844;13492;13889.097208681429;14472.131672099655;14486.42054317826;4400.845970391037;4949;5084.720340381277;5093.592512598983;5786.9710651446685;3801.923961520768;3909;5698.743556539945;5842.766289348421;5893.145493903381;5800.187973683687;5803.245578074844;5809.883802323951;5831.708414579275;5834.4165583441745;5753.971230143843;5847.590668653196;5855.502282305966;5913.142594323842;5917.4970127539;5121.103806833789;5127.769250383742;5151.4333423323405;5156.613254005964;5167.819126330561;19288.517787055294;19540.511487212792;19765.08264873791;19768.901155494274;19928.701069483825;1916.377177417345;1925.345382569919;1985.0967809646577;2096.937091887246;2215.3353993801793;12598.937005315007;12733;12947.48210071596;13042.608721738363;13073.86926130736;1774.5120091974682;1812.9909350453163;1819.5724004858848;1837.3477415517566;1888.2730148892774;53401.73299133479;55621.16568251439;55753.44246557523;55761;55791.721041394536;9212.815743685089;9330.626774928927;9516.429789084861;9558.474283914451;9594;12028.518859245532;12055.939720301343;12096.697582560506;12118.454669539891;12124.575639852563;6789.321067893171;6817.795466136024;6839.897401538981;6855.280195579492;6860.931390686129;6430;6456.903146452807;6465.288818229991;6494.220693516808;6509.674516274262;5607.467290607386;5671.262735844314;5672.14917762339;5680.403557626472;5715.457031581993;1540.399244294726;1593.402474072223;1610.4604957339423;1641.8686505079565;1719.5615118144792;831.7380025292014;883.9779005524784;968.4818622037176;969.904008470424;995.3331268050424;16163.515094547183;16216;16274.186290685418;16812.15939203035;16896.746548801777;784.125699844669;859.9656013759504;870.429252782199;883.4037025008093;966.6616684160488;8985.820283594423;9215.308851836138;9507.952460237793;9636.180924621485;9837.508124593885;490335;496717;500928;507847;509150;70991;71373;73176;73691;74269.62865185748;1021.9795604087876;1031.417249254184;1038.2835843268122;1063.4682658670665;1065.2756125834353;14587.34356953961;14589.343479543233;14719.099438648553;14922.253887305375;15260.771088433905;33178.5023224647;33820;34363.4845477323;34449.827750861084;35006.64993350034;44597.77701111474;45204.547954520036;46026.53973460357;47657;48017.75991120022;8563;9010.414323068979;9079.500627465288;9085.13691199335;9110.180083792491;10465.162786977176;10521.894781052397;12468.750624987268;15519.84480155184;16169.110698911672;10846.45767711627;11210.49552770111;13531.93234033863;13781.173129612256;13867.375968081258;13417;13699.383527741475;13709.520166794115;14028.78956815669;14047.719045618825;14202.715945681235;14228.430862765374;14368.568942931728;14506.709865802835;14780.408783648534;8538.231559159705;8992.190702836777;8996.235319997611;9043.773905652411;9270.490123043297;11319.886801131883;13497.797533036815;13583.660408489866;13864.514741983981;13875.583732488041 17:00:11 INFO - 2015-12-15 17:00:11,889 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1440.6758479342157;1607.6302450436397;1685.4775019743881;1703.684818308613;1717.2787429279701;5745.224394706714;6739.595624262542;7184.964075179623;7209.0988626421695;7219.314165154302;7049.36555709986;7198.532095413794;7237.023001894742;7266.781996540099;7282.817929551764;2063.38098570429;2102.463871712711;2107.525806693497;2109.166879082761;2109.8734075955417;2086.7704552499213;2106.5049713317358;2129.9893500532476;2152.5802468518646;2154.5367745934623;2042.5710600773855;2071.1818831561523;2072.046858445117;2094.8428867834896;2099.2652571599892;2031.309354819361;2052.6818343156683;2053.7843526429733;2054.3323419888447;2055.393658870629;2066.101245958013;2074.8236399906027;2119.3430036688674;2121.1515393842433;2124.851260411772;7009.299070093001;7021.754238601676;7039.929600703978;7057;7066.57600543969;4034.536028356747;4072.1448495815916;4098.979505102456;4102;4103.5691252418665;2637.7230390809073;2672.772814310786;2683.9865800670873;2690.7847372210176;2701.5947607858784;830.2735106781566;832.7460124661976;847.1274587175158;848.2281124176969;856.1652388920814;9210.81578368429;9223.53882305882;9241.907580924171;9251.583678734472;9253.583588738524;3762.172322089136;3763.8682646107254;3823.847046118124;3871.709621778378;3874.3800991841736;7996.400269979775;8036.4776289541;8048.436609437284;8048.758537243775;8061.556614386148;4112.342025276002;4121.340585506305;4131.153113611703;4156.002559385725;4158.604932531404;3214.373197226518;3241.497567876983;3708.6291370862696;3755.6244375562774;3795.8481660733814;3399.439092549751;3451.9827400862837;3482.1468740158316;3483.9825800870835;3501.352249833789;1105.2815669814524;1116.1628778416189;1123.5393488669606;1128.3963079752293;1133.9659810205708;864.7838040489878;865.0398058155442;865.5628907401722;878.9979423457264;992.593036854886;6612.933870661328;6781.796546103625;6795.1845778506895;6822.4200942919415;6850.21222559412;1041.9739506512399;1045.4093437207953;1062.4475272858072;1067.2742535076068;1078.320657985464;5401.459854014645;5462.890742185134;5464.726763661801;5471.480209380103;5477.315335583052;8392.16078392156;8449.408541402043;8700.564971751388;8726.86909696355;8757.255633271243;8581.485110893367;8628.611712472828;8639.827203455896;8685.39202255836;8685.739427817176;6659.966700166565;6767.52627316093;6783.864322713518;6825.863482730318;6842.965785171043;6614.404703576702;6656.267810540933;6663.300353462817;6675.632840193836;6680.331966803281;7598.468107232441;7620;7763.961180194063;7844.2155784423385;7898.2891539761695;7874;7968.521888686697;8091.959540202261;8104.108548059591;8117.431779775359;5044.571211446922;5156.1750119980625;5163.741812909265;5905.320888097924;5998.700064996646;5725.427457254408;6858.5199036067;6873.759418420332;6966.233714291323;6972.755953541602;6409.9679501602195;6622.536422450575;6697.531172817856;6750.291219422086;6765.526413151032;6364.840878977878;6450.290468048604;6460.321666224979;6482.35176482348;6542.476601871744;6505.544611877123;6528.640924748995;6557.573757705733;6563.245226798979;6583.078369028244;5754;5876.6180198288685;5892.057270836645;5911.379305172895;5943.049112142036;5778.797742079007;5813.069908814569;5821.854453638693;5835.124731290341;5845.649261044351 17:00:11 INFO - 2015-12-15 17:00:11,890 DEBUG : BROWSER_OUTPUT: |4;prototype.html;286.69896608561004;293.62855987176226;296.96436427628686;299.50133028507537;300.90371081254;5284.180951952444;5354.170103633938;5360.437154098817;5403.432639572848;5421.593380496469;5330.3603567571845;5332.306800115979;5332.960072785809;5333.226682131099;5336.519713225809;4095.6313931746135;4120.093579412524;4127.463429754128;4134.297169481196;4142.544320124784;3881.961180388188;3999.180168065554;4035;4139.296319625656;4142.482189726285;4086.795660216992;4090.4886889138857;4098.836046558149;4108.753474791507;4109.815058322382;3875.5736868944396;3956.7625942443256;4097.201045796071;4098.241825262336;4101.979490102531;3535.257595904863;3579.821008949542;3593.802340871251;3605.405108157151;3617.113807117247;5136.486351364871;5137.972405518911;5142.434332223453;5147.794088236466;5156.639035267533;4739.194336962724;4740.928886066712;4742.976285118587;4744.833930812405;4749.453812811536;5408.4591540845995;5599.972000139973;5602.915956260659;5604.439556044404;5605.411431799643;3958.129211573449;3959.109200429909;3959.5644479107277;3967.8016099194924;3971;5886.764529418818;5894.646321220697;5896.734646940898;5903.173555702205;5904.114382842565;4083.3262511685834;4085.142120154801;4089.754614723126;4091.6931230157857;4093.836246550164;4032.6370626643156;4141.399497072907;4144.33690609501;4150.5641907599875;4156;4661.044485880387;4661.324108004321;4665.066986602626;4666;4673.7429441381055;5595.608307418442;5598.048331783607;5598.496135347838;5601.075822489243;5601.131824567117;1422.4310275889727;1458.9927050364893;1459.613202501338;1460.3501441858484;1462.3200211901517;973.5667627905514;991.6248565984508;993.036754348281;995.8804943406838;1016.1210552871713;4959.652824302337;4966.106100901872;4973.403191616957;4977.129002424561;4978.651494395358;5833.241678581757;5840.532757379399;5847.707614619252;5848.502877255396;5851.034579294454;4965.230389289666;4968.22992436173;4970.055689418999;4976.104301225741;4976.975115124474;3021.6525099613827;3077.1076387847274;3117.5479555464785;3138.9372212555622;3152.7635427342584;5463.9180412293845;5479.671219726829;5613.663180209119;5615.466530679579;5616.522595579421;6047.365026672136;6073.271207455134;6073.453389194905;6073.848153796191;6080.0575910733905;5567.192757050205;5586.91619625706;5592.412796656293;5601.355844077983;5610.523105535994;5756.510696590837;5885.823425297248;5897.292324921037;5905.320888097752;5907;5754.482096611325;5758.740856661377;5759.769609215585;5765.423457654201;5768.048272035235;5598.916016259841;5604.775808967595;5606.467385598362;5606.775728970959;5612.7193640317;3850.7497012694084;3943.802809859553;3988.481497405319;3989.4414781929972;3990.82041308136;5784.50831679312;5839.854003649943;5854.326752423526;5854.707264636836;5859.677717725566;5624.971875140598;5634.183043458757;5636.718164091861;5638.718064096861;5643.238162847963;5624.634398764231;5631.662100273997;5636.830895073154;5638.23883775685;5642.266505354278;5396.163594642837;5501.779928802803;5515.889682206253;5518.089515229942;5524.751386187711;5656.123300888369;5661;5671.858203544779;5674.347450043298;5677.318721753416;5530.7511161997;5534.225208470898;5534.280543529316;5539.390667026543;5539.977908063608 17:00:11 INFO - 2015-12-15 17:00:11,891 DEBUG : BROWSER_OUTPUT: |5;yui.html;691.4848437913754;733.5855241788389;741.5921243316177;763.3549650545291;766.440498436142;10840.512176952036;11289.548418063277;11299.265547739391;11346;11366.37484938328;11530.135239857002;11625.186236963416;11626.534938602446;11636.883631163686;11650.766984660302;614.2782230878722;621.4158690830604;775.8176828445324;783.561205724796;801.5391150088693;777.5762209595783;779.5946108023825;781.7811012916393;786.2412771675332;786.6931896560346;640.878233135705;818.8157664525464;827.0571548434789;828.0146625515647;828.6892415344246;640.5324113397199;800.5316889619551;816.2327412232542;817.9263866252006;818.8607936650784;593.2880543347957;602.5209958083334;603.2700432476709;605.1376788076973;605.1769593353036;10492.317999330018;10774.5690172393;10796.2442629016;10803.621873234477;10848.457577121113;10744.30162039465;11315.434228288552;11318.773624527546;11345.773084538345;11355.772884542263;9429.198518125968;9615.471149086798;9649.758756031086;9805.166560842337;9828.852567211497;3249.78876373035;3256.3161736035463;3293.75296852737;3300.900972970791;3361.8487168077495;39345.80327098375;39539.8023009886;39552.60447395518;40536;40698.593014069775;8516.467035933592;8562.186592273723;8567.74296771098;8717.869231961526;8807.207351338411;15601.687966240612;15719.921400392925;15725.528234152765;15750.9212453937;15752.76370854438;6326.778562750282;6361.395667411589;6426.839329016812;6561;6728.495362847708;3342.983285083559;3377.4933759935716;3432.8111953842777;3450.3616830886367;3468.202313467866;2601.843889366644;2636.683597968218;2641.0624228399024;2670.0521314933308;2671.9465610687676;455.16477264219867;455.1874903297598;458.42238779138523;460.2199272233532;463.92809114587294;458.0472616956722;499.65274134476914;508.9820359281437;552.3344370034099;600.1507866369126;7312.670929808065;7342.596157211405;7570.205128461543;7628.275313845176;7714.151443341228;465.95573420524994;552.4392741367515;582.2198254339121;583.4952765857588;583.6818933681154;3449.3273811606996;3460.4290292101523;3536.2397084626414;3568.91077723059;3629.7822130672243;46770.53229467662;52773.208401874006;52878.20682689763;52949.2057619136;53354;43593;44701.882452938284;44803.775981119885;45309;45400.54599454029;40012.59987400147;41157.794211028755;41832.58167418348;41913.16173676509;42535.361969570484;7569.772906812805;8312.177707259072;8472.83054338922;8592.914070859211;8638.78403039929;31034;31083;31434.21414464657;32034.19914502156;32347.838260808545;35861;36185.63814361928;39930.20139597134;40438.59561404349;40862.79568602138;9646.951765241129;9795.706128816148;9823.26325525574;9857.605695772088;10084.546195421077;10662;10948.890511094787;11473.942630286796;11540.942295288805;11790.528378864748;10000.699979000641;10099.899001009895;10127.139193168663;10132.848007279748;10539.314944528582;10094.192464602667;10406.219533534864;10439.475789819036;10460.79078418412;10557.366558006544;9014.729558113006;9054.456732596065;9059.592318345558;9271.351005429555;9283.48940808235;9122.863157052781;9205.815883682155;9296.39573427725;9325.393849399767;9407.670731524364;8685.478871267744;8911.15344042315;8949.955250223707;8972.596233169654;9064.184223419923 17:00:11 INFO - 2015-12-15 17:00:11,891 DEBUG : BROWSER_OUTPUT: __end_tp_report 17:00:11 INFO - 2015-12-15 17:00:11,891 DEBUG : BROWSER_OUTPUT: __start_cc_report 17:00:11 INFO - 2015-12-15 17:00:11,891 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1147 17:00:11 INFO - 2015-12-15 17:00:11,891 DEBUG : BROWSER_OUTPUT: __end_cc_report 17:00:11 INFO - 2015-12-15 17:00:11,891 DEBUG : BROWSER_OUTPUT: __startTimestamp1450227611883__endTimestamp 17:00:11 INFO - 2015-12-15 17:00:11,892 DEBUG : BROWSER_OUTPUT: 17:00:11 INFO - 2015-12-15 17:00:11,892 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 17:00:11 INFO - 2015-12-15 17:00:11,892 DEBUG : BROWSER_OUTPUT: Number of tests: 6 17:00:11 INFO - 2015-12-15 17:00:11,892 DEBUG : BROWSER_OUTPUT: 17:00:11 INFO - 2015-12-15 17:00:11,892 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4758.05 Median:4898.31 stddev:507.53 (10.4%) stddev-sans-first:481.35 17:00:11 INFO - 2015-12-15 17:00:11,892 DEBUG : BROWSER_OUTPUT: Values: 2648.2 2729.6 2734.0 2735.8 2753.7 4626.5 4762.2 4820.3 4847.2 4857.5 4745.6 4821.6 4823.7 4827.7 4989.1 4428.2 4439.2 4444.6 4539.5 4581.7 4506.9 4513.1 4582.7 4588.7 4622.4 4277.1 4341.4 4372.8 4459.3 4465.0 4437.3 4478.5 4478.6 4481.7 4530.1 4445.4 4451.4 4452.3 4473.7 4499.2 4597.6 4654.8 4723.4 4723.8 4727.6 5217.9 5222.5 5224.0 5227.3 5231.8 5219.0 5240.7 5247.4 5247.6 5295.9 4572.1 4673.3 4685.1 4849.0 4856.2 5269.8 5340.7 5347.6 5366.4 5416.0 5100.4 5107.7 5109.7 5114.1 5114.7 4919.2 4991.9 5036.8 5218.3 5265.7 5032.7 5106.3 5113.9 5123.2 5161.8 5091.4 5113.5 5114.2 5115.3 5129.5 4073.8 4164.2 4172.7 4289.7 4328.1 3661.1 3712.5 3737.2 3747.1 3764.2 4446.6 4464.8 4469.5 4476.0 4514.0 4990.0 5119.9 5183.9 5194.7 5197.8 4352.5 4386.8 4406.3 4420.3 4456.2 4578.7 4581.3 4583.5 4610.0 4622.8 4717.0 4834.5 4941.2 4941.4 4941.5 5022.0 5065.3 5119.7 5136.5 5174.4 4884.8 4893.6 4898.3 4910.0 4942.3 4840.1 4906.4 5047.8 5063.6 5087.9 5043.1 5059.9 5076.0 5199.1 5203.3 5008.0 5049.8 5050.9 5059.6 5103.0 5058.5 5058.8 5062.8 5064.1 5068.3 4778.0 4854.1 4864.8 4911.3 4967.6 5089.6 5097.3 5102.8 5119.8 5131.4 4994.6 5002.2 5003.3 5005.2 5045.2 17:00:11 INFO - 2015-12-15 17:00:11,892 DEBUG : BROWSER_OUTPUT: 17:00:11 INFO - 2015-12-15 17:00:11,892 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:37963.64 Median:31446.03 stddev:58469.86 (185.9%) stddev-sans-first:58566.40 17:00:11 INFO - 2015-12-15 17:00:11,892 DEBUG : BROWSER_OUTPUT: Values: 551.7 555.8 565.5 569.2 579.4 33832.8 34842.7 34869.7 34883.5 34924.1 37041.8 37603.1 37629.8 37666.4 37686.0 6985.9 7088.9 7771.1 8005.8 9612.6 9935.2 10093.3 10100.1 10102.7 10104.0 10024.0 10146.6 10185.1 10204.1 10207.8 7248.3 7446.6 10079.2 10378.2 10408.1 9899.9 10065.2 10077.3 10077.7 10083.9 34799.8 35168.5 35217.3 35228.8 35249.0 3001.6 3340.7 3696.1 3783.6 3784.3 27285.7 27390.5 27439.5 27676.7 28021.9 2977.9 3013.8 3015.8 3017.7 3186.2 207019.0 207523.0 208510.0 209246.0 209936.0 43667.6 44784.6 51724.7 52647.0 52741.7 45640.0 45908.0 45941.5 45975.0 46055.0 12864.4 13062.5 13098.0 13102.5 13111.1 9072.0 9083.2 9088.1 9120.0 9134.4 17611.6 17870.7 17879.4 17884.1 17925.9 6947.0 7117.3 7173.4 7181.4 7217.5 731.4 808.5 871.3 886.8 887.5 21765.0 22190.2 25165.7 26299.7 26463.6 950.3 960.2 979.6 984.8 1023.5 12226.3 12342.4 12366.8 12368.7 12370.4 252898.0 256237.0 259451.0 259464.0 259479.0 43208.4 43305.1 43595.8 43623.6 43811.8 1228.0 1233.3 1239.1 1241.8 1253.7 23328.0 23921.9 27012.7 28623.3 28652.9 38235.2 39353.4 44339.6 45648.8 45709.5 205457.0 205733.0 206737.0 206912.0 207514.0 23581.9 24278.5 27679.4 28086.3 28102.4 35589.3 35970.6 36486.5 36855.3 36965.8 30902.4 31151.2 32892.5 35911.0 35933.6 31441.7 31569.0 31576.7 31656.2 31871.7 30659.4 31450.4 31471.8 31642.4 31691.5 27583.2 28233.3 28532.3 28663.4 28675.7 22738.7 22978.9 23037.3 23567.3 23645.5 17:00:11 INFO - 2015-12-15 17:00:11,892 DEBUG : BROWSER_OUTPUT: 17:00:11 INFO - 2015-12-15 17:00:11,892 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26995.88 Median:3855.46 stddev:81806.14 (2121.8%) stddev-sans-first:82011.14 17:00:11 INFO - 2015-12-15 17:00:11,892 DEBUG : BROWSER_OUTPUT: Values: 335.1 338.3 338.8 339.7 341.3 12547.8 12914.6 14794.3 15025.4 16988.5 11645.5 13492.0 13889.1 14472.1 14486.4 4400.8 4949.0 5084.7 5093.6 5787.0 3801.9 3909.0 5698.7 5842.8 5893.1 5800.2 5803.2 5809.9 5831.7 5834.4 5754.0 5847.6 5855.5 5913.1 5917.5 5121.1 5127.8 5151.4 5156.6 5167.8 19288.5 19540.5 19765.1 19768.9 19928.7 1916.4 1925.3 1985.1 2096.9 2215.3 12598.9 12733.0 12947.5 13042.6 13073.9 1774.5 1813.0 1819.6 1837.3 1888.3 53401.7 55621.2 55753.4 55761.0 55791.7 9212.8 9330.6 9516.4 9558.5 9594.0 12028.5 12055.9 12096.7 12118.5 12124.6 6789.3 6817.8 6839.9 6855.3 6860.9 6430.0 6456.9 6465.3 6494.2 6509.7 5607.5 5671.3 5672.1 5680.4 5715.5 1540.4 1593.4 1610.5 1641.9 1719.6 831.7 884.0 968.5 969.9 995.3 16163.5 16216.0 16274.2 16812.2 16896.7 784.1 860.0 870.4 883.4 966.7 8985.8 9215.3 9508.0 9636.2 9837.5 490335.0 496717.0 500928.0 507847.0 509150.0 70991.0 71373.0 73176.0 73691.0 74269.6 1022.0 1031.4 1038.3 1063.5 1065.3 14587.3 14589.3 14719.1 14922.3 15260.8 33178.5 33820.0 34363.5 34449.8 35006.6 44597.8 45204.5 46026.5 47657.0 48017.8 8563.0 9010.4 9079.5 9085.1 9110.2 10465.2 10521.9 12468.8 15519.8 16169.1 10846.5 11210.5 13531.9 13781.2 13867.4 13417.0 13699.4 13709.5 14028.8 14047.7 14202.7 14228.4 14368.6 14506.7 14780.4 8538.2 8992.2 8996.2 9043.8 9270.5 11319.9 13497.8 13583.7 13864.5 13875.6 17:00:11 INFO - 2015-12-15 17:00:11,892 DEBUG : BROWSER_OUTPUT: 17:00:11 INFO - 2015-12-15 17:00:11,894 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4950.91 Median:5970.87 stddev:2565.13 (43.0%) stddev-sans-first:2558.76 17:00:11 INFO - 2015-12-15 17:00:11,894 DEBUG : BROWSER_OUTPUT: Values: 1440.7 1607.6 1685.5 1703.7 1717.3 5745.2 6739.6 7185.0 7209.1 7219.3 7049.4 7198.5 7237.0 7266.8 7282.8 2063.4 2102.5 2107.5 2109.2 2109.9 2086.8 2106.5 2130.0 2152.6 2154.5 2042.6 2071.2 2072.0 2094.8 2099.3 2031.3 2052.7 2053.8 2054.3 2055.4 2066.1 2074.8 2119.3 2121.2 2124.9 7009.3 7021.8 7039.9 7057.0 7066.6 4034.5 4072.1 4099.0 4102.0 4103.6 2637.7 2672.8 2684.0 2690.8 2701.6 830.3 832.7 847.1 848.2 856.2 9210.8 9223.5 9241.9 9251.6 9253.6 3762.2 3763.9 3823.8 3871.7 3874.4 7996.4 8036.5 8048.4 8048.8 8061.6 4112.3 4121.3 4131.2 4156.0 4158.6 3214.4 3241.5 3708.6 3755.6 3795.8 3399.4 3452.0 3482.1 3484.0 3501.4 1105.3 1116.2 1123.5 1128.4 1134.0 864.8 865.0 865.6 879.0 992.6 6612.9 6781.8 6795.2 6822.4 6850.2 1042.0 1045.4 1062.4 1067.3 1078.3 5401.5 5462.9 5464.7 5471.5 5477.3 8392.2 8449.4 8700.6 8726.9 8757.3 8581.5 8628.6 8639.8 8685.4 8685.7 6660.0 6767.5 6783.9 6825.9 6843.0 6614.4 6656.3 6663.3 6675.6 6680.3 7598.5 7620.0 7764.0 7844.2 7898.3 7874.0 7968.5 8092.0 8104.1 8117.4 5044.6 5156.2 5163.7 5905.3 5998.7 5725.4 6858.5 6873.8 6966.2 6972.8 6410.0 6622.5 6697.5 6750.3 6765.5 6364.8 6450.3 6460.3 6482.4 6542.5 6505.5 6528.6 6557.6 6563.2 6583.1 5754.0 5876.6 5892.1 5911.4 5943.0 5778.8 5813.1 5821.9 5835.1 5845.6 17:00:11 INFO - 2015-12-15 17:00:11,894 DEBUG : BROWSER_OUTPUT: 17:00:11 INFO - 2015-12-15 17:00:11,894 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4672.41 Median:5334.87 stddev:1382.26 (25.9%) stddev-sans-first:1346.37 17:00:11 INFO - 2015-12-15 17:00:11,894 DEBUG : BROWSER_OUTPUT: Values: 286.7 293.6 297.0 299.5 300.9 5284.2 5354.2 5360.4 5403.4 5421.6 5330.4 5332.3 5333.0 5333.2 5336.5 4095.6 4120.1 4127.5 4134.3 4142.5 3882.0 3999.2 4035.0 4139.3 4142.5 4086.8 4090.5 4098.8 4108.8 4109.8 3875.6 3956.8 4097.2 4098.2 4102.0 3535.3 3579.8 3593.8 3605.4 3617.1 5136.5 5138.0 5142.4 5147.8 5156.6 4739.2 4740.9 4743.0 4744.8 4749.5 5408.5 5600.0 5602.9 5604.4 5605.4 3958.1 3959.1 3959.6 3967.8 3971.0 5886.8 5894.6 5896.7 5903.2 5904.1 4083.3 4085.1 4089.8 4091.7 4093.8 4032.6 4141.4 4144.3 4150.6 4156.0 4661.0 4661.3 4665.1 4666.0 4673.7 5595.6 5598.0 5598.5 5601.1 5601.1 1422.4 1459.0 1459.6 1460.4 1462.3 973.6 991.6 993.0 995.9 1016.1 4959.7 4966.1 4973.4 4977.1 4978.7 5833.2 5840.5 5847.7 5848.5 5851.0 4965.2 4968.2 4970.1 4976.1 4977.0 3021.7 3077.1 3117.5 3138.9 3152.8 5463.9 5479.7 5613.7 5615.5 5616.5 6047.4 6073.3 6073.5 6073.8 6080.1 5567.2 5586.9 5592.4 5601.4 5610.5 5756.5 5885.8 5897.3 5905.3 5907.0 5754.5 5758.7 5759.8 5765.4 5768.0 5598.9 5604.8 5606.5 5606.8 5612.7 3850.7 3943.8 3988.5 3989.4 3990.8 5784.5 5839.9 5854.3 5854.7 5859.7 5625.0 5634.2 5636.7 5638.7 5643.2 5624.6 5631.7 5636.8 5638.2 5642.3 5396.2 5501.8 5515.9 5518.1 5524.8 5656.1 5661.0 5671.9 5674.3 5677.3 5530.8 5534.2 5534.3 5539.4 5540.0 17:00:11 INFO - 2015-12-15 17:00:11,894 DEBUG : BROWSER_OUTPUT: 17:00:11 INFO - 2015-12-15 17:00:11,894 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12142.92 Median:462.07 stddev:13993.02 (3028.3%) stddev-sans-first:14005.85 17:00:11 INFO - 2015-12-15 17:00:11,896 DEBUG : BROWSER_OUTPUT: Values: 691.5 733.6 741.6 763.4 766.4 10840.5 11289.5 11299.3 11346.0 11366.4 11530.1 11625.2 11626.5 11636.9 11650.8 614.3 621.4 775.8 783.6 801.5 777.6 779.6 781.8 786.2 786.7 640.9 818.8 827.1 828.0 828.7 640.5 800.5 816.2 817.9 818.9 593.3 602.5 603.3 605.1 605.2 10492.3 10774.6 10796.2 10803.6 10848.5 10744.3 11315.4 11318.8 11345.8 11355.8 9429.2 9615.5 9649.8 9805.2 9828.9 3249.8 3256.3 3293.8 3300.9 3361.8 39345.8 39539.8 39552.6 40536.0 40698.6 8516.5 8562.2 8567.7 8717.9 8807.2 15601.7 15719.9 15725.5 15750.9 15752.8 6326.8 6361.4 6426.8 6561.0 6728.5 3343.0 3377.5 3432.8 3450.4 3468.2 2601.8 2636.7 2641.1 2670.1 2671.9 455.2 455.2 458.4 460.2 463.9 458.0 499.7 509.0 552.3 600.2 7312.7 7342.6 7570.2 7628.3 7714.2 466.0 552.4 582.2 583.5 583.7 3449.3 3460.4 3536.2 3568.9 3629.8 46770.5 52773.2 52878.2 52949.2 53354.0 43593.0 44701.9 44803.8 45309.0 45400.5 40012.6 41157.8 41832.6 41913.2 42535.4 7569.8 8312.2 8472.8 8592.9 8638.8 31034.0 31083.0 31434.2 32034.2 32347.8 35861.0 36185.6 39930.2 40438.6 40862.8 9647.0 9795.7 9823.3 9857.6 10084.5 10662.0 10948.9 11473.9 11540.9 11790.5 10000.7 10099.9 10127.1 10132.8 10539.3 10094.2 10406.2 10439.5 10460.8 10557.4 9014.7 9054.5 9059.6 9271.4 9283.5 9122.9 9205.8 9296.4 9325.4 9407.7 8685.5 8911.2 8950.0 8972.6 9064.2 17:00:11 INFO - 2015-12-15 17:00:11,896 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 17:00:11 INFO - 2015-12-15 17:00:11,896 DEBUG : BROWSER_OUTPUT: 17:00:12 INFO - 2015-12-15 17:00:12,648 INFO : Browser exited with error code: 0 17:00:12 INFO - 2015-12-15 17:00:12,663 INFO : Completed test dromaeo_css (00:18:37) 17:00:12 INFO - 2015-12-15 17:00:12,663 INFO : Starting test kraken 17:00:12 INFO - 2015-12-15 17:00:12,663 DEBUG : operating with platform_type : linux_ 17:00:12 INFO - 2015-12-15 17:00:12,663 INFO : Initialising browser for kraken test... 17:00:12 INFO - 2015-12-15 17:00:12,670 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmheVFf/profile http://localhost:60378/getInfo.html 17:00:16 INFO - 2015-12-15 17:00:16,788 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 17:00:16 INFO - 2015-12-15 17:00:16,788 DEBUG : BROWSER_OUTPUT: colorDepth:24 17:00:16 INFO - 2015-12-15 17:00:16,788 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 17:00:16 INFO - 2015-12-15 17:00:16,788 DEBUG : BROWSER_OUTPUT: __metrics 17:00:16 INFO - 2015-12-15 17:00:16,897 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 17:00:16 INFO - 2015-12-15 17:00:16,958 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:00:18 INFO - 2015-12-15 17:00:18,139 INFO : Browser initialized. 17:00:18 INFO - 2015-12-15 17:00:18,139 INFO : Running cycle 1/1 for kraken test... 17:00:18 INFO - 2015-12-15 17:00:18,140 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmheVFf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 17:00:30 INFO - 2015-12-15 17:00:30,084 DEBUG : BROWSER_OUTPUT: RSS: Main: 142856192 17:00:30 INFO - 2015-12-15 17:00:30,085 DEBUG : BROWSER_OUTPUT: 17:00:32 INFO - 2015-12-15 17:00:32,680 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60378/tests/kraken/driver.html?testName=audio-beat-detection) 17:00:33 INFO - 2015-12-15 17:00:33,270 DEBUG : BROWSER_OUTPUT: RSS: Main: 179896320 17:00:33 INFO - 2015-12-15 17:00:33,270 DEBUG : BROWSER_OUTPUT: 17:00:36 INFO - 2015-12-15 17:00:36,014 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60378/tests/kraken/driver.html?testName=audio-dft) 17:00:36 INFO - 2015-12-15 17:00:36,588 DEBUG : BROWSER_OUTPUT: RSS: Main: 169537536 17:00:36 INFO - 2015-12-15 17:00:36,588 DEBUG : BROWSER_OUTPUT: 17:00:39 INFO - 2015-12-15 17:00:39,606 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60378/tests/kraken/driver.html?testName=audio-fft) 17:00:40 INFO - 2015-12-15 17:00:40,167 DEBUG : BROWSER_OUTPUT: RSS: Main: 153042944 17:00:40 INFO - 2015-12-15 17:00:40,167 DEBUG : BROWSER_OUTPUT: 17:00:42 INFO - 2015-12-15 17:00:42,220 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60378/tests/kraken/driver.html?testName=audio-oscillator) 17:00:42 INFO - 2015-12-15 17:00:42,786 DEBUG : BROWSER_OUTPUT: RSS: Main: 156172288 17:00:42 INFO - 2015-12-15 17:00:42,787 DEBUG : BROWSER_OUTPUT: 17:00:45 INFO - 2015-12-15 17:00:45,053 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60378/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:00:45 INFO - 2015-12-15 17:00:45,619 DEBUG : BROWSER_OUTPUT: RSS: Main: 158834688 17:00:45 INFO - 2015-12-15 17:00:45,619 DEBUG : BROWSER_OUTPUT: 17:00:48 INFO - 2015-12-15 17:00:48,617 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60378/tests/kraken/driver.html?testName=imaging-darkroom) 17:00:49 INFO - 2015-12-15 17:00:49,198 DEBUG : BROWSER_OUTPUT: RSS: Main: 157958144 17:00:49 INFO - 2015-12-15 17:00:49,199 DEBUG : BROWSER_OUTPUT: 17:00:52 INFO - 2015-12-15 17:00:52,623 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60378/tests/kraken/driver.html?testName=imaging-desaturate) 17:00:53 INFO - 2015-12-15 17:00:53,196 DEBUG : BROWSER_OUTPUT: RSS: Main: 157655040 17:00:53 INFO - 2015-12-15 17:00:53,196 DEBUG : BROWSER_OUTPUT: 17:00:56 INFO - 2015-12-15 17:00:56,066 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60378/tests/kraken/driver.html?testName=json-parse-financial) 17:00:56 INFO - 2015-12-15 17:00:56,647 DEBUG : BROWSER_OUTPUT: RSS: Main: 159207424 17:00:56 INFO - 2015-12-15 17:00:56,647 DEBUG : BROWSER_OUTPUT: 17:00:58 INFO - 2015-12-15 17:00:58,381 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60378/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:00:58 INFO - 2015-12-15 17:00:58,947 DEBUG : BROWSER_OUTPUT: RSS: Main: 166883328 17:00:58 INFO - 2015-12-15 17:00:58,947 DEBUG : BROWSER_OUTPUT: 17:01:00 INFO - 2015-12-15 17:01:00,837 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60378/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:01:01 INFO - 2015-12-15 17:01:01,444 DEBUG : BROWSER_OUTPUT: RSS: Main: 166764544 17:01:01 INFO - 2015-12-15 17:01:01,444 DEBUG : BROWSER_OUTPUT: 17:01:03 INFO - 2015-12-15 17:01:03,568 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60378/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:01:04 INFO - 2015-12-15 17:01:04,149 DEBUG : BROWSER_OUTPUT: RSS: Main: 175730688 17:01:04 INFO - 2015-12-15 17:01:04,150 DEBUG : BROWSER_OUTPUT: 17:01:06 INFO - 2015-12-15 17:01:06,705 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60378/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:01:07 INFO - 2015-12-15 17:01:07,291 DEBUG : BROWSER_OUTPUT: RSS: Main: 183160832 17:01:07 INFO - 2015-12-15 17:01:07,291 DEBUG : BROWSER_OUTPUT: 17:01:09 INFO - 2015-12-15 17:01:09,867 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60378/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:01:10 INFO - 2015-12-15 17:01:10,440 DEBUG : BROWSER_OUTPUT: RSS: Main: 180531200 17:01:10 INFO - 2015-12-15 17:01:10,440 DEBUG : BROWSER_OUTPUT: 17:01:12 INFO - 2015-12-15 17:01:12,194 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60378/tests/kraken/driver.html?testName=ai-astar) 17:01:12 INFO - 2015-12-15 17:01:12,444 DEBUG : BROWSER_OUTPUT: RSS: Main: 238915584 17:01:12 INFO - 2015-12-15 17:01:12,445 DEBUG : BROWSER_OUTPUT: 17:01:12 INFO - 2015-12-15 17:01:12,446 DEBUG : BROWSER_OUTPUT: __start_tp_report 17:01:12 INFO - 2015-12-15 17:01:12,446 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 17:01:12 INFO - 2015-12-15 17:01:12,447 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 17:01:12 INFO - 2015-12-15 17:01:12,447 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 17:01:12 INFO - 2015-12-15 17:01:12,447 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;114;107;112;112;112;102;112;111;112 17:01:12 INFO - 2015-12-15 17:01:12,447 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;156;128;156;168;131;159;148;131;167;138 17:01:12 INFO - 2015-12-15 17:01:12,448 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;160;161;160;159;161;164;161;162;162 17:01:12 INFO - 2015-12-15 17:01:12,448 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;89;92;72;78;71;113;90;72 17:01:12 INFO - 2015-12-15 17:01:12,448 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;96;96;131;96;95;95;130;96;95;132 17:01:12 INFO - 2015-12-15 17:01:12,448 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;110;110;110;109;109;110;109 17:01:12 INFO - 2015-12-15 17:01:12,448 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;147;148;146;146;147;147;147;147;147 17:01:12 INFO - 2015-12-15 17:01:12,449 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;95;95;97;95;95;95;95;97 17:01:12 INFO - 2015-12-15 17:01:12,449 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;70;70;69;69;69;70;69;79;68;68 17:01:12 INFO - 2015-12-15 17:01:12,449 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;67;69;69;68;68;69;68;70;68;69 17:01:12 INFO - 2015-12-15 17:01:12,449 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;81;83;84;83;83;83;83;82;85;84 17:01:12 INFO - 2015-12-15 17:01:12,449 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;128;122;120;122;126;119;128;125;134;124 17:01:12 INFO - 2015-12-15 17:01:12,450 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;145;158;133;153;135;146;144;145;133 17:01:12 INFO - 2015-12-15 17:01:12,450 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;69;68;68;68;68;67;67;70;71;69 17:01:12 INFO - 2015-12-15 17:01:12,450 DEBUG : BROWSER_OUTPUT: __end_tp_report 17:01:12 INFO - 2015-12-15 17:01:12,450 DEBUG : BROWSER_OUTPUT: __start_cc_report 17:01:12 INFO - 2015-12-15 17:01:12,450 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,984 17:01:12 INFO - 2015-12-15 17:01:12,450 DEBUG : BROWSER_OUTPUT: __end_cc_report 17:01:12 INFO - 2015-12-15 17:01:12,450 DEBUG : BROWSER_OUTPUT: __startTimestamp1450227672444__endTimestamp 17:01:12 INFO - 2015-12-15 17:01:12,450 DEBUG : BROWSER_OUTPUT: 17:01:12 INFO - 2015-12-15 17:01:12,452 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 17:01:12 INFO - 2015-12-15 17:01:12,452 DEBUG : BROWSER_OUTPUT: Number of tests: 14 17:01:12 INFO - 2015-12-15 17:01:12,452 DEBUG : BROWSER_OUTPUT: 17:01:12 INFO - 2015-12-15 17:01:12,452 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:110.50 Median:112.00 stddev:3.47 (3.1%) stddev-sans-first:3.68 17:01:12 INFO - 2015-12-15 17:01:12,452 DEBUG : BROWSER_OUTPUT: Values: 111.0 114.0 107.0 112.0 112.0 112.0 102.0 112.0 111.0 112.0 17:01:12 INFO - 2015-12-15 17:01:12,452 DEBUG : BROWSER_OUTPUT: 17:01:12 INFO - 2015-12-15 17:01:12,452 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:148.20 Median:156.00 stddev:15.23 (9.8%) stddev-sans-first:15.89 17:01:12 INFO - 2015-12-15 17:01:12,453 DEBUG : BROWSER_OUTPUT: Values: 156.0 128.0 156.0 168.0 131.0 159.0 148.0 131.0 167.0 138.0 17:01:12 INFO - 2015-12-15 17:01:12,453 DEBUG : BROWSER_OUTPUT: 17:01:12 INFO - 2015-12-15 17:01:12,453 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:1.40 (0.9%) stddev-sans-first:1.45 17:01:12 INFO - 2015-12-15 17:01:12,453 DEBUG : BROWSER_OUTPUT: Values: 162.0 160.0 161.0 160.0 159.0 161.0 164.0 161.0 162.0 162.0 17:01:12 INFO - 2015-12-15 17:01:12,453 DEBUG : BROWSER_OUTPUT: 17:01:12 INFO - 2015-12-15 17:01:12,453 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:82.00 Median:75.00 stddev:13.87 (18.5%) stddev-sans-first:14.23 17:01:12 INFO - 2015-12-15 17:01:12,453 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 89.0 92.0 72.0 78.0 71.0 113.0 90.0 72.0 17:01:12 INFO - 2015-12-15 17:01:12,454 DEBUG : BROWSER_OUTPUT: 17:01:12 INFO - 2015-12-15 17:01:12,454 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.20 Median:95.50 stddev:17.13 (17.9%) stddev-sans-first:17.76 17:01:12 INFO - 2015-12-15 17:01:12,454 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 131.0 96.0 95.0 95.0 130.0 96.0 95.0 132.0 17:01:12 INFO - 2015-12-15 17:01:12,454 DEBUG : BROWSER_OUTPUT: 17:01:12 INFO - 2015-12-15 17:01:12,454 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.70 Median:110.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 17:01:12 INFO - 2015-12-15 17:01:12,454 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 110.0 110.0 110.0 109.0 109.0 110.0 109.0 17:01:12 INFO - 2015-12-15 17:01:12,454 DEBUG : BROWSER_OUTPUT: 17:01:12 INFO - 2015-12-15 17:01:12,454 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.60 17:01:12 INFO - 2015-12-15 17:01:12,455 DEBUG : BROWSER_OUTPUT: Values: 148.0 147.0 148.0 146.0 146.0 147.0 147.0 147.0 147.0 147.0 17:01:12 INFO - 2015-12-15 17:01:12,455 DEBUG : BROWSER_OUTPUT: 17:01:12 INFO - 2015-12-15 17:01:12,455 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.60 Median:95.50 stddev:0.84 (0.9%) stddev-sans-first:0.88 17:01:12 INFO - 2015-12-15 17:01:12,455 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 95.0 95.0 97.0 95.0 95.0 95.0 95.0 97.0 17:01:12 INFO - 2015-12-15 17:01:12,456 DEBUG : BROWSER_OUTPUT: 17:01:12 INFO - 2015-12-15 17:01:12,456 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:70.10 Median:69.50 stddev:3.21 (4.6%) stddev-sans-first:3.41 17:01:12 INFO - 2015-12-15 17:01:12,456 DEBUG : BROWSER_OUTPUT: Values: 70.0 70.0 69.0 69.0 69.0 70.0 69.0 79.0 68.0 68.0 17:01:12 INFO - 2015-12-15 17:01:12,456 DEBUG : BROWSER_OUTPUT: 17:01:12 INFO - 2015-12-15 17:01:12,456 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.50 Median:69.00 stddev:0.85 (1.2%) stddev-sans-first:0.71 17:01:12 INFO - 2015-12-15 17:01:12,456 DEBUG : BROWSER_OUTPUT: Values: 67.0 69.0 69.0 68.0 68.0 69.0 68.0 70.0 68.0 69.0 17:01:12 INFO - 2015-12-15 17:01:12,456 DEBUG : BROWSER_OUTPUT: 17:01:12 INFO - 2015-12-15 17:01:12,456 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:83.00 stddev:1.10 (1.3%) stddev-sans-first:0.87 17:01:12 INFO - 2015-12-15 17:01:12,457 DEBUG : BROWSER_OUTPUT: Values: 81.0 83.0 84.0 83.0 83.0 83.0 83.0 82.0 85.0 84.0 17:01:12 INFO - 2015-12-15 17:01:12,457 DEBUG : BROWSER_OUTPUT: 17:01:12 INFO - 2015-12-15 17:01:12,457 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:124.80 Median:125.50 stddev:4.47 (3.6%) stddev-sans-first:4.59 17:01:12 INFO - 2015-12-15 17:01:12,457 DEBUG : BROWSER_OUTPUT: Values: 128.0 122.0 120.0 122.0 126.0 119.0 128.0 125.0 134.0 124.0 17:01:12 INFO - 2015-12-15 17:01:12,457 DEBUG : BROWSER_OUTPUT: 17:01:12 INFO - 2015-12-15 17:01:12,457 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.10 Median:145.00 stddev:8.32 (5.7%) stddev-sans-first:8.69 17:01:12 INFO - 2015-12-15 17:01:12,457 DEBUG : BROWSER_OUTPUT: Values: 139.0 145.0 158.0 133.0 153.0 135.0 146.0 144.0 145.0 133.0 17:01:12 INFO - 2015-12-15 17:01:12,458 DEBUG : BROWSER_OUTPUT: 17:01:12 INFO - 2015-12-15 17:01:12,458 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.50 stddev:1.27 (1.9%) stddev-sans-first:1.33 17:01:12 INFO - 2015-12-15 17:01:12,458 DEBUG : BROWSER_OUTPUT: Values: 69.0 68.0 68.0 68.0 68.0 67.0 67.0 70.0 71.0 69.0 17:01:12 INFO - 2015-12-15 17:01:12,458 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 17:01:12 INFO - 2015-12-15 17:01:12,458 DEBUG : BROWSER_OUTPUT: 17:01:13 INFO - 2015-12-15 17:01:13,370 INFO : Browser exited with error code: 0 17:01:13 INFO - 2015-12-15 17:01:13,423 INFO : Completed test kraken (00:01:00) 17:01:13 INFO - 2015-12-15 17:01:13,423 INFO : Starting test v8_7 17:01:13 INFO - 2015-12-15 17:01:13,423 DEBUG : operating with platform_type : linux_ 17:01:13 INFO - 2015-12-15 17:01:13,423 INFO : Initialising browser for v8_7 test... 17:01:13 INFO - 2015-12-15 17:01:13,429 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJBfR7j/profile http://localhost:60378/getInfo.html 17:01:17 INFO - 2015-12-15 17:01:17,632 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 17:01:17 INFO - 2015-12-15 17:01:17,632 DEBUG : BROWSER_OUTPUT: colorDepth:24 17:01:17 INFO - 2015-12-15 17:01:17,632 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 17:01:17 INFO - 2015-12-15 17:01:17,633 DEBUG : BROWSER_OUTPUT: __metrics 17:01:17 INFO - 2015-12-15 17:01:17,790 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 17:01:17 INFO - 2015-12-15 17:01:17,846 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:01:19 INFO - 2015-12-15 17:01:18,999 INFO : Browser initialized. 17:01:19 INFO - 2015-12-15 17:01:19,000 INFO : Running cycle 1/1 for v8_7 test... 17:01:19 INFO - 2015-12-15 17:01:19,000 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJBfR7j/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 17:01:30 INFO - 2015-12-15 17:01:30,940 DEBUG : BROWSER_OUTPUT: RSS: Main: 142266368 17:01:30 INFO - 2015-12-15 17:01:30,941 DEBUG : BROWSER_OUTPUT: 17:01:52 INFO - 2015-12-15 17:01:52,318 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60378/tests/v8_7/run.html (next: http://localhost:60378/tests/v8_7/run.html) 17:01:52 INFO - 2015-12-15 17:01:52,568 DEBUG : BROWSER_OUTPUT: RSS: Main: 444907520 17:01:52 INFO - 2015-12-15 17:01:52,568 DEBUG : BROWSER_OUTPUT: 17:01:52 INFO - 2015-12-15 17:01:52,568 DEBUG : BROWSER_OUTPUT: __start_tp_report 17:01:52 INFO - 2015-12-15 17:01:52,568 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 17:01:52 INFO - 2015-12-15 17:01:52,568 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 17:01:52 INFO - 2015-12-15 17:01:52,568 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 17:01:52 INFO - 2015-12-15 17:01:52,568 DEBUG : BROWSER_OUTPUT: |0;Richards;167.9261125104954 17:01:52 INFO - 2015-12-15 17:01:52,568 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;186.81113394358303 17:01:52 INFO - 2015-12-15 17:01:52,568 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.2516056967328 17:01:52 INFO - 2015-12-15 17:01:52,568 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5196.891191709845 17:01:52 INFO - 2015-12-15 17:01:52,569 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1982.1782178217823 17:01:52 INFO - 2015-12-15 17:01:52,569 DEBUG : BROWSER_OUTPUT: |5;Earley;795.5449482895783 17:01:52 INFO - 2015-12-15 17:01:52,569 DEBUG : BROWSER_OUTPUT: |6;Boyer;12550 17:01:52 INFO - 2015-12-15 17:01:52,569 DEBUG : BROWSER_OUTPUT: |7;RegExp;27216.216216216217 17:01:52 INFO - 2015-12-15 17:01:52,569 DEBUG : BROWSER_OUTPUT: |8;Splay;613.8735420503376 17:01:52 INFO - 2015-12-15 17:01:52,569 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 17:01:52 INFO - 2015-12-15 17:01:52,569 DEBUG : BROWSER_OUTPUT: __end_tp_report 17:01:52 INFO - 2015-12-15 17:01:52,569 DEBUG : BROWSER_OUTPUT: __startTimestamp1450227712568__endTimestamp 17:01:52 INFO - 2015-12-15 17:01:52,569 DEBUG : BROWSER_OUTPUT: 17:01:52 INFO - 2015-12-15 17:01:52,569 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 17:01:52 INFO - 2015-12-15 17:01:52,570 DEBUG : BROWSER_OUTPUT: Number of tests: 10 17:01:52 INFO - 2015-12-15 17:01:52,571 DEBUG : BROWSER_OUTPUT: 17:01:52 INFO - 2015-12-15 17:01:52,571 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.93 Median:167.93 stddev:0.00 (0.0%) 17:01:52 INFO - 2015-12-15 17:01:52,571 DEBUG : BROWSER_OUTPUT: Values: 167.9 17:01:52 INFO - 2015-12-15 17:01:52,571 DEBUG : BROWSER_OUTPUT: 17:01:52 INFO - 2015-12-15 17:01:52,571 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:186.81 Median:186.81 stddev:0.00 (0.0%) 17:01:52 INFO - 2015-12-15 17:01:52,571 DEBUG : BROWSER_OUTPUT: Values: 186.8 17:01:52 INFO - 2015-12-15 17:01:52,571 DEBUG : BROWSER_OUTPUT: 17:01:52 INFO - 2015-12-15 17:01:52,572 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.25 Median:279.25 stddev:0.00 (0.0%) 17:01:52 INFO - 2015-12-15 17:01:52,572 DEBUG : BROWSER_OUTPUT: Values: 279.3 17:01:52 INFO - 2015-12-15 17:01:52,572 DEBUG : BROWSER_OUTPUT: 17:01:52 INFO - 2015-12-15 17:01:52,572 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5196.89 Median:5196.89 stddev:0.00 (0.0%) 17:01:52 INFO - 2015-12-15 17:01:52,572 DEBUG : BROWSER_OUTPUT: Values: 5196.9 17:01:52 INFO - 2015-12-15 17:01:52,572 DEBUG : BROWSER_OUTPUT: 17:01:52 INFO - 2015-12-15 17:01:52,572 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1982.18 Median:1982.18 stddev:0.00 (0.0%) 17:01:52 INFO - 2015-12-15 17:01:52,573 DEBUG : BROWSER_OUTPUT: Values: 1982.2 17:01:52 INFO - 2015-12-15 17:01:52,573 DEBUG : BROWSER_OUTPUT: 17:01:52 INFO - 2015-12-15 17:01:52,573 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:795.54 Median:795.54 stddev:0.00 (0.0%) 17:01:52 INFO - 2015-12-15 17:01:52,573 DEBUG : BROWSER_OUTPUT: Values: 795.5 17:01:52 INFO - 2015-12-15 17:01:52,573 DEBUG : BROWSER_OUTPUT: 17:01:52 INFO - 2015-12-15 17:01:52,573 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12550.00 Median:12550.00 stddev:0.00 (0.0%) 17:01:52 INFO - 2015-12-15 17:01:52,573 DEBUG : BROWSER_OUTPUT: Values: 12550.0 17:01:52 INFO - 2015-12-15 17:01:52,573 DEBUG : BROWSER_OUTPUT: 17:01:52 INFO - 2015-12-15 17:01:52,574 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:27216.22 Median:27216.22 stddev:0.00 (0.0%) 17:01:52 INFO - 2015-12-15 17:01:52,574 DEBUG : BROWSER_OUTPUT: Values: 27216.2 17:01:52 INFO - 2015-12-15 17:01:52,574 DEBUG : BROWSER_OUTPUT: 17:01:52 INFO - 2015-12-15 17:01:52,574 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:613.87 Median:613.87 stddev:0.00 (0.0%) 17:01:52 INFO - 2015-12-15 17:01:52,574 DEBUG : BROWSER_OUTPUT: Values: 613.9 17:01:52 INFO - 2015-12-15 17:01:52,574 DEBUG : BROWSER_OUTPUT: 17:01:52 INFO - 2015-12-15 17:01:52,574 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 17:01:52 INFO - 2015-12-15 17:01:52,575 DEBUG : BROWSER_OUTPUT: Values: 4835.7 17:01:52 INFO - 2015-12-15 17:01:52,575 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 17:01:52 INFO - 2015-12-15 17:01:52,575 DEBUG : BROWSER_OUTPUT: 17:01:53 INFO - 2015-12-15 17:01:53,514 INFO : Browser exited with error code: 0 17:01:53 INFO - 2015-12-15 17:01:53,528 INFO : Completed test v8_7 (00:00:40) 17:01:53 INFO - 2015-12-15 17:01:53,639 INFO : Completed test suite (00:20:18) 17:01:53 INFO - 2015-12-15 17:01:53,639 DEBUG : Working with test: dromaeo_css 17:01:53 INFO - 2015-12-15 17:01:53,639 DEBUG : Generating results file: dromaeo_css 17:01:53 INFO - 2015-12-15 17:01:53,640 DEBUG : Working with test: kraken 17:01:53 INFO - 2015-12-15 17:01:53,640 DEBUG : Generating results file: kraken 17:01:53 INFO - 2015-12-15 17:01:53,640 INFO : javascript benchmark 17:01:53 INFO - 2015-12-15 17:01:53,641 DEBUG : Working with test: v8_7 17:01:53 INFO - 2015-12-15 17:01:53,641 DEBUG : Generating results file: v8_7 17:01:53 INFO - 2015-12-15 17:01:53,641 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:01:53 INFO - 2015-12-15 17:01:53,698 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,94,35]] 17:01:53 INFO - 2015-12-15 17:01:53,698 DEBUG : process_Request line: dromaeo_css 6316.04 graph.html#tests=[[72,94,35]] 17:01:53 INFO - 2015-12-15 17:01:53,698 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:01:53 INFO - 2015-12-15 17:01:53,729 DEBUG : process_Request line: kraken 1518.50 graph.html#tests=[[232,94,35]] 17:01:53 INFO - 2015-12-15 17:01:53,729 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:01:53 INFO - 2015-12-15 17:01:53,762 DEBUG : process_Request line: v8_7 19645.14 graph.html#tests=[[230,94,35]] 17:01:53 INFO - 2015-12-15 17:01:53,764 INFO : javascript benchmark 17:01:53 INFO - 2015-12-15 17:01:53,766 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2648.24525010372, 2729.590561415786, 2733.986330068349, 2735.781137508998, 2753.669559652842, 4626.514216007321, 4762.214234651287, 4820.301056346832, 4847.175980083381, 4857.538533839289, 4745.57289843914, 4821.638377121712, 4823.662343635938, 4827.662063655545, 4989.077020751154, 4428.247197976351, 4439.1787519308955, 4444.577765112309, 4539.45526536816, 4581.748003859786, 4506.909861802762, 4513.14250292445, 4582.656300777438, 4588.7246765194195, 4622.422197225347, 4277.12318974611, 4341.435613370273, 4372.781360931973, 4459.264221403465, 4464.977675111636, 4437.33439984005, 4478.529754375776, 4478.552144785528, 4481.686281960264, 4530.07133537624, 4445.444319460067, 4451.443569553806, 4452.309891966718, 4473.686841921067, 4499.212637788374, 4597.60920321773, 4654.790534425959, 4723.433188017426, 4723.7874295656775, 4727.550882666142, 5217.921731174036, 5222.451642577551, 5224.0, 5227.294315267466, 5231.816886408996, 5218.9739051304505, 5240.659357141773, 5247.449017853109, 5247.553957913582, 5295.947040529622, 4572.1312950539295, 4673.322368256584, 4685.109829132454, 4849.0, 4856.17445034345, 5269.789208431696, 5340.732963351817, 5347.598930080259, 5366.409694933554, 5415.970965516539, 5100.38795344561, 5107.719075450886, 5109.6678715883345, 5114.053900028507, 5114.71869047206, 4919.237518184687, 4991.850244492671, 5036.7733451994745, 5218.269442278084, 5265.710385928811, 5032.72320022395, 5106.259592359087, 5113.923291150636, 5123.180291153398, 5161.767720452589, 5091.439941606421, 5113.53978141969, 5114.15616423286, 5115.258287548285, 5129.512696293846, 4073.7555746655294, 4164.187983343219, 4172.70791044624, 4289.699721019561, 4328.11273688893, 3661.1213308805695, 3712.480252764615, 3737.177820879402, 3747.081964918558, 3764.228333191744, 4446.644268458515, 4464.799084041226, 4469.463664360298, 4476.0, 4514.0, 4989.950100498949, 5119.8720031998755, 5183.922241166386, 5194.662346947436, 5197.818076367309, 4352.542982986741, 4386.8025938832825, 4406.25093734066, 4420.270655341833, 4456.22016147173, 4578.748168850679, 4581.335706322565, 4583.495815460296, 4610.0, 4622.815087396467, 4716.952830471651, 4834.516548345137, 4941.1847045237855, 4941.357623509065, 4941.530554597308, 5022.0, 5065.3161823153405, 5119.74401279942, 5136.512031357015, 5174.404943431554, 4884.78018489176, 4893.608511319015, 4898.314236007033, 4909.950900491092, 4942.258661200849, 4840.080384726891, 4906.411230652345, 5047.823326183713, 5063.594912406925, 5087.949120508895, 5043.142665746906, 5059.949400505948, 5075.974620126875, 5199.142141546602, 5203.271541984201, 5007.998400320023, 5049.823256186016, 5050.924236136388, 5059.645824792229, 5103.030424219386, 5058.4688607697135, 5058.772355244097, 5062.82280120194, 5064.088464076502, 5068.26510156018, 4778.0, 4854.05345957549, 4864.756762161807, 4911.3369695090705, 4967.552920237196, 5089.618278629192, 5097.260897169964, 5102.821401250939, 5119.795208191779, 5131.358580177478, 4994.625403094855, 5002.2496625506465, 5003.299538064746, 5005.199168133081, 5045.2432135180015], "unit": "score", "name": "dojo.html", "value": 4725.58321509368}, {"lowerIsBetter": false, "replicates": [551.6965668882115, 555.813802376204, 565.5221337969417, 569.1718549510463, 579.3771695427412, 33832.830835845816, 34842.651573484254, 34869.651303486986, 34883.4767478488, 34924.12689682759, 37041.81479092607, 37603.059923501925, 37629.81185094077, 37666.43500347497, 37686.0, 6985.930140698579, 7088.893666595005, 7771.106322772869, 8005.759827205193, 9612.567434465465, 9935.155511781508, 10093.343932644355, 10100.090991810735, 10102.74743131424, 10104.04011618895, 10024.047715467055, 10146.64486742964, 10185.134263587604, 10204.132648724868, 10207.846882296772, 7248.275172482762, 7446.553206807609, 10079.244056695778, 10378.221633377527, 10408.115310198642, 9899.90100098997, 10065.194784417228, 10077.294589378746, 10077.748056298651, 10083.949580252125, 34799.826000869834, 35168.47247291293, 35217.29565408703, 35228.823855880815, 35249.0, 3001.6247969003875, 3340.7160391366765, 3696.149885526344, 3783.6027217142077, 3784.3188226119287, 27285.727142728516, 27390.452190956068, 27439.45121097567, 27676.723232767617, 28021.85989070062, 2977.8511074446405, 3013.758899288052, 3015.793682526985, 3017.6982301769867, 3186.203449137716, 207019.0, 207523.0, 208510.0, 209246.0, 209936.0, 43667.56332436699, 44784.552154478035, 51724.741376293634, 52647.0, 52741.736291318295, 45640.0, 45908.0, 45941.5405845944, 45975.0, 46055.0, 12864.356782160854, 13062.54281100157, 13098.017648676387, 13102.475900964046, 13111.082224244212, 9071.954640226757, 9083.182513573678, 9088.136627020422, 9120.0, 9134.360594758304, 17611.55971100707, 17870.731939020927, 17879.37422190217, 17884.105794710213, 17925.910370448324, 6947.0, 7117.25268846774, 7173.354398104091, 7181.425485961111, 7217.530860494051, 731.3783284208487, 808.4623725222779, 871.302957633903, 886.7605746448423, 887.4630848336792, 21765.0, 22190.223342182948, 25165.74834251671, 26299.73700263011, 26463.603045954325, 950.3347656640241, 960.1934375124929, 979.5738853598707, 984.7931934293879, 1023.467796745688, 12226.26642401441, 12342.382880855921, 12366.75266494665, 12368.690782730324, 12370.381480925918, 252898.0, 256237.0, 259451.0, 259464.0, 259479.0, 43208.351874721906, 43305.13389732251, 43595.78202108969, 43623.56376436195, 43811.780941095094, 1228.0359917464748, 1233.3031837012059, 1239.114033466076, 1241.7951038078797, 1253.705379235897, 23328.0, 23921.880390597937, 27012.72987270102, 28623.284417889718, 28652.85673571619, 38235.23529529449, 39353.40969885397, 44339.55660443483, 45648.771756142334, 45709.54290457053, 205457.0, 205733.0, 206737.0, 206912.0, 207514.0, 23581.88209058944, 24278.51442971166, 27679.44641107126, 28086.2978425541, 28102.43795124127, 35589.28821423505, 35970.64029359673, 36486.45270320894, 36855.26289474249, 36965.815170923976, 30902.381952360378, 31151.221219469695, 32892.50661240036, 35911.0, 35933.64066359303, 31441.685583143877, 31569.0, 31576.684233157375, 31656.208594785316, 31871.68128318687, 30659.386812264074, 31450.370992579563, 31471.84264078665, 31642.36715265728, 31691.52462713107, 27583.172504824888, 28233.294167645974, 28532.286692832844, 28663.42673146567, 28675.713242867303, 22738.65892011654, 22978.885105574365, 23037.308880733606, 23567.29298121059, 23645.527089458457], "unit": "score", "name": "ext.html", "value": 16491.378685502405}, {"lowerIsBetter": false, "replicates": [335.0584856553086, 338.2912797688842, 338.8085731561668, 339.6857906436547, 341.3480252717307, 12547.811782823243, 12914.612561623166, 14794.260286985647, 15025.422330655289, 16988.490345289647, 11645.534178632844, 13492.0, 13889.097208681429, 14472.131672099655, 14486.42054317826, 4400.845970391037, 4949.0, 5084.720340381277, 5093.592512598983, 5786.9710651446685, 3801.923961520768, 3909.0, 5698.743556539945, 5842.766289348421, 5893.145493903381, 5800.187973683687, 5803.245578074844, 5809.883802323951, 5831.708414579275, 5834.4165583441745, 5753.971230143843, 5847.590668653196, 5855.502282305966, 5913.142594323842, 5917.4970127539, 5121.103806833789, 5127.769250383742, 5151.4333423323405, 5156.613254005964, 5167.819126330561, 19288.517787055294, 19540.511487212792, 19765.08264873791, 19768.901155494274, 19928.701069483825, 1916.377177417345, 1925.345382569919, 1985.0967809646577, 2096.937091887246, 2215.3353993801793, 12598.937005315007, 12733.0, 12947.48210071596, 13042.608721738363, 13073.86926130736, 1774.5120091974682, 1812.9909350453163, 1819.5724004858848, 1837.3477415517566, 1888.2730148892774, 53401.73299133479, 55621.16568251439, 55753.44246557523, 55761.0, 55791.721041394536, 9212.815743685089, 9330.626774928927, 9516.429789084861, 9558.474283914451, 9594.0, 12028.518859245532, 12055.939720301343, 12096.697582560506, 12118.454669539891, 12124.575639852563, 6789.321067893171, 6817.795466136024, 6839.897401538981, 6855.280195579492, 6860.931390686129, 6430.0, 6456.903146452807, 6465.288818229991, 6494.220693516808, 6509.674516274262, 5607.467290607386, 5671.262735844314, 5672.14917762339, 5680.403557626472, 5715.457031581993, 1540.399244294726, 1593.402474072223, 1610.4604957339423, 1641.8686505079565, 1719.5615118144792, 831.7380025292014, 883.9779005524784, 968.4818622037176, 969.904008470424, 995.3331268050424, 16163.515094547183, 16216.0, 16274.186290685418, 16812.15939203035, 16896.746548801777, 784.125699844669, 859.9656013759504, 870.429252782199, 883.4037025008093, 966.6616684160488, 8985.820283594423, 9215.308851836138, 9507.952460237793, 9636.180924621485, 9837.508124593885, 490335.0, 496717.0, 500928.0, 507847.0, 509150.0, 70991.0, 71373.0, 73176.0, 73691.0, 74269.62865185748, 1021.9795604087876, 1031.417249254184, 1038.2835843268122, 1063.4682658670665, 1065.2756125834353, 14587.34356953961, 14589.343479543233, 14719.099438648553, 14922.253887305375, 15260.771088433905, 33178.5023224647, 33820.0, 34363.4845477323, 34449.827750861084, 35006.64993350034, 44597.77701111474, 45204.547954520036, 46026.53973460357, 47657.0, 48017.75991120022, 8563.0, 9010.414323068979, 9079.500627465288, 9085.13691199335, 9110.180083792491, 10465.162786977176, 10521.894781052397, 12468.750624987268, 15519.84480155184, 16169.110698911672, 10846.45767711627, 11210.49552770111, 13531.93234033863, 13781.173129612256, 13867.375968081258, 13417.0, 13699.383527741475, 13709.520166794115, 14028.78956815669, 14047.719045618825, 14202.715945681235, 14228.430862765374, 14368.568942931728, 14506.709865802835, 14780.408783648534, 8538.231559159705, 8992.190702836777, 8996.235319997611, 9043.773905652411, 9270.490123043297, 11319.886801131883, 13497.797533036815, 13583.660408489866, 13864.514741983981, 13875.583732488041], "unit": "score", "name": "jquery.html", "value": 8494.708616546644}, {"lowerIsBetter": false, "replicates": [1440.6758479342157, 1607.6302450436397, 1685.4775019743881, 1703.684818308613, 1717.2787429279701, 5745.224394706714, 6739.595624262542, 7184.964075179623, 7209.0988626421695, 7219.314165154302, 7049.36555709986, 7198.532095413794, 7237.023001894742, 7266.781996540099, 7282.817929551764, 2063.38098570429, 2102.463871712711, 2107.525806693497, 2109.166879082761, 2109.8734075955417, 2086.7704552499213, 2106.5049713317358, 2129.9893500532476, 2152.5802468518646, 2154.5367745934623, 2042.5710600773855, 2071.1818831561523, 2072.046858445117, 2094.8428867834896, 2099.2652571599892, 2031.309354819361, 2052.6818343156683, 2053.7843526429733, 2054.3323419888447, 2055.393658870629, 2066.101245958013, 2074.8236399906027, 2119.3430036688674, 2121.1515393842433, 2124.851260411772, 7009.299070093001, 7021.754238601676, 7039.929600703978, 7057.0, 7066.57600543969, 4034.536028356747, 4072.1448495815916, 4098.979505102456, 4102.0, 4103.5691252418665, 2637.7230390809073, 2672.772814310786, 2683.9865800670873, 2690.7847372210176, 2701.5947607858784, 830.2735106781566, 832.7460124661976, 847.1274587175158, 848.2281124176969, 856.1652388920814, 9210.81578368429, 9223.53882305882, 9241.907580924171, 9251.583678734472, 9253.583588738524, 3762.172322089136, 3763.8682646107254, 3823.847046118124, 3871.709621778378, 3874.3800991841736, 7996.400269979775, 8036.4776289541, 8048.436609437284, 8048.758537243775, 8061.556614386148, 4112.342025276002, 4121.340585506305, 4131.153113611703, 4156.002559385725, 4158.604932531404, 3214.373197226518, 3241.497567876983, 3708.6291370862696, 3755.6244375562774, 3795.8481660733814, 3399.439092549751, 3451.9827400862837, 3482.1468740158316, 3483.9825800870835, 3501.352249833789, 1105.2815669814524, 1116.1628778416189, 1123.5393488669606, 1128.3963079752293, 1133.9659810205708, 864.7838040489878, 865.0398058155442, 865.5628907401722, 878.9979423457264, 992.593036854886, 6612.933870661328, 6781.796546103625, 6795.1845778506895, 6822.4200942919415, 6850.21222559412, 1041.9739506512399, 1045.4093437207953, 1062.4475272858072, 1067.2742535076068, 1078.320657985464, 5401.459854014645, 5462.890742185134, 5464.726763661801, 5471.480209380103, 5477.315335583052, 8392.16078392156, 8449.408541402043, 8700.564971751388, 8726.86909696355, 8757.255633271243, 8581.485110893367, 8628.611712472828, 8639.827203455896, 8685.39202255836, 8685.739427817176, 6659.966700166565, 6767.52627316093, 6783.864322713518, 6825.863482730318, 6842.965785171043, 6614.404703576702, 6656.267810540933, 6663.300353462817, 6675.632840193836, 6680.331966803281, 7598.468107232441, 7620.0, 7763.961180194063, 7844.2155784423385, 7898.2891539761695, 7874.0, 7968.521888686697, 8091.959540202261, 8104.108548059591, 8117.431779775359, 5044.571211446922, 5156.1750119980625, 5163.741812909265, 5905.320888097924, 5998.700064996646, 5725.427457254408, 6858.5199036067, 6873.759418420332, 6966.233714291323, 6972.755953541602, 6409.9679501602195, 6622.536422450575, 6697.531172817856, 6750.291219422086, 6765.526413151032, 6364.840878977878, 6450.290468048604, 6460.321666224979, 6482.35176482348, 6542.476601871744, 6505.544611877123, 6528.640924748995, 6557.573757705733, 6563.245226798979, 6583.078369028244, 5754.0, 5876.6180198288685, 5892.057270836645, 5911.379305172895, 5943.049112142036, 5778.797742079007, 5813.069908814569, 5821.854453638693, 5835.124731290341, 5845.649261044351], "unit": "score", "name": "mootools.html", "value": 4066.887332601735}, {"lowerIsBetter": false, "replicates": [286.69896608561004, 293.62855987176226, 296.96436427628686, 299.50133028507537, 300.90371081254, 5284.180951952444, 5354.170103633938, 5360.437154098817, 5403.432639572848, 5421.593380496469, 5330.3603567571845, 5332.306800115979, 5332.960072785809, 5333.226682131099, 5336.519713225809, 4095.6313931746135, 4120.093579412524, 4127.463429754128, 4134.297169481196, 4142.544320124784, 3881.961180388188, 3999.180168065554, 4035.0, 4139.296319625656, 4142.482189726285, 4086.795660216992, 4090.4886889138857, 4098.836046558149, 4108.753474791507, 4109.815058322382, 3875.5736868944396, 3956.7625942443256, 4097.201045796071, 4098.241825262336, 4101.979490102531, 3535.257595904863, 3579.821008949542, 3593.802340871251, 3605.405108157151, 3617.113807117247, 5136.486351364871, 5137.972405518911, 5142.434332223453, 5147.794088236466, 5156.639035267533, 4739.194336962724, 4740.928886066712, 4742.976285118587, 4744.833930812405, 4749.453812811536, 5408.4591540845995, 5599.972000139973, 5602.915956260659, 5604.439556044404, 5605.411431799643, 3958.129211573449, 3959.109200429909, 3959.5644479107277, 3967.8016099194924, 3971.0, 5886.764529418818, 5894.646321220697, 5896.734646940898, 5903.173555702205, 5904.114382842565, 4083.3262511685834, 4085.142120154801, 4089.754614723126, 4091.6931230157857, 4093.836246550164, 4032.6370626643156, 4141.399497072907, 4144.33690609501, 4150.5641907599875, 4156.0, 4661.044485880387, 4661.324108004321, 4665.066986602626, 4666.0, 4673.7429441381055, 5595.608307418442, 5598.048331783607, 5598.496135347838, 5601.075822489243, 5601.131824567117, 1422.4310275889727, 1458.9927050364893, 1459.613202501338, 1460.3501441858484, 1462.3200211901517, 973.5667627905514, 991.6248565984508, 993.036754348281, 995.8804943406838, 1016.1210552871713, 4959.652824302337, 4966.106100901872, 4973.403191616957, 4977.129002424561, 4978.651494395358, 5833.241678581757, 5840.532757379399, 5847.707614619252, 5848.502877255396, 5851.034579294454, 4965.230389289666, 4968.22992436173, 4970.055689418999, 4976.104301225741, 4976.975115124474, 3021.6525099613827, 3077.1076387847274, 3117.5479555464785, 3138.9372212555622, 3152.7635427342584, 5463.9180412293845, 5479.671219726829, 5613.663180209119, 5615.466530679579, 5616.522595579421, 6047.365026672136, 6073.271207455134, 6073.453389194905, 6073.848153796191, 6080.0575910733905, 5567.192757050205, 5586.91619625706, 5592.412796656293, 5601.355844077983, 5610.523105535994, 5756.510696590837, 5885.823425297248, 5897.292324921037, 5905.320888097752, 5907.0, 5754.482096611325, 5758.740856661377, 5759.769609215585, 5765.423457654201, 5768.048272035235, 5598.916016259841, 5604.775808967595, 5606.467385598362, 5606.775728970959, 5612.7193640317, 3850.7497012694084, 3943.802809859553, 3988.481497405319, 3989.4414781929972, 3990.82041308136, 5784.50831679312, 5839.854003649943, 5854.326752423526, 5854.707264636836, 5859.677717725566, 5624.971875140598, 5634.183043458757, 5636.718164091861, 5638.718064096861, 5643.238162847963, 5624.634398764231, 5631.662100273997, 5636.830895073154, 5638.23883775685, 5642.266505354278, 5396.163594642837, 5501.779928802803, 5515.889682206253, 5518.089515229942, 5524.751386187711, 5656.123300888369, 5661.0, 5671.858203544779, 5674.347450043298, 5677.318721753416, 5530.7511161997, 5534.225208470898, 5534.280543529316, 5539.390667026543, 5539.977908063608], "unit": "score", "name": "prototype.html", "value": 4226.011643906148}, {"lowerIsBetter": false, "replicates": [691.4848437913754, 733.5855241788389, 741.5921243316177, 763.3549650545291, 766.440498436142, 10840.512176952036, 11289.548418063277, 11299.265547739391, 11346.0, 11366.37484938328, 11530.135239857002, 11625.186236963416, 11626.534938602446, 11636.883631163686, 11650.766984660302, 614.2782230878722, 621.4158690830604, 775.8176828445324, 783.561205724796, 801.5391150088693, 777.5762209595783, 779.5946108023825, 781.7811012916393, 786.2412771675332, 786.6931896560346, 640.878233135705, 818.8157664525464, 827.0571548434789, 828.0146625515647, 828.6892415344246, 640.5324113397199, 800.5316889619551, 816.2327412232542, 817.9263866252006, 818.8607936650784, 593.2880543347957, 602.5209958083334, 603.2700432476709, 605.1376788076973, 605.1769593353036, 10492.317999330018, 10774.5690172393, 10796.2442629016, 10803.621873234477, 10848.457577121113, 10744.30162039465, 11315.434228288552, 11318.773624527546, 11345.773084538345, 11355.772884542263, 9429.198518125968, 9615.471149086798, 9649.758756031086, 9805.166560842337, 9828.852567211497, 3249.78876373035, 3256.3161736035463, 3293.75296852737, 3300.900972970791, 3361.8487168077495, 39345.80327098375, 39539.8023009886, 39552.60447395518, 40536.0, 40698.593014069775, 8516.467035933592, 8562.186592273723, 8567.74296771098, 8717.869231961526, 8807.207351338411, 15601.687966240612, 15719.921400392925, 15725.528234152765, 15750.9212453937, 15752.76370854438, 6326.778562750282, 6361.395667411589, 6426.839329016812, 6561.0, 6728.495362847708, 3342.983285083559, 3377.4933759935716, 3432.8111953842777, 3450.3616830886367, 3468.202313467866, 2601.843889366644, 2636.683597968218, 2641.0624228399024, 2670.0521314933308, 2671.9465610687676, 455.16477264219867, 455.1874903297598, 458.42238779138523, 460.2199272233532, 463.92809114587294, 458.0472616956722, 499.65274134476914, 508.9820359281437, 552.3344370034099, 600.1507866369126, 7312.670929808065, 7342.596157211405, 7570.205128461543, 7628.275313845176, 7714.151443341228, 465.95573420524994, 552.4392741367515, 582.2198254339121, 583.4952765857588, 583.6818933681154, 3449.3273811606996, 3460.4290292101523, 3536.2397084626414, 3568.91077723059, 3629.7822130672243, 46770.53229467662, 52773.208401874006, 52878.20682689763, 52949.2057619136, 53354.0, 43593.0, 44701.882452938284, 44803.775981119885, 45309.0, 45400.54599454029, 40012.59987400147, 41157.794211028755, 41832.58167418348, 41913.16173676509, 42535.361969570484, 7569.772906812805, 8312.177707259072, 8472.83054338922, 8592.914070859211, 8638.78403039929, 31034.0, 31083.0, 31434.21414464657, 32034.19914502156, 32347.838260808545, 35861.0, 36185.63814361928, 39930.20139597134, 40438.59561404349, 40862.79568602138, 9646.951765241129, 9795.706128816148, 9823.26325525574, 9857.605695772088, 10084.546195421077, 10662.0, 10948.890511094787, 11473.942630286796, 11540.942295288805, 11790.528378864748, 10000.699979000641, 10099.899001009895, 10127.139193168663, 10132.848007279748, 10539.314944528582, 10094.192464602667, 10406.219533534864, 10439.475789819036, 10460.79078418412, 10557.366558006544, 9014.729558113006, 9054.456732596065, 9059.592318345558, 9271.351005429555, 9283.48940808235, 9122.863157052781, 9205.815883682155, 9296.39573427725, 9325.393849399767, 9407.670731524364, 8685.478871267744, 8911.15344042315, 8949.955250223707, 8972.596233169654, 9064.184223419923], "unit": "score", "name": "yui.html", "value": 5579.173967672224}], "name": "dromaeo_css", "value": 6316.036491247452}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 114.0, 107.0, 112.0, 112.0, 112.0, 102.0, 112.0, 111.0, 112.0], "unit": "score", "name": "ai-astar", "value": 110.5}, {"lowerIsBetter": true, "replicates": [156.0, 128.0, 156.0, 168.0, 131.0, 159.0, 148.0, 131.0, 167.0, 138.0], "unit": "score", "name": "audio-beat-detection", "value": 148.2}, {"lowerIsBetter": true, "replicates": [162.0, 160.0, 161.0, 160.0, 159.0, 161.0, 164.0, 161.0, 162.0, 162.0], "unit": "score", "name": "audio-dft", "value": 161.2}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 89.0, 92.0, 72.0, 78.0, 71.0, 113.0, 90.0, 72.0], "unit": "score", "name": "audio-fft", "value": 82.0}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 131.0, 96.0, 95.0, 95.0, 130.0, 96.0, 95.0, 132.0], "unit": "score", "name": "audio-oscillator", "value": 106.2}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0, 109.0, 110.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.7}, {"lowerIsBetter": true, "replicates": [148.0, 147.0, 148.0, 146.0, 146.0, 147.0, 147.0, 147.0, 147.0, 147.0], "unit": "score", "name": "imaging-darkroom", "value": 147.0}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 95.0, 95.0, 97.0, 95.0, 95.0, 95.0, 95.0, 97.0], "unit": "score", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 69.0, 69.0, 69.0, 70.0, 69.0, 79.0, 68.0, 68.0], "unit": "score", "name": "json-parse-financial", "value": 70.1}, {"lowerIsBetter": true, "replicates": [67.0, 69.0, 69.0, 68.0, 68.0, 69.0, 68.0, 70.0, 68.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.5}, {"lowerIsBetter": true, "replicates": [81.0, 83.0, 84.0, 83.0, 83.0, 83.0, 83.0, 82.0, 85.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 83.1}, {"lowerIsBetter": true, "replicates": [128.0, 122.0, 120.0, 122.0, 126.0, 119.0, 128.0, 125.0, 134.0, 124.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.8}, {"lowerIsBetter": true, "replicates": [139.0, 145.0, 158.0, 133.0, 153.0, 135.0, 146.0, 144.0, 145.0, 133.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 143.1}, {"lowerIsBetter": true, "replicates": [69.0, 68.0, 68.0, 68.0, 68.0, 67.0, 67.0, 70.0, 71.0, 69.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.5}], "name": "kraken", "value": 1518.4999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.9261125104954], "unit": "score", "name": "Richards", "value": 210.22340999999997}, {"lowerIsBetter": false, "replicates": [186.81113394358303], "unit": "score", "name": "DeltaBlue", "value": 353.92965399999986}, {"lowerIsBetter": false, "replicates": [279.2516056967328], "unit": "score", "name": "Encrypt", "value": 953.1941609999996}, {"lowerIsBetter": false, "replicates": [5196.891191709845], "unit": "score", "name": "Decrypt", "value": 51.219275174476564}, {"lowerIsBetter": false, "replicates": [1982.1782178217823], "unit": "score", "name": "RayTrace", "value": 373.32112387612375}, {"lowerIsBetter": false, "replicates": [795.5449482895783], "unit": "score", "name": "Earley", "value": 837.743991}, {"lowerIsBetter": false, "replicates": [12550.0], "unit": "score", "name": "Boyer", "value": 53.10462151394425}, {"lowerIsBetter": false, "replicates": [27216.216216216217], "unit": "score", "name": "RegExp", "value": 33.472140019860944}, {"lowerIsBetter": false, "replicates": [613.8735420503376], "unit": "score", "name": "Splay", "value": 132.748839}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 19645.13635737712}]} 17:01:53 INFO - RETURN: dromaeo_css: 6316.04 17:01:53 INFO - RETURN: kraken: 1518.50 17:01:53 INFO - RETURN: v8_7: 19645.14 17:01:53 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,35]]", "result": "19645.14"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,35]]", "result": "1518.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,35]]", "result": "6316.04"}}} 17:01:53 INFO - Return code: 0 17:01:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:01:53 INFO - # TBPL SUCCESS # 17:01:53 INFO - Running post-action listener: _resource_record_post_action 17:01:53 INFO - Running post-run listener: _resource_record_post_run 17:01:54 INFO - Total resource usage - Wall time: 1234s; CPU: 13.0%; Read bytes: 12288; Write bytes: 507416576; Read time: 444; Write time: 713612 17:01:54 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 4096; Write bytes: 231407616; Read time: 260; Write time: 453500 17:01:55 INFO - run-tests - Wall time: 1220s; CPU: 13.0%; Read bytes: 8192; Write bytes: 276008960; Read time: 184; Write time: 260112 17:01:55 INFO - Running post-run listener: _upload_blobber_files 17:01:55 INFO - Blob upload gear active. 17:01:55 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 17:01:55 INFO - Copying logs to upload dir... 17:01:55 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1277.325667 ========= master_lag: 1.77 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2015-12-15 17:01:55.936938) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-15 17:01:55.940898) ========= 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=004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010065 build_url: 'https://queue.taskcluster.net/v1/task/E4RtvuPOQFWIvF0nxDeC-Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.90 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-15 17:01:56.847080) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 17:01:56.847503) ========= 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=004b26eee9c3b360c8654f40000001e1-1450226133.410173-184349190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004574 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 17:01:56.931369) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-15 17:01:56.931696) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-15 17:01:56.935494) ========= ========= Total master_lag: 2.89 =========