builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-019 starttime: 1450272029.05 results: success (0) buildid: 20151216045007 builduid: 2cde2e0c6f5948a788a3a975977b2a4a revision: 48a56019f52049bbf9ccede95d15a2cb3b5295bc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.051932) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.052464) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.052796) ========= 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=d60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006205 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.079022) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.079635) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.096365) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.096834) ========= 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=d60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004553 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.150523) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.151234) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.151783) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.152508) ========= 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=d60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-16 05:20:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.5M=0s 2015-12-16 05:20:29 (43.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.184697 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.351230) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.351618) ========= 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=d60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019189 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.403629) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.404586) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 48a56019f52049bbf9ccede95d15a2cb3b5295bc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 48a56019f52049bbf9ccede95d15a2cb3b5295bc --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=d60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-16 05:20:29,898 truncating revision to first 12 chars 2015-12-16 05:20:29,898 Setting DEBUG logging. 2015-12-16 05:20:29,898 attempt 1/10 2015-12-16 05:20:29,898 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/48a56019f520?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-16 05:20:30,122 unpacking tar archive at: fx-team-48a56019f520/testing/mozharness/ program finished with exit code 0 elapsedTime=0.472415 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.892009) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.892388) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.901433) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.901810) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-16 05:20:29.902283) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2015-12-16 05:20:29.902618) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-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=d60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:20:30 INFO - MultiFileLogger online at 20151216 05:20:30 in /builds/slave/test 05:20:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 05:20:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:20:30 INFO - {'append_to_log': False, 05:20:30 INFO - 'base_work_dir': '/builds/slave/test', 05:20:30 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 05:20:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:20:30 INFO - 'branch': 'Fx-Team-Non-PGO', 05:20:30 INFO - 'buildbot_json_path': 'buildprops.json', 05:20:30 INFO - 'config_files': ('talos/linux_config.py',), 05:20:30 INFO - 'default_actions': ('clobber', 05:20:30 INFO - 'read-buildbot-config', 05:20:30 INFO - 'download-and-extract', 05:20:30 INFO - 'populate-webroot', 05:20:30 INFO - 'create-virtualenv', 05:20:30 INFO - 'install', 05:20:30 INFO - 'run-tests'), 05:20:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:20:30 INFO - 'download_minidump_stackwalk': True, 05:20:30 INFO - 'download_symbols': 'ondemand', 05:20:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:20:30 INFO - 'tooltool.py': '/tools/tooltool.py', 05:20:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:20:30 INFO - '/tools/misc-python/virtualenv.py')}, 05:20:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:20:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:20:30 INFO - 'installer_path': 'installer.exe', 05:20:30 INFO - 'log_level': 'info', 05:20:30 INFO - 'log_name': 'talos', 05:20:30 INFO - 'log_to_console': True, 05:20:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:20:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:20:30 INFO - 'opt_config_files': (), 05:20:30 INFO - 'pip_index': False, 05:20:30 INFO - 'sps_profile': False, 05:20:30 INFO - 'sps_profile_interval': 0, 05:20:30 INFO - 'suite': 'dromaeojs-e10s', 05:20:30 INFO - 'system_bits': '64', 05:20:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:20:30 INFO - 'title': 'talos-linux64-ix-019', 05:20:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:20:30 INFO - 'use_talos_json': True, 05:20:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:20:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:20:30 INFO - 'work_dir': 'build'} 05:20:30 INFO - ##### 05:20:30 INFO - ##### Running clobber step. 05:20:30 INFO - ##### 05:20:30 INFO - Running pre-action listener: _resource_record_pre_action 05:20:30 INFO - Running main action method: clobber 05:20:30 INFO - rmtree: /builds/slave/test/build 05:20:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:20:30 INFO - Running post-action listener: _resource_record_post_action 05:20:30 INFO - ##### 05:20:30 INFO - ##### Running read-buildbot-config step. 05:20:30 INFO - ##### 05:20:30 INFO - Running pre-action listener: _resource_record_pre_action 05:20:30 INFO - Running main action method: read_buildbot_config 05:20:30 INFO - Using buildbot properties: 05:20:30 INFO - { 05:20:30 INFO - "properties": { 05:20:30 INFO - "buildnumber": 585, 05:20:30 INFO - "product": "firefox", 05:20:30 INFO - "script_repo_revision": "production", 05:20:30 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 05:20:30 INFO - "repository": "", 05:20:30 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 05:20:30 INFO - "buildid": "20151216045007", 05:20:30 INFO - "slavename": "talos-linux64-ix-019", 05:20:30 INFO - "pgo_build": "False", 05:20:30 INFO - "basedir": "/builds/slave/test", 05:20:30 INFO - "project": "", 05:20:30 INFO - "platform": "ubuntu64_hw", 05:20:30 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:20:30 INFO - "slavebuilddir": "test", 05:20:30 INFO - "scheduler": "tests-fx-team-linux64-talos", 05:20:30 INFO - "branch": "fx-team", 05:20:30 INFO - "repo_path": "integration/fx-team", 05:20:30 INFO - "stage_platform": "linux64", 05:20:30 INFO - "builduid": "2cde2e0c6f5948a788a3a975977b2a4a", 05:20:30 INFO - "revision": "48a56019f52049bbf9ccede95d15a2cb3b5295bc" 05:20:30 INFO - }, 05:20:30 INFO - "sourcestamp": { 05:20:30 INFO - "repository": "", 05:20:30 INFO - "hasPatch": false, 05:20:30 INFO - "project": "", 05:20:30 INFO - "branch": "fx-team-linux64-talos", 05:20:30 INFO - "changes": [ 05:20:30 INFO - { 05:20:30 INFO - "category": null, 05:20:30 INFO - "files": [ 05:20:30 INFO - { 05:20:30 INFO - "url": null, 05:20:30 INFO - "name": "https://queue.taskcluster.net/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2" 05:20:30 INFO - } 05:20:30 INFO - ], 05:20:30 INFO - "repository": "", 05:20:30 INFO - "rev": "48a56019f52049bbf9ccede95d15a2cb3b5295bc", 05:20:30 INFO - "who": "s.kaspari@gmail.com", 05:20:30 INFO - "when": 1450271213, 05:20:30 INFO - "number": 7366024, 05:20:30 INFO - "comments": "Bug 1222377 - Add option to enable blocking content in restricted profiles. r=margaret\n\n(Feature only enabled in Nightly until we deployed an actual block list)", 05:20:30 INFO - "project": "", 05:20:30 INFO - "at": "Wed 16 Dec 2015 05:06:53", 05:20:30 INFO - "branch": "fx-team-linux64-talos", 05:20:30 INFO - "revlink": "", 05:20:30 INFO - "properties": [ 05:20:30 INFO - [ 05:20:30 INFO - "buildid", 05:20:30 INFO - "20151216045007", 05:20:30 INFO - "Change" 05:20:30 INFO - ], 05:20:30 INFO - [ 05:20:30 INFO - "builduid", 05:20:30 INFO - "2cde2e0c6f5948a788a3a975977b2a4a", 05:20:30 INFO - "Change" 05:20:30 INFO - ], 05:20:30 INFO - [ 05:20:30 INFO - "pgo_build", 05:20:30 INFO - "False", 05:20:30 INFO - "Change" 05:20:30 INFO - ] 05:20:30 INFO - ], 05:20:30 INFO - "revision": "48a56019f52049bbf9ccede95d15a2cb3b5295bc" 05:20:30 INFO - } 05:20:30 INFO - ], 05:20:30 INFO - "revision": "48a56019f52049bbf9ccede95d15a2cb3b5295bc" 05:20:30 INFO - } 05:20:30 INFO - } 05:20:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 05:20:30 INFO - Running post-action listener: _resource_record_post_action 05:20:30 INFO - ##### 05:20:30 INFO - ##### Running download-and-extract step. 05:20:30 INFO - ##### 05:20:30 INFO - Running pre-action listener: _resource_record_pre_action 05:20:30 INFO - Running main action method: download_and_extract 05:20:30 INFO - mkdir: /builds/slave/test/build/tests 05:20:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:30 INFO - https://queue.taskcluster.net/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:20:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/test_packages.json 05:20:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/test_packages.json 05:20:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 05:20:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 05:20:31 INFO - Downloaded 1302 bytes. 05:20:31 INFO - Reading from file /builds/slave/test/build/test_packages.json 05:20:31 INFO - Using the following test package requirements: 05:20:31 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:20:31 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:20:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:20:31 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:20:31 INFO - u'jsshell-linux-x86_64.zip'], 05:20:31 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:20:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:20:31 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:20:31 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:20:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:20:31 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:20:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:20:31 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:20:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:20:31 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:20:31 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:20:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:20:31 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:20:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:31 INFO - https://queue.taskcluster.net/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:20:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:20:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:20:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/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 05:20:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/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 05:20:32 INFO - Downloaded 21869445 bytes. 05:20:32 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 05:20:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:20:32 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 05:20:33 INFO - Return code: 0 05:20:33 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 05:20: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')]} 05:20:33 INFO - https://queue.taskcluster.net/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:20:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:20:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:20:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/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 05:20:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/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 05:20:35 INFO - Downloaded 21869445 bytes. 05:20:35 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 05:20:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:20:35 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 05:20:36 INFO - Return code: 0 05:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:36 INFO - https://queue.taskcluster.net/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:20:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:20:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:20:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/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 05:20:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/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 05:20:37 INFO - Downloaded 11132052 bytes. 05:20:37 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 05:20:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:20:37 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 05:20:37 INFO - Return code: 0 05:20:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:37 INFO - https://queue.taskcluster.net/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:20:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:20:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:20:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/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 05:20:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANpTl2gpRymqbyq130qVZg/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 05:20:39 INFO - Downloaded 57745992 bytes. 05:20:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:20:39 INFO - mkdir: /builds/slave/test/properties 05:20:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:20:39 INFO - Writing to file /builds/slave/test/properties/build_url 05:20:39 INFO - Contents: 05:20:39 INFO - build_url:https://queue.taskcluster.net/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:20:39 INFO - Running post-action listener: _resource_record_post_action 05:20:39 INFO - Running post-action listener: set_extra_try_arguments 05:20:39 INFO - ##### 05:20:39 INFO - ##### Running populate-webroot step. 05:20:39 INFO - ##### 05:20:39 INFO - Running pre-action listener: _resource_record_pre_action 05:20:39 INFO - Running main action method: populate_webroot 05:20:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:20:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:20:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:20:39 INFO - '--tppagecycles', 05:20:39 INFO - '7'], 05:20:39 INFO - 'tests': ['tsvgm']}}, 05:20:39 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:20:39 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:20:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:20:39 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:20:39 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:20:39 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:20:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:39 INFO - 'talos_options': ['--e10s'], 05:20:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:39 INFO - 'tests': ['damp', 'tps']}, 05:20:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:39 INFO - 'talos_options': ['--e10s'], 05:20:39 INFO - 'tests': ['damp', 'tps']}, 05:20:39 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:20:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:20:39 INFO - 'other': {'tests': ['a11yr', 05:20:39 INFO - 'ts_paint', 05:20:39 INFO - 'tpaint', 05:20:39 INFO - 'sessionrestore', 05:20:39 INFO - 'sessionrestore_no_auto_restore']}, 05:20:39 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:20:39 INFO - 'tests': ['a11yr', 05:20:39 INFO - 'ts_paint', 05:20:39 INFO - 'tpaint', 05:20:39 INFO - 'sessionrestore', 05:20:39 INFO - 'sessionrestore_no_auto_restore']}, 05:20:39 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:20:39 INFO - 'tests': ['a11yr', 05:20:39 INFO - 'ts_paint', 05:20:39 INFO - 'tpaint', 05:20:39 INFO - 'sessionrestore', 05:20:39 INFO - 'sessionrestore_no_auto_restore']}, 05:20:39 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:20:39 INFO - 'tests': ['a11yr', 05:20:39 INFO - 'ts_paint', 05:20:39 INFO - 'tpaint', 05:20:39 INFO - 'sessionrestore', 05:20:39 INFO - 'sessionrestore_no_auto_restore']}, 05:20:39 INFO - 'other_l64': {'tests': ['a11yr', 05:20:39 INFO - 'ts_paint', 05:20:39 INFO - 'tpaint', 05:20:39 INFO - 'sessionrestore', 05:20:39 INFO - 'sessionrestore_no_auto_restore']}, 05:20:39 INFO - 'other_nol64': {'tests': ['a11yr', 05:20:39 INFO - 'ts_paint', 05:20:39 INFO - 'tpaint', 05:20:39 INFO - 'sessionrestore', 05:20:39 INFO - 'sessionrestore_no_auto_restore']}, 05:20:39 INFO - 'svgr': {'tests': ['tsvgx', 05:20:39 INFO - 'tsvgr_opacity', 05:20:39 INFO - 'tart', 05:20:39 INFO - 'tscrollx', 05:20:39 INFO - 'cart']}, 05:20:39 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:20:39 INFO - 'tests': ['tsvgx', 05:20:39 INFO - 'tsvgr_opacity', 05:20:39 INFO - 'tart', 05:20:39 INFO - 'tscrollx', 05:20:39 INFO - 'cart']}, 05:20:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:39 INFO - 'tests': ['tp5o']}, 05:20:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:39 INFO - 'talos_options': ['--e10s'], 05:20:39 INFO - 'tests': ['tp5o']}, 05:20:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:39 INFO - 'talos_options': ['--xperf_path', 05:20:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:39 INFO - 'tests': ['tp5n']}, 05:20:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:39 INFO - 'talos_options': ['--e10s', 05:20:39 INFO - '--xperf_path', 05:20:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:39 INFO - 'tests': ['tp5n']}}, 05:20:39 INFO - 'talos.zip': {'path': '', 05:20:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:20:39 INFO - Running post-action listener: _resource_record_post_action 05:20:39 INFO - ##### 05:20:39 INFO - ##### Running create-virtualenv step. 05:20:39 INFO - ##### 05:20:39 INFO - Running pre-action listener: _resource_record_pre_action 05:20:39 INFO - Running main action method: create_virtualenv 05:20:39 INFO - Creating virtualenv /builds/slave/test/build/venv 05:20:39 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 05:20:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:20:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:20:39 INFO - Using real prefix '/usr' 05:20:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:20:39 INFO - Installing distribute.............................................................................................................................................................................................done. 05:20:41 INFO - Installing pip.................done. 05:20:41 INFO - Return code: 0 05:20:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:41 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 0x7f562c061f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x143b240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ca0b0>, '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 0x14cafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14cb440>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966', '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 05:20:41 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 05:20:41 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 05:20:41 INFO - Using env: {'DISPLAY': ':0', 05:20:41 INFO - 'HOME': '/home/cltbld', 05:20:41 INFO - 'LANG': 'en_US.UTF-8', 05:20:41 INFO - 'LANGUAGE': 'en_US:en', 05:20:41 INFO - 'LOGNAME': 'cltbld', 05:20:41 INFO - 'MAIL': '/var/mail/cltbld', 05:20:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:41 INFO - 'MOZ_NO_REMOTE': '1', 05:20:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:41 INFO - 'NO_EM_RESTART': '1', 05:20:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:41 INFO - 'PWD': '/builds/slave/test', 05:20:41 INFO - 'SHELL': '/bin/bash', 05:20:41 INFO - 'SHLVL': '1', 05:20:41 INFO - 'TERM': 'linux', 05:20:41 INFO - 'TMOUT': '86400', 05:20:41 INFO - 'USER': 'cltbld', 05:20:41 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966', 05:20:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:41 INFO - '_': '/tools/buildbot/bin/python'} 05:20:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:42 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 05:20:42 INFO - Cleaning up... 05:20:42 INFO - Return code: 0 05:20:42 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:20: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')]} 05:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20: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')]} 05:20:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:42 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 0x7f562c061f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x143b240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ca0b0>, '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 0x14cafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14cb440>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966', '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 05:20:42 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 05:20:42 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 05:20:42 INFO - Using env: {'DISPLAY': ':0', 05:20:42 INFO - 'HOME': '/home/cltbld', 05:20:42 INFO - 'LANG': 'en_US.UTF-8', 05:20:42 INFO - 'LANGUAGE': 'en_US:en', 05:20:42 INFO - 'LOGNAME': 'cltbld', 05:20:42 INFO - 'MAIL': '/var/mail/cltbld', 05:20:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:42 INFO - 'MOZ_NO_REMOTE': '1', 05:20:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:42 INFO - 'NO_EM_RESTART': '1', 05:20:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:42 INFO - 'PWD': '/builds/slave/test', 05:20:42 INFO - 'SHELL': '/bin/bash', 05:20:42 INFO - 'SHLVL': '1', 05:20:42 INFO - 'TERM': 'linux', 05:20:42 INFO - 'TMOUT': '86400', 05:20:42 INFO - 'USER': 'cltbld', 05:20:42 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966', 05:20:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:42 INFO - '_': '/tools/buildbot/bin/python'} 05:20:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:42 INFO - Downloading/unpacking psutil>=0.7.1 05:20:42 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 05:20:42 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 05:20:42 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 05:20:42 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 05:20:42 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 05:20:42 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 05:20:44 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 05:20:44 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:20:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:20:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:44 INFO - Installing collected packages: psutil 05:20:44 INFO - Running setup.py install for psutil 05:20:44 INFO - building 'psutil._psutil_linux' extension 05:20:44 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 05:20:44 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 05:20:44 INFO - building 'psutil._psutil_posix' extension 05:20:44 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 05:20:44 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 05:20:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:44 INFO - Successfully installed psutil 05:20:44 INFO - Cleaning up... 05:20:44 INFO - Return code: 0 05:20:44 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:44 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 0x7f562c061f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x143b240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ca0b0>, '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 0x14cafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14cb440>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966', '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 05:20:44 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 05:20:44 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 05:20:44 INFO - Using env: {'DISPLAY': ':0', 05:20:44 INFO - 'HOME': '/home/cltbld', 05:20:44 INFO - 'LANG': 'en_US.UTF-8', 05:20:44 INFO - 'LANGUAGE': 'en_US:en', 05:20:44 INFO - 'LOGNAME': 'cltbld', 05:20:44 INFO - 'MAIL': '/var/mail/cltbld', 05:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:44 INFO - 'MOZ_NO_REMOTE': '1', 05:20:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:44 INFO - 'NO_EM_RESTART': '1', 05:20:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:44 INFO - 'PWD': '/builds/slave/test', 05:20:44 INFO - 'SHELL': '/bin/bash', 05:20:44 INFO - 'SHLVL': '1', 05:20:44 INFO - 'TERM': 'linux', 05:20:44 INFO - 'TMOUT': '86400', 05:20:44 INFO - 'USER': 'cltbld', 05:20:44 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966', 05:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:44 INFO - '_': '/tools/buildbot/bin/python'} 05:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:44 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:46 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:20:46 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:20:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:20:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:20:46 INFO - Installing collected packages: mozsystemmonitor 05:20:46 INFO - Running setup.py install for mozsystemmonitor 05:20:46 INFO - Successfully installed mozsystemmonitor 05:20:46 INFO - Cleaning up... 05:20:46 INFO - Return code: 0 05:20:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:20:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:46 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 0x7f562c061f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x143b240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ca0b0>, '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 0x14cafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14cb440>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966', '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 05:20:46 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 05:20:46 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 05:20:46 INFO - Using env: {'DISPLAY': ':0', 05:20:46 INFO - 'HOME': '/home/cltbld', 05:20:46 INFO - 'LANG': 'en_US.UTF-8', 05:20:46 INFO - 'LANGUAGE': 'en_US:en', 05:20:46 INFO - 'LOGNAME': 'cltbld', 05:20:46 INFO - 'MAIL': '/var/mail/cltbld', 05:20:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:46 INFO - 'MOZ_NO_REMOTE': '1', 05:20:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:46 INFO - 'NO_EM_RESTART': '1', 05:20:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:46 INFO - 'PWD': '/builds/slave/test', 05:20:46 INFO - 'SHELL': '/bin/bash', 05:20:46 INFO - 'SHLVL': '1', 05:20:46 INFO - 'TERM': 'linux', 05:20:46 INFO - 'TMOUT': '86400', 05:20:46 INFO - 'USER': 'cltbld', 05:20:46 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966', 05:20:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:46 INFO - '_': '/tools/buildbot/bin/python'} 05:20:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:46 INFO - Downloading/unpacking blobuploader==1.2.4 05:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:48 INFO - Downloading blobuploader-1.2.4.tar.gz 05:20:48 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:20:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:20:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:20:48 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 05:20:48 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 05:20:48 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 05:20:48 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 05:20:48 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 05:20:48 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 05:20:48 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:20:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:20:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:49 INFO - Downloading docopt-0.6.1.tar.gz 05:20:49 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:20:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:20:49 INFO - Installing collected packages: blobuploader, requests, docopt 05:20:49 INFO - Running setup.py install for blobuploader 05:20:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:20:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:20:49 INFO - Running setup.py install for requests 05:20:49 INFO - Running setup.py install for docopt 05:20:49 INFO - Successfully installed blobuploader requests docopt 05:20:49 INFO - Cleaning up... 05:20:49 INFO - Return code: 0 05:20:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:49 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 0x7f562c061f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x143b240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ca0b0>, '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 0x14cafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14cb440>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966', '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 05:20:49 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 05:20:49 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 05:20:49 INFO - Using env: {'DISPLAY': ':0', 05:20:49 INFO - 'HOME': '/home/cltbld', 05:20:49 INFO - 'LANG': 'en_US.UTF-8', 05:20:49 INFO - 'LANGUAGE': 'en_US:en', 05:20:49 INFO - 'LOGNAME': 'cltbld', 05:20:49 INFO - 'MAIL': '/var/mail/cltbld', 05:20:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:49 INFO - 'MOZ_NO_REMOTE': '1', 05:20:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:49 INFO - 'NO_EM_RESTART': '1', 05:20:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:49 INFO - 'PWD': '/builds/slave/test', 05:20:49 INFO - 'SHELL': '/bin/bash', 05:20:49 INFO - 'SHLVL': '1', 05:20:49 INFO - 'TERM': 'linux', 05:20:49 INFO - 'TMOUT': '86400', 05:20:49 INFO - 'USER': 'cltbld', 05:20:49 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966', 05:20:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:49 INFO - '_': '/tools/buildbot/bin/python'} 05:20:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:50 INFO - Running setup.py (path:/tmp/pip-Adzi00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:50 INFO - Running setup.py (path:/tmp/pip-k93yOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:50 INFO - Running setup.py (path:/tmp/pip-yiT9tq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:50 INFO - Running setup.py (path:/tmp/pip-2zlGBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:50 INFO - Running setup.py (path:/tmp/pip-lOi5_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:50 INFO - Running setup.py (path:/tmp/pip-trMzjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:50 INFO - Running setup.py (path:/tmp/pip-LQ_N_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:50 INFO - Running setup.py (path:/tmp/pip-Ey8hnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:50 INFO - Running setup.py (path:/tmp/pip-twQO9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:50 INFO - Running setup.py (path:/tmp/pip-WGQMGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:50 INFO - Running setup.py (path:/tmp/pip-VUC3bX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:50 INFO - Running setup.py (path:/tmp/pip-fC7Ly7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:51 INFO - Running setup.py (path:/tmp/pip-21OJaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:51 INFO - Running setup.py (path:/tmp/pip-7pwfxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:51 INFO - Running setup.py (path:/tmp/pip-a1UI3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:51 INFO - Running setup.py (path:/tmp/pip-XbY2hK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:51 INFO - Running setup.py (path:/tmp/pip-pvQBlg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:20:51 INFO - Running setup.py install for manifestparser 05:20:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:20:51 INFO - Running setup.py install for mozcrash 05:20:51 INFO - Running setup.py install for mozdebug 05:20:51 INFO - Running setup.py install for mozdevice 05:20:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:20:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:20:51 INFO - Running setup.py install for mozfile 05:20:51 INFO - Running setup.py install for mozhttpd 05:20:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:20:52 INFO - Running setup.py install for mozinfo 05:20:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:20:52 INFO - Running setup.py install for mozInstall 05:20:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:20:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:20:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:20:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:20:52 INFO - Running setup.py install for mozleak 05:20:52 INFO - Running setup.py install for mozlog 05:20:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:20:52 INFO - Running setup.py install for moznetwork 05:20:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:20:52 INFO - Running setup.py install for mozprocess 05:20:52 INFO - Running setup.py install for mozprofile 05:20:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:20:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:20:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:20:52 INFO - Running setup.py install for mozrunner 05:20:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:20:52 INFO - Running setup.py install for mozscreenshot 05:20:52 INFO - Running setup.py install for moztest 05:20:53 INFO - Running setup.py install for mozversion 05:20:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:20:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:20:53 INFO - Cleaning up... 05:20:53 INFO - Return code: 0 05:20:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20: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')]} 05:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20: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')]} 05:20:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:53 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 0x7f562c061f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x143b240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ca0b0>, '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 0x14cafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14cb440>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966', '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 05:20:53 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 05:20:53 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 05:20:53 INFO - Using env: {'DISPLAY': ':0', 05:20:53 INFO - 'HOME': '/home/cltbld', 05:20:53 INFO - 'LANG': 'en_US.UTF-8', 05:20:53 INFO - 'LANGUAGE': 'en_US:en', 05:20:53 INFO - 'LOGNAME': 'cltbld', 05:20:53 INFO - 'MAIL': '/var/mail/cltbld', 05:20:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:53 INFO - 'MOZ_NO_REMOTE': '1', 05:20:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:53 INFO - 'NO_EM_RESTART': '1', 05:20:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:53 INFO - 'PWD': '/builds/slave/test', 05:20:53 INFO - 'SHELL': '/bin/bash', 05:20:53 INFO - 'SHLVL': '1', 05:20:53 INFO - 'TERM': 'linux', 05:20:53 INFO - 'TMOUT': '86400', 05:20:53 INFO - 'USER': 'cltbld', 05:20:53 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966', 05:20:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:53 INFO - '_': '/tools/buildbot/bin/python'} 05:20:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:53 INFO - Running setup.py (path:/tmp/pip-_3JA3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:53 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)) 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:53 INFO - Running setup.py (path:/tmp/pip-1QjTlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:53 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)) 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:53 INFO - Running setup.py (path:/tmp/pip-kWheNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:53 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)) 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:53 INFO - Running setup.py (path:/tmp/pip-9N8EzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:53 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)) 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:53 INFO - Running setup.py (path:/tmp/pip-vr4qM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:53 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)) 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:53 INFO - Running setup.py (path:/tmp/pip-eQFKmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:53 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)) 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:53 INFO - Running setup.py (path:/tmp/pip-ZGu0DW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:53 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)) 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:53 INFO - Running setup.py (path:/tmp/pip-dR5GES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:53 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)) 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:53 INFO - Running setup.py (path:/tmp/pip-NbYaXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:54 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)) 05:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:54 INFO - Running setup.py (path:/tmp/pip-DHuKLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:54 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)) 05:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:54 INFO - Running setup.py (path:/tmp/pip-NkWbUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:54 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)) 05:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:54 INFO - Running setup.py (path:/tmp/pip-CdpX0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:54 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)) 05:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:54 INFO - Running setup.py (path:/tmp/pip-VsD8xq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:54 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)) 05:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:54 INFO - Running setup.py (path:/tmp/pip-fXX3Yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:54 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)) 05:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:54 INFO - Running setup.py (path:/tmp/pip-gn33Ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:54 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)) 05:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:54 INFO - Running setup.py (path:/tmp/pip-52P8cp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:54 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)) 05:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:54 INFO - Running setup.py (path:/tmp/pip-FDOb2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:54 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)) 05:20:54 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)) 05:20:54 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)) 05:20:54 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)) 05:20:54 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)) 05:20:54 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)) 05:20:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:56 INFO - Downloading blessings-1.5.1.tar.gz 05:20:56 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 05:20:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:20:56 INFO - Installing collected packages: blessings 05:20:56 INFO - Running setup.py install for blessings 05:20:56 INFO - Successfully installed blessings 05:20:56 INFO - Cleaning up... 05:20:56 INFO - Return code: 0 05:20:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:20:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:56 INFO - Reading from file tmpfile_stdout 05:20:56 INFO - Current package versions: 05:20:56 INFO - argparse == 1.2.1 05:20:56 INFO - blessings == 1.5.1 05:20:56 INFO - blobuploader == 1.2.4 05:20:56 INFO - docopt == 0.6.1 05:20:56 INFO - manifestparser == 1.1 05:20:56 INFO - mozInstall == 1.12 05:20:56 INFO - mozcrash == 0.16 05:20:56 INFO - mozdebug == 0.1 05:20:56 INFO - mozdevice == 0.47 05:20:56 INFO - mozfile == 1.2 05:20:56 INFO - mozhttpd == 0.7 05:20:56 INFO - mozinfo == 0.9 05:20:56 INFO - mozleak == 0.1 05:20:56 INFO - mozlog == 3.1 05:20:56 INFO - moznetwork == 0.27 05:20:56 INFO - mozprocess == 0.22 05:20:56 INFO - mozprofile == 0.27 05:20:56 INFO - mozrunner == 6.11 05:20:56 INFO - mozscreenshot == 0.1 05:20:56 INFO - mozsystemmonitor == 0.0 05:20:56 INFO - moztest == 0.7 05:20:56 INFO - mozversion == 1.4 05:20:56 INFO - psutil == 3.1.1 05:20:56 INFO - requests == 1.2.3 05:20:56 INFO - wsgiref == 0.1.2 05:20:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:56 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 0x7f562c061f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x143b240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ca0b0>, '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 0x14cafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14cb440>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966', '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 05:20:56 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 05:20:56 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 05:20:56 INFO - Using env: {'DISPLAY': ':0', 05:20:56 INFO - 'HOME': '/home/cltbld', 05:20:56 INFO - 'LANG': 'en_US.UTF-8', 05:20:56 INFO - 'LANGUAGE': 'en_US:en', 05:20:56 INFO - 'LOGNAME': 'cltbld', 05:20:56 INFO - 'MAIL': '/var/mail/cltbld', 05:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:56 INFO - 'MOZ_NO_REMOTE': '1', 05:20:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:56 INFO - 'NO_EM_RESTART': '1', 05:20:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:56 INFO - 'PWD': '/builds/slave/test', 05:20:56 INFO - 'SHELL': '/bin/bash', 05:20:56 INFO - 'SHLVL': '1', 05:20:56 INFO - 'TERM': 'linux', 05:20:56 INFO - 'TMOUT': '86400', 05:20:56 INFO - 'USER': 'cltbld', 05:20:56 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966', 05:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:56 INFO - '_': '/tools/buildbot/bin/python'} 05:20:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:56 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)) 05:20:56 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)) 05:20:56 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)) 05:20:56 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)) 05:20:56 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)) 05:20:56 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)) 05:20:56 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)) 05:20:56 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)) 05:20:56 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)) 05:20:56 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)) 05:20:56 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)) 05:20:56 INFO - Cleaning up... 05:20:56 INFO - Return code: 0 05:20:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:56 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 0x7f562c061f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x143b240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ca0b0>, '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 0x14cafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14cb440>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966', '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 05:20:56 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 05:20:56 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 05:20:56 INFO - Using env: {'DISPLAY': ':0', 05:20:56 INFO - 'HOME': '/home/cltbld', 05:20:56 INFO - 'LANG': 'en_US.UTF-8', 05:20:56 INFO - 'LANGUAGE': 'en_US:en', 05:20:56 INFO - 'LOGNAME': 'cltbld', 05:20:56 INFO - 'MAIL': '/var/mail/cltbld', 05:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:56 INFO - 'MOZ_NO_REMOTE': '1', 05:20:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:56 INFO - 'NO_EM_RESTART': '1', 05:20:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:56 INFO - 'PWD': '/builds/slave/test', 05:20:56 INFO - 'SHELL': '/bin/bash', 05:20:56 INFO - 'SHLVL': '1', 05:20:56 INFO - 'TERM': 'linux', 05:20:56 INFO - 'TMOUT': '86400', 05:20:56 INFO - 'USER': 'cltbld', 05:20:56 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966', 05:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:56 INFO - '_': '/tools/buildbot/bin/python'} 05:20:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:57 INFO - Downloading/unpacking jsonschema 05:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:20:58 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 05:20:58 INFO - Downloading/unpacking functools32 (from jsonschema) 05:20: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 05:20: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 05:20: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 05:20: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 05:20: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 05:20: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 05:20:59 INFO - Downloading functools32-3.2.3-2.tar.gz 05:20:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:20:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:20:59 INFO - warning: no files found matching '*.txt' 05:20:59 INFO - no previously-included directories found matching 'build' 05:20:59 INFO - no previously-included directories found matching 'dist' 05:20:59 INFO - no previously-included directories found matching '.git*' 05:20:59 INFO - Installing collected packages: jsonschema, functools32 05:20:59 INFO - Running setup.py install for functools32 05:20:59 INFO - warning: no files found matching '*.txt' 05:20:59 INFO - no previously-included directories found matching 'build' 05:20:59 INFO - no previously-included directories found matching 'dist' 05:20:59 INFO - no previously-included directories found matching '.git*' 05:20:59 INFO - Successfully installed jsonschema functools32 05:20:59 INFO - Cleaning up... 05:20:59 INFO - Return code: 0 05:20:59 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:59 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 0x7f562c061f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x143b240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ca0b0>, '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 0x14cafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14cb440>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966', '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 05:20:59 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 05:20:59 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 05:20:59 INFO - Using env: {'DISPLAY': ':0', 05:20:59 INFO - 'HOME': '/home/cltbld', 05:20:59 INFO - 'LANG': 'en_US.UTF-8', 05:20:59 INFO - 'LANGUAGE': 'en_US:en', 05:20:59 INFO - 'LOGNAME': 'cltbld', 05:20:59 INFO - 'MAIL': '/var/mail/cltbld', 05:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:59 INFO - 'MOZ_NO_REMOTE': '1', 05:20:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:59 INFO - 'NO_EM_RESTART': '1', 05:20:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:59 INFO - 'PWD': '/builds/slave/test', 05:20:59 INFO - 'SHELL': '/bin/bash', 05:20:59 INFO - 'SHLVL': '1', 05:20:59 INFO - 'TERM': 'linux', 05:20:59 INFO - 'TMOUT': '86400', 05:20:59 INFO - 'USER': 'cltbld', 05:20:59 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966', 05:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:59 INFO - '_': '/tools/buildbot/bin/python'} 05:20:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:59 INFO - Downloading/unpacking flake8 05:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:21:01 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 05:21:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:21: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 05:21: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 05:21: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 05:21: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 05:21: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 05:21: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 05:21:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:21:01 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 05:21:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:21: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 05:21: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 05:21: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 05:21: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 05:21: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 05:21: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 05:21:01 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 05:21:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:21: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 05:21: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 05:21: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 05:21: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 05:21: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 05:21: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 05:21:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 05:21:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:21:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:21:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:21:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:21:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:21:02 INFO - no previously-included directories found matching 'docs/_build' 05:21:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:21:02 INFO - Running setup.py install for pep8 05:21:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:21:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:21:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:21:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:21:02 INFO - no previously-included directories found matching 'docs/_build' 05:21:02 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:21:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:21:02 INFO - Cleaning up... 05:21:02 INFO - Return code: 0 05:21:02 INFO - Running post-action listener: _resource_record_post_action 05:21:02 INFO - Running post-action listener: _start_resource_monitoring 05:21:02 INFO - Starting resource monitoring. 05:21:02 INFO - ##### 05:21:02 INFO - ##### Running install step. 05:21:02 INFO - ##### 05:21:02 INFO - Running pre-action listener: _resource_record_pre_action 05:21:02 INFO - Running main action method: install 05:21:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:21:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:21:02 INFO - Reading from file tmpfile_stdout 05:21:02 INFO - Detecting whether we're running mozinstall >=1.0... 05:21:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:21:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:21:02 INFO - Reading from file tmpfile_stdout 05:21:02 INFO - Output received: 05:21:02 INFO - Usage: mozinstall [options] installer 05:21:02 INFO - Options: 05:21:02 INFO - -h, --help show this help message and exit 05:21:02 INFO - -d DEST, --destination=DEST 05:21:02 INFO - Directory to install application into. [default: 05:21:02 INFO - "/builds/slave/test"] 05:21:02 INFO - --app=APP Application being installed. [default: firefox] 05:21:02 INFO - mkdir: /builds/slave/test/build/application 05:21:02 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'] 05:21:02 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 05:21:17 INFO - Reading from file tmpfile_stdout 05:21:17 INFO - Output received: 05:21:17 INFO - /builds/slave/test/build/application/firefox/firefox 05:21:17 INFO - Running post-action listener: _resource_record_post_action 05:21:17 INFO - ##### 05:21:17 INFO - ##### Running run-tests step. 05:21:17 INFO - ##### 05:21:17 INFO - Running pre-action listener: _resource_record_pre_action 05:21:17 INFO - Running main action method: run_tests 05:21:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:21:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:21:17 INFO - Python 2.7.3 05:21:17 INFO - Return code: 0 05:21:17 INFO - grabbing minidump binary from tooltool 05:21:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:17 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 0x14ca0b0>, '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 0x14cafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14cb440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:21:17 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 05:21:17 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 05:21:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:21:17 INFO - Return code: 0 05:21:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:21:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:21:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:21:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:21:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:21:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:21:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:21:18 INFO - Return code: 0 05:21:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--webServer', 'localhost'] in /builds/slave/test/build 05:21:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-019 --webServer localhost 05:21:18 INFO - Using env: {'DISPLAY': ':0', 05:21:18 INFO - 'HOME': '/home/cltbld', 05:21:18 INFO - 'LANG': 'en_US.UTF-8', 05:21:18 INFO - 'LANGUAGE': 'en_US:en', 05:21:18 INFO - 'LOGNAME': 'cltbld', 05:21:18 INFO - 'MAIL': '/var/mail/cltbld', 05:21:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:21:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:21:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:18 INFO - 'MOZ_NO_REMOTE': '1', 05:21:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:21:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:21:18 INFO - 'NO_EM_RESTART': '1', 05:21:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:21:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:18 INFO - 'PWD': '/builds/slave/test', 05:21:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:21:18 INFO - 'SHELL': '/bin/bash', 05:21:18 INFO - 'SHLVL': '1', 05:21:18 INFO - 'TERM': 'linux', 05:21:18 INFO - 'TMOUT': '86400', 05:21:18 INFO - 'USER': 'cltbld', 05:21:18 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966', 05:21:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:18 INFO - '_': '/tools/buildbot/bin/python'} 05:21:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--webServer', 'localhost'] with output_timeout 3600 05:21:18 INFO - mozversion INFO | application_buildid: 20151216045007 05:21:18 INFO - mozversion INFO | application_changeset: 48a56019f52049bbf9ccede95d15a2cb3b5295bc 05:21:18 INFO - mozversion INFO | application_display_name: Nightly 05:21:18 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:21:18 INFO - mozversion INFO | application_name: Firefox 05:21:18 INFO - mozversion INFO | application_remotingname: firefox 05:21:18 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 05:21:18 INFO - mozversion INFO | application_vendor: Mozilla 05:21:18 INFO - mozversion INFO | application_version: 46.0a1 05:21:18 INFO - mozversion INFO | platform_buildid: 20151216045007 05:21:18 INFO - mozversion INFO | platform_changeset: 48a56019f52049bbf9ccede95d15a2cb3b5295bc 05:21:18 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 05:21:18 INFO - mozversion INFO | platform_version: 46.0a1 05:21:18 INFO - 2015-12-16 05:21:18,439 DEBUG : using testdate: 1450272078 05:21:18 INFO - 2015-12-16 05:21:18,439 DEBUG : actual date: 1450272078 05:21:18 INFO - 2015-12-16 05:21:18,448 INFO : starting webserver on 'localhost:49543' 05:21:18 INFO - 2015-12-16 05:21:18,449 INFO : Starting test suite talos-linux64-ix-019.e 05:21:18 INFO - 2015-12-16 05:21:18,449 INFO : Starting test dromaeo_css 05:21:18 INFO - 2015-12-16 05:21:18,449 DEBUG : operating with platform_type : linux_ 05:21:18 INFO - 2015-12-16 05:21:18,450 INFO : Initialising browser for dromaeo_css test... 05:21:18 INFO - 2015-12-16 05:21:18,463 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi62Kbo/profile http://localhost:49543/getInfo.html 05:21:23 INFO - 2015-12-16 05:21:23,497 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:21:23 INFO - 2015-12-16 05:21:23,497 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:21:23 INFO - 2015-12-16 05:21:23,497 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:21:23 INFO - 2015-12-16 05:21:23,498 DEBUG : BROWSER_OUTPUT: __metrics 05:21:23 INFO - 2015-12-16 05:21:23,614 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] 05:21:24 INFO - 2015-12-16 05:21:24,517 INFO : Browser initialized. 05:21:24 INFO - 2015-12-16 05:21:24,518 INFO : Running cycle 1/1 for dromaeo_css test... 05:21:24 INFO - 2015-12-16 05:21:24,518 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi62Kbo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:21:36 INFO - 2015-12-16 05:21:36,574 DEBUG : BROWSER_OUTPUT: RSS: Main: 142843904 05:21:36 INFO - 2015-12-16 05:21:36,574 DEBUG : BROWSER_OUTPUT: 05:22:26 INFO - 2015-12-16 05:22:26,199 DEBUG : BROWSER_OUTPUT: 1450272146195 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] 05:24:38 INFO - 2015-12-16 05:24:38,157 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49543/tests/dromaeo/cssquery-ext.html) 05:24:39 INFO - 2015-12-16 05:24:39,289 DEBUG : BROWSER_OUTPUT: RSS: Main: 146821120 05:24:39 INFO - 2015-12-16 05:24:39,289 DEBUG : BROWSER_OUTPUT: 05:27:41 INFO - 2015-12-16 05:27:41,353 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/dromaeo/cssquery-ext.html (next: http://localhost:49543/tests/dromaeo/cssquery-jquery.html) 05:27:41 INFO - 2015-12-16 05:27:41,944 DEBUG : BROWSER_OUTPUT: RSS: Main: 143474688 05:27:41 INFO - 2015-12-16 05:27:41,945 DEBUG : BROWSER_OUTPUT: 05:30:43 INFO - 2015-12-16 05:30:43,730 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49543/tests/dromaeo/cssquery-mootools.html) 05:30:44 INFO - 2015-12-16 05:30:44,324 DEBUG : BROWSER_OUTPUT: RSS: Main: 145149952 05:30:44 INFO - 2015-12-16 05:30:44,324 DEBUG : BROWSER_OUTPUT: 05:33:46 INFO - 2015-12-16 05:33:46,069 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49543/tests/dromaeo/cssquery-prototype.html) 05:33:46 INFO - 2015-12-16 05:33:46,677 DEBUG : BROWSER_OUTPUT: RSS: Main: 144355328 05:33:46 INFO - 2015-12-16 05:33:46,677 DEBUG : BROWSER_OUTPUT: 05:36:49 INFO - 2015-12-16 05:36:49,320 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49543/tests/dromaeo/cssquery-yui.html) 05:36:49 INFO - 2015-12-16 05:36:49,950 DEBUG : BROWSER_OUTPUT: RSS: Main: 145391616 05:36:49 INFO - 2015-12-16 05:36:49,950 DEBUG : BROWSER_OUTPUT: 05:39:52 INFO - 2015-12-16 05:39:52,456 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/dromaeo/cssquery-yui.html (next: http://localhost:49543/tests/dromaeo/cssquery-dojo.html) 05:39:52 INFO - 2015-12-16 05:39:52,707 DEBUG : BROWSER_OUTPUT: RSS: Main: 148037632 05:39:52 INFO - 2015-12-16 05:39:52,707 DEBUG : BROWSER_OUTPUT: 05:39:52 INFO - 2015-12-16 05:39:52,708 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:39:52 INFO - 2015-12-16 05:39:52,708 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:39:52 INFO - 2015-12-16 05:39:52,708 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:39:52 INFO - 2015-12-16 05:39:52,709 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:39:52 INFO - 2015-12-16 05:39:52,712 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2679.8124131310806;2733.658292713411;2769.072360759146;2774.2648198227466;2781.8052736308455;4629.537046295369;4829.323894654747;4837.526022295553;4873.658843880929;4887.266909963507;4696.8590942271785;4709.340692303081;4714.50497697742;4730.763461826912;4833.275008748689;4374.212641724488;4462.843800466992;4484.304932735415;4502.57225563571;4565.634749220071;4267.530571637118;4421.867343979686;4529;4539.364488971547;4559.566841150102;4331.30699088146;4423.137488189805;4426.247537918562;4456.131054444385;4471;4442.400275962736;4520.09598080382;4527.84152554659;4538.72767633943;4585.151746926829;4243.511996120455;4389.714668546566;4423.60187583119;4443.644508439349;4462.263726485126;4644.024754801496;4660.067986402699;4743.146233677937;4785.856424307276;4792.472827988919;5141.974290128563;5148.279240906276;5150.201718733602;5181.559567436772;5183.844484665466;4984.177610694231;5095.847124586268;5188.040212560651;5189.532942035197;5202.167653175512;4717.693349932278;4782.3543821584335;4785.880352991134;4792.400949881265;4820.758962051884;5344.198370244494;5347.81282655113;5365.597580181424;5370.350093072814;5381.165919282517;4847.927281090787;4974.303597496353;5011.874703132451;5121;5138.486151384906;5234.057869583511;5234.162533994542;5238.397584277781;5247.422783493813;5253.605979551549;5153.123968925292;5161.122609156452;5161.638685292069;5162.096633089194;5213.921791173136;4877.756112194376;4941.752912354368;4957.182064959241;4985.127602669519;5144.922826157611;4176.749395036308;4214.662826973835;4231.196072746168;4253.149370125987;4277.016286254179;3767.1335592813257;3776.546814382292;3790.033541446913;3792.6207379261855;3821.407681809324;4290.377895205178;4391.714538554984;4439.067795762926;4503.977480112579;4520.299353600197;5072.721000344943;5075.39095308558;5133.23001549763;5202.14164662834;5249.501297376743;4391.560843915583;4475.641948644101;4494.12364588902;4497.302918047708;4517.412736344254;4385.057212699285;4587.518310577408;4592.334111553872;4604.815807367735;4639.768011599406;4844.781984810692;4883.291922671122;4923.08922849274;4937.703737775745;4967.478414766542;5107.540321371094;5165.328507294028;5196.610254231024;5204.609654276021;5242.501962313574;4675.532446755297;4768.308595253599;4919.0407870464915;4947.109520286383;4948.703077815343;5016.924746128884;5092.3889133302755;5120.436751957356;5122.052420302256;5157.484251574812;5180.326557547494;5180.352455943007;5180.9740951295;5188.948110518846;5231.55531779803;4732.337472753886;4863.708177509361;4918.631102667386;4994.32576602154;4995.62532810048;5033.395992480926;5041.873953151201;5047.596192304681;5054.241863720471;5079.441261461162;4912.901741965212;4935.259711043373;4945.060438516671;4948.035133149001;5009.524095210949;4832.299316599003;4934.925976110289;4960.305557222064;5023.547880690609;5135.050015747099;4963.925541116959;4993.975030124971;4997.775100120431;5022.422421421583;5061.8734531637 05:39:52 INFO - 2015-12-16 05:39:52,712 DEBUG : BROWSER_OUTPUT: |1;ext.html;543.5488544508058;544.2407841061716;553.9501444869962;558.17947617003;573.5870173475097;34840.82579587102;35220.647793522025;35332.82333588332;35337.64662353376;35385;39331.41002884952;39791.204175916464;39806.40290395646;39896.01039896009;39902.002449938765;7749.92250077499;7847.9215207847765;7908.485948413363;7911.248431399036;7915.050193976702;7826.5695386753705;7878.566678832662;7915.960420197891;7920.485168464032;7967.44227904047;7854.960725196366;7911.52530848148;7919.089304729972;7936.246056624612;7942.920570794305;7906.762797116067;7993.44045916786;8005.399595030396;8011.359091272684;8011.799705007363;7830.216978302181;7936.2857342839425;7959.761207163794;7966.52200867944;7974.641141148662;35951.28097438037;36133.45799813005;36195.457068144;36264.45603315952;36364.454533182026;2884.971150288512;2944.8085874418093;2966.466036113499;3193;3276.2137087099163;27201.863990680118;27414.725852741616;27538.586921196198;27725;27903.441931161262;3241.6434192238835;3337.5160601712614;3338.132085657722;3345.1637090727318;3466.376052310583;204170;204531;205230;205233.94766052446;206021.9698901511;49808.501914980385;51015.7449212759;51671.741641292305;52228.21657675138;54764.726176368866;53479;53588;53662.463375366526;53788.924221516136;53963.19055214175;14671.33978970949;14775.482858099915;14813.259337033322;14824.481143159937;14880.330385132695;11185.440727963569;11208.103351731841;11239.381833999209;11260.605878794202;11279.097672186206;18028.188731507114;18139.72790408145;18186.36347727823;18202;18251.45245642633;7355.5586664800285;7517.8120546985665;7588.317051465395;7649;7664.156942736406;822.59692750553;856.3705676327894;911.7603145373931;921.6728061538057;1099.428297285407;22380.440488987904;22885.166321820812;23827.7617223829;27863.582046269323;28702.856485717857;961.3991255465335;964.5514835601479;1070.4861666400172;1098.368438148052;1104.8177050786692;13048.217106973612;13944.651383715285;14149.71700565983;14422.350994205286;14554.636134096521;252459;256157;258234;259273;259342.36247556828;46018;46094.30858537125;46133.76933115313;46245.53754462412;46308;1239.1387985350275;1244.464880101547;1247.214255019332;1251.993740031294;1253.7775668722923;26337.60493592636;26494;26726.732732672423;26944.595831062157;27048;39081.80459097686;39464.210715786096;39573.60426395814;39608.60391396049;39612.405813912235;244787;246061;248941;249019;249426;28009.57985630258;28523.714762852105;29141.271468213465;29186;29206;33849.830750846086;33944;34746.30507389788;34838;36043.81978090093;31430.685693143692;32040.35919281648;32176.678233218303;32348.83825580857;32626.673733262363;32047.519287210245;32336;32475.512867306537;32494.512582311756;32521;31804.522932155574;31935.520967185974;32661;33031;33531.66468335286;26730.732692673602;28873.133805985854;29385.118446446642;29603.40793184081;29671.703282966893;22630.321090367317;22892.54214915659;22968.28963795256;23159.30522084273;23308.417289567897 05:39:52 INFO - 2015-12-16 05:39:52,716 DEBUG : BROWSER_OUTPUT: |2;jquery.html;341.1505351674331;344.45919905747957;345.63880744621866;347.0871607671823;347.24127780798955;12554.560590379338;12883.613491595244;15061.62345941352;15155.54533363998;15858.127802970817;15169.54491365258;15318.770218446733;16420.917895410537;16457.588560286;16533.503994880142;4815.903681926359;4907.1412502812045;4949.7772600233075;4981.1532039553185;4986.227134794112;4819.325294458778;4975.850724478253;4981.626378021645;4982.676126051795;5079.415867175267;4897.902041959159;4939.20972644377;4946.109700253953;4960.454350021495;4977.303177555145;5107.642465027449;5220.843374698765;5233.895322093575;5240.3187585614005;5255.316808814868;5021.57316628087;5041.19340905457;5048.697078175322;5053.570446512051;5056.82301119459;10890.455477226107;12345.567905123278;17771.822281777146;18628.441146765617;18812.62374752511;1845.6770065238663;1854.4251282102591;1876.4558278099335;1901.5151136460252;1935.9709604355946;8516.148385161434;8525.06224315331;8548.316134709208;8648.956755216246;8680.782980425476;1737.7480265361578;1763.5855573940114;1771.2029586686042;1774.6894293498715;1838.7058070708622;53646;55618;55980.720096399666;57085.429145708425;57163;9759.756006099904;9897.604095836086;9994.900050999397;10003.499825008721;10035.598576056876;12575.245485270912;12663.303518306582;12681.492740290285;12702.237865728086;12703.30131842739;7095.432365410755;7186.353228209382;7226.602536860418;7363.226861179499;7406;6607.306232845482;6648.767293144813;6667.933320666828;6886.896696549556;6907.20567134786;4110.7328023678365;4181.268278051329;4196.559361267023;4225.830966761295;4232.640225580799;1761;1787.2850859656241;1795.2908601102752;1809.9276028958957;1817.1459414075628;864.7189663359434;870.4472659861034;875.6672464463463;921.2031592672424;951.695457453622;15149;15300.540983770505;15559;15752.842471575366;15837;867.8866958954965;873.0352959979258;888.4314039015036;889.1375365895071;964.782923842144;8848.513331766815;8853.29173666105;8961.283097352196;9095.272378209727;9417.387869788441;490505;498213;503021;511417;512805;68863.65568172227;72592.63703681447;72608;73052;73429;1046.4610725476387;1083.0360978728938;1087.6682611803317;1096.9396683182501;1129.9322040677587;13642;14057.718845623234;14269.71460570762;14283;14549.054311469721;32649.510257346636;33045.17387065342;33487.43498057548;34654.653453465144;34693.13267168341;40042.599574003885;40270.59729402669;40832;41350.79324603358;41657;8602.827943441222;8954.194122529003;9288.674896378594;9289.16397524226;9305.627774888928;16275.837241627432;16416.425425110065;16508.66982660364;16724.83275167233;16881.66236675284;13658.521951731642;13843.169409835442;14087;14154.716905661624;14303.427862885368;13504.797428038391;13735.519256825963;13812.171269723849;14047.086939349318;14205.928970355082;14030.508932187324;14108.858911410754;14182.92908535492;14302.141871487744;14659.48691795782;8970.327225457986;9107.772305692411;9154.130357616015;9197.574375971735;9218.861717074384;13665.79501307501;13686.65783355424;13720.313984300945;13796.931015344859;14368.784468233194 05:39:52 INFO - 2015-12-16 05:39:52,717 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1433.1830856411843;1449.6665766873618;1467.6917847252078;1630.7227771278883;1698.3461367373561;7050.224475307725;7146;7154.713811447542;7337.779866603997;7343.155537113234;7338.229485903983;7352.816179595513;7434.516756410843;7437.814054648637;7460.253974602537;2043.2848503023934;2090.4042347930845;2104.715863358442;2105.652567326397;2108.051376880402;2134.5410736691606;2148.4521447030984;2150.3656421355736;2152.300502336739;2165.577712346093;1705.6247625522428;1734.1329335332334;2066.5143691232547;2067.6071546406133;2097.4651463876767;2037.786032466592;2037.8165965063142;2046.7441569803775;2052.404802607257;2058.053295484079;2034.4608678700156;2046.2735728816206;2053.0966374795194;2070.927517536879;2077.511784730587;7269.563826170447;7295.562266264041;7326.267373262631;7326.963365183194;7339.045924029895;4191.832326706928;4194.3498757692305;4246.214450326699;4247.5540068293;4252.787360631956;2698.730126987305;2755.3524921643398;2761.3096725818546;2766.2669392610974;2805.340744924941;852.4501696405783;859.4542465534369;873.9737807865774;874.1040433555656;898.2050884966786;9454;9472.100150485692;9515.57179926905;9519.095685909828;9572;3711.387621042498;3733.4959780429836;3741.569719482294;3830.942535861964;3844.961860297704;7843.254890785366;7878.212178782076;7880.724174653859;7882.448228623941;7885.093214280431;4120.7733574653685;4132.979335103305;4139.441175441277;4142.71001029925;4164.37534369847;3816.866409675648;3820.426935959573;3832.3868181090893;3833.5783063862955;3844.6539811417106;3097.3495565930957;3101.8914337998062;3104.177392990859;3112.673169317234;3135.4826453634896;898.9640414383351;900.401233179928;922.5525620074259;927.3508544019213;931.1107891963159;913.2511340700562;913.5751875377981;925.3522534225934;932.4172392254841;1059.0733108530037;6814.965925170342;6836.077129587541;6858.177018757681;6880.621565813828;6911.205211400653;993.7366597851575;1017.6743442098457;1069.8930106989394;1074.9247552671238;1087.1900434176432;5397.082495975694;5448.318960129984;5451.672899626035;5479.205515200274;5489.972550137224;8413.495190288602;8452.450590711707;8633.438826476258;8730.388872778845;8792.78018049541;8614.698485552975;8616.48301101936;8629.870551941725;8652.78368040804;8698.043215246318;6718.563293385914;6758.648270345951;6844.247132815386;6869.484788640772;6891.448684105159;6564.113844630913;6579.934200658124;6584.539082264196;6613.801585952237;6618.569792963442;7590.734324298623;7653.693852245848;7795.493292936168;7808.882866757117;7812.296893279405;7804.648790804541;7936.6428510716005;7947.602619869099;7969.043714754266;7975;6140.3552626975315;6192.442680158807;6209.441150296495;6210.099535567408;6210.658413787285;6949.68726407323;7093.255208203064;7119.85760284781;7124.750633727795;7145.535540189871;6527.086207930798;6604.438622717123;6648.135742353463;6778.559393639398;6791.252962174058;5677.290338707661;6447.9355206447335;6502.317256687979;6594.769183078569;6636.734530618721;6485.6108633482145;6506.4469520091325;6517.869642607026;6527.771527996498;6531.640759758259;6015.1278064681255;6053;6077.027675571887;6098.0852872069545;6104.847378815565;6152.169457123231;6161.9691901542;6187.969060154851;6189.133521307111;6195 05:39:52 INFO - 2015-12-16 05:39:52,718 DEBUG : BROWSER_OUTPUT: |4;prototype.html;298.3153662344918;304.38210432821364;306.9094617087959;311.15676516639905;311.4533992842561;5598.188262701906;5620.213170156181;5687.289088863892;5763.279590051244;5823.213866128082;5619.915701264485;5673.914891276634;5673.943260567404;5685.431456854312;5689.3172819261745;4176.60322269384;4297.677674174433;4300.935485967713;4303.8063287152;4334.869953901388;4292.463442069741;4305.569443055685;4314.1156063007165;4318.546552611978;4326.545712700168;4220.873373798776;4251.8511852085185;4261.275583150857;4267.48790145183;4281.12236991416;4226.957730422687;4239.321708526636;4241.34259189826;4252.893677658068;4255.510616279105;3626.8186590670625;3693.741438099334;3700;3704.6480584344445;3704.833282502267;5317.8404647860625;5440.972795136038;5445.455454454563;5458.099413596791;5459.945400545984;4869.902601947942;4880.463149053601;4890.902181956377;4894.632902532324;4900.240462728283;5939.643621382731;5940.762369505214;5947.137665038588;5955.196048533479;5956.344802071769;3915.725899187058;4011.538673052607;4022.5172979242384;4023.6982226333143;4039.9192016159514;6193.2877719062435;6265.530085243579;6271.43557079865;6281.026440901667;6288.151099601541;4182.9581704182565;4184.184084103571;4185.24665560196;4194.769287689206;4196.286631272691;4255.553166917491;4256.936145957813;4258.723182993095;4259.5101563319995;4270.039241170712;4683.15703173432;4841.055994081191;4894.388201474816;4896.6572339935865;4901.852944411602;5890.617109887844;5910.468057874812;5910.911336329958;5913.970430147908;5916.083007133901;1471.2423102102211;1494.305148106114;1494.8953573249769;1496.6931778985281;1496.7305884940815;987.4914419074182;992.9106380425796;1004.6483730694345;1009.7879445316419;1029.1818004686293;5147.3308469898675;5158.92261616076;5159.613029022838;5165.044466773659;5177.249298851645;6103.115048317969;6132.294786099566;6143.38566143391;6146.416090471398;6147.938520614736;5141.820036298712;5143.305653736773;5146.742662866842;5156.04613146569;5156.149235376196;3236.288016636336;3289.9177520562175;3327.5507806446303;3336.115929278743;3337.9833100834826;5687.516561092353;5751;5798.768049278066;5817;5820.563457740686;6275;6312.431881130628;6313.905291420632;6327.778527751507;6327.96836015817;5791.102379131242;5824.796132135355;5827.125931110368;5837.532997360201;5837.912431313449;5978.073398623221;6154.169187159855;6183.227096612924;6184.721687523982;6189.752409903554;5993.520518358608;6057.7879774207695;6060.212172417734;6068.21113255274;6095;5836.912446313224;5842.269716285465;5848.181254624441;5852.531797456279;5863.20846685692;4244.002659374983;4283.507396649426;4311.288637374923;4314.71954322968;4315.741055536552;5943.821685349447;6106.6336019838955;6144.293406258337;6144.754209831557;6149.661768602591;5866.560007999503;5877.529797616266;5884.264466941632;5889.204957330706;5892.587518873809;5884.470397664059;5894.528437725057;5900.262467191601;5903.645781253139;5910.1725758392995;5632.436756324335;5735.8566035849435;5746.482816546531;5782.277215348082;5800.216970709069;5957.821265362046;6034.668093254913;6046;6047.365026672136;6061.3029501606115;5868.735906884286;5868.970655146697;5872.677002764889;5881.4118588142555;5886.882262354815 05:39:52 INFO - 2015-12-16 05:39:52,722 DEBUG : BROWSER_OUTPUT: |5;yui.html;697.368881162548;706.3536863769655;721.5021635071801;746.2984794293363;784.9293563579284;11836.822447663291;12059.577914772985;12116.81824772628;12128.575499857507;12154.635360939175;11061.170412219075;11240.550377984871;11260.493277802498;11295;11325.150613703963;635.3297271378693;642.6850843086877;793.2503783924194;815.6370415165254;822.56404105824;818.9017317921857;820.6922404098464;822.2024636103;827.5283088639478;829.5396055189367;850.3537311643165;856.3106699107216;861.8147098373848;862.8490014247513;864.1747131489444;850.6384786465758;851.6423102296989;852.9914700853005;855.5422848775906;855.6876739989859;630.0517720830121;630.3853742600974;633.5976654824174;634.8920683483819;635.7933671556763;11796.05631549474;11847.763044739057;11887.108466864933;11913.166078374517;11968;10817.459127043616;11237.550497980072;11267.26762760418;11488.88511114895;11761.294322340687;9308.488033158173;9314.720558383191;9322.48726320052;9325.580348884316;9456.385334953206;3176.6029246344206;3217.790843595159;3251.983740081308;3256.1208473711963;3272.4764037754085;39397.01507462308;39432.80283598564;39437.60562394368;39480.21039579221;39672.404913926315;9113.179813816789;9381.953090234641;9574.808503830023;9709.126178643955;9831;15896.046237225804;16016;16028.839711602968;16063.357465701241;16118.75821862673;5885.970570147122;6023.518118550505;6087.756489740449;6131.049687298476;6174.969125154345;3607.639236076371;3704.7777133372083;3719.8512059517316;3728.254349130185;3751.531058617673;2510.79913606911;2512.8869200886006;2545.350935511433;2547.0957809977563;2549.541082605149;456.66207006814716;460.82488654311817;467.49510528628997;470.6681789338508;473.4271531446919;544.8474427160402;615.6552330694735;617.305531277313;635.7838334966134;638.0970926139553;7740.187280335484;7827.921720782833;7902.209779022051;7938.682452701827;7958.6816527339415;471.90561887622613;518.5177784660227;570;626.8150895485843;656.4879394072631;3698.9075273118387;3721.2557488502407;3790.2067204086807;3853.537575490959;3871.7096217783214;49031;49457;49798.253026204635;49853.75073124611;49886;44408.3338749919;44675.77662111669;45059.098818023456;45693;45737.542624573325;37897.24205515819;37920;38831;39022;40775.1844963105;8032.759017229492;8108.270255677017;8562.614682339185;8576.842126313117;8674.132586741276;31373.21566960753;31543.68456315499;31637.209069772518;31975.520367194047;32098.67901320957;38645.6135438642;39184;39209.803950980066;39439;39731;10182.134518566005;10287.794244115226;10357.17142628603;10394.844077338696;10444.164466842787;11264.211505194886;11425.143114266297;11478.88521114778;11563.132765042486;11777.882221177679;10695;11155.27490713101;11269.774604508028;11361.204715670154;11622.82565761531;10510.47447627631;10841.457927103456;10867.239293249713;11227.101831853595;11306.265092768943;10034.19726421899;10217.642382516577;10311.329763565343;10377.533011014371;10536.262461627919;9115.179633832988;9342.439453632804;9442.15020648145;9523.52382380892;9819.656312029045;9013;9195.26437884981;9281.350305478552;9291.62833486653;9325.860112098188 05:39:52 INFO - 2015-12-16 05:39:52,722 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:39:52 INFO - 2015-12-16 05:39:52,722 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:39:52 INFO - 2015-12-16 05:39:52,722 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,320 05:39:52 INFO - 2015-12-16 05:39:52,722 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:39:52 INFO - 2015-12-16 05:39:52,722 DEBUG : BROWSER_OUTPUT: __startTimestamp1450273192707__endTimestamp 05:39:52 INFO - 2015-12-16 05:39:52,722 DEBUG : BROWSER_OUTPUT: 05:39:52 INFO - 2015-12-16 05:39:52,722 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:39:52 INFO - 2015-12-16 05:39:52,722 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:39:52 INFO - 2015-12-16 05:39:52,722 DEBUG : BROWSER_OUTPUT: 05:39:52 INFO - 2015-12-16 05:39:52,723 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4754.77 Median:4883.29 stddev:500.14 (10.2%) stddev-sans-first:474.45 05:39:52 INFO - 2015-12-16 05:39:52,723 DEBUG : BROWSER_OUTPUT: Values: 2679.8 2733.7 2769.1 2774.3 2781.8 4629.5 4829.3 4837.5 4873.7 4887.3 4696.9 4709.3 4714.5 4730.8 4833.3 4374.2 4462.8 4484.3 4502.6 4565.6 4267.5 4421.9 4529.0 4539.4 4559.6 4331.3 4423.1 4426.2 4456.1 4471.0 4442.4 4520.1 4527.8 4538.7 4585.2 4243.5 4389.7 4423.6 4443.6 4462.3 4644.0 4660.1 4743.1 4785.9 4792.5 5142.0 5148.3 5150.2 5181.6 5183.8 4984.2 5095.8 5188.0 5189.5 5202.2 4717.7 4782.4 4785.9 4792.4 4820.8 5344.2 5347.8 5365.6 5370.4 5381.2 4847.9 4974.3 5011.9 5121.0 5138.5 5234.1 5234.2 5238.4 5247.4 5253.6 5153.1 5161.1 5161.6 5162.1 5213.9 4877.8 4941.8 4957.2 4985.1 5144.9 4176.7 4214.7 4231.2 4253.1 4277.0 3767.1 3776.5 3790.0 3792.6 3821.4 4290.4 4391.7 4439.1 4504.0 4520.3 5072.7 5075.4 5133.2 5202.1 5249.5 4391.6 4475.6 4494.1 4497.3 4517.4 4385.1 4587.5 4592.3 4604.8 4639.8 4844.8 4883.3 4923.1 4937.7 4967.5 5107.5 5165.3 5196.6 5204.6 5242.5 4675.5 4768.3 4919.0 4947.1 4948.7 5016.9 5092.4 5120.4 5122.1 5157.5 5180.3 5180.4 5181.0 5188.9 5231.6 4732.3 4863.7 4918.6 4994.3 4995.6 5033.4 5041.9 5047.6 5054.2 5079.4 4912.9 4935.3 4945.1 4948.0 5009.5 4832.3 4934.9 4960.3 5023.5 5135.1 4963.9 4994.0 4997.8 5022.4 5061.9 05:39:52 INFO - 2015-12-16 05:39:52,723 DEBUG : BROWSER_OUTPUT: 05:39:52 INFO - 2015-12-16 05:39:52,723 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:39430.98 Median:32643.84 stddev:61820.44 (189.4%) stddev-sans-first:61924.91 05:39:52 INFO - 2015-12-16 05:39:52,723 DEBUG : BROWSER_OUTPUT: Values: 543.5 544.2 554.0 558.2 573.6 34840.8 35220.6 35332.8 35337.6 35385.0 39331.4 39791.2 39806.4 39896.0 39902.0 7749.9 7847.9 7908.5 7911.2 7915.1 7826.6 7878.6 7916.0 7920.5 7967.4 7855.0 7911.5 7919.1 7936.2 7942.9 7906.8 7993.4 8005.4 8011.4 8011.8 7830.2 7936.3 7959.8 7966.5 7974.6 35951.3 36133.5 36195.5 36264.5 36364.5 2885.0 2944.8 2966.5 3193.0 3276.2 27201.9 27414.7 27538.6 27725.0 27903.4 3241.6 3337.5 3338.1 3345.2 3466.4 204170.0 204531.0 205230.0 205233.9 206022.0 49808.5 51015.7 51671.7 52228.2 54764.7 53479.0 53588.0 53662.5 53788.9 53963.2 14671.3 14775.5 14813.3 14824.5 14880.3 11185.4 11208.1 11239.4 11260.6 11279.1 18028.2 18139.7 18186.4 18202.0 18251.5 7355.6 7517.8 7588.3 7649.0 7664.2 822.6 856.4 911.8 921.7 1099.4 22380.4 22885.2 23827.8 27863.6 28702.9 961.4 964.6 1070.5 1098.4 1104.8 13048.2 13944.7 14149.7 14422.4 14554.6 252459.0 256157.0 258234.0 259273.0 259342.4 46018.0 46094.3 46133.8 46245.5 46308.0 1239.1 1244.5 1247.2 1252.0 1253.8 26337.6 26494.0 26726.7 26944.6 27048.0 39081.8 39464.2 39573.6 39608.6 39612.4 244787.0 246061.0 248941.0 249019.0 249426.0 28009.6 28523.7 29141.3 29186.0 29206.0 33849.8 33944.0 34746.3 34838.0 36043.8 31430.7 32040.4 32176.7 32348.8 32626.7 32047.5 32336.0 32475.5 32494.5 32521.0 31804.5 31935.5 32661.0 33031.0 33531.7 26730.7 28873.1 29385.1 29603.4 29671.7 22630.3 22892.5 22968.3 23159.3 23308.4 05:39:52 INFO - 2015-12-16 05:39:52,723 DEBUG : BROWSER_OUTPUT: 05:39:52 INFO - 2015-12-16 05:39:52,723 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26786.99 Median:22730.76 stddev:82154.53 (361.4%) stddev-sans-first:82360.99 05:39:52 INFO - 2015-12-16 05:39:52,723 DEBUG : BROWSER_OUTPUT: Values: 341.2 344.5 345.6 347.1 347.2 12554.6 12883.6 15061.6 15155.5 15858.1 15169.5 15318.8 16420.9 16457.6 16533.5 4815.9 4907.1 4949.8 4981.2 4986.2 4819.3 4975.9 4981.6 4982.7 5079.4 4897.9 4939.2 4946.1 4960.5 4977.3 5107.6 5220.8 5233.9 5240.3 5255.3 5021.6 5041.2 5048.7 5053.6 5056.8 10890.5 12345.6 17771.8 18628.4 18812.6 1845.7 1854.4 1876.5 1901.5 1936.0 8516.1 8525.1 8548.3 8649.0 8680.8 1737.7 1763.6 1771.2 1774.7 1838.7 53646.0 55618.0 55980.7 57085.4 57163.0 9759.8 9897.6 9994.9 10003.5 10035.6 12575.2 12663.3 12681.5 12702.2 12703.3 7095.4 7186.4 7226.6 7363.2 7406.0 6607.3 6648.8 6667.9 6886.9 6907.2 4110.7 4181.3 4196.6 4225.8 4232.6 1761.0 1787.3 1795.3 1809.9 1817.1 864.7 870.4 875.7 921.2 951.7 15149.0 15300.5 15559.0 15752.8 15837.0 867.9 873.0 888.4 889.1 964.8 8848.5 8853.3 8961.3 9095.3 9417.4 490505.0 498213.0 503021.0 511417.0 512805.0 68863.7 72592.6 72608.0 73052.0 73429.0 1046.5 1083.0 1087.7 1096.9 1129.9 13642.0 14057.7 14269.7 14283.0 14549.1 32649.5 33045.2 33487.4 34654.7 34693.1 40042.6 40270.6 40832.0 41350.8 41657.0 8602.8 8954.2 9288.7 9289.2 9305.6 16275.8 16416.4 16508.7 16724.8 16881.7 13658.5 13843.2 14087.0 14154.7 14303.4 13504.8 13735.5 13812.2 14047.1 14205.9 14030.5 14108.9 14182.9 14302.1 14659.5 8970.3 9107.8 9154.1 9197.6 9218.9 13665.8 13686.7 13720.3 13796.9 14368.8 05:39:52 INFO - 2015-12-16 05:39:52,723 DEBUG : BROWSER_OUTPUT: 05:39:52 INFO - 2015-12-16 05:39:52,723 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5012.44 Median:6522.48 stddev:2620.33 (40.2%) stddev-sans-first:2613.87 05:39:52 INFO - 2015-12-16 05:39:52,724 DEBUG : BROWSER_OUTPUT: Values: 1433.2 1449.7 1467.7 1630.7 1698.3 7050.2 7146.0 7154.7 7337.8 7343.2 7338.2 7352.8 7434.5 7437.8 7460.3 2043.3 2090.4 2104.7 2105.7 2108.1 2134.5 2148.5 2150.4 2152.3 2165.6 1705.6 1734.1 2066.5 2067.6 2097.5 2037.8 2037.8 2046.7 2052.4 2058.1 2034.5 2046.3 2053.1 2070.9 2077.5 7269.6 7295.6 7326.3 7327.0 7339.0 4191.8 4194.3 4246.2 4247.6 4252.8 2698.7 2755.4 2761.3 2766.3 2805.3 852.5 859.5 874.0 874.1 898.2 9454.0 9472.1 9515.6 9519.1 9572.0 3711.4 3733.5 3741.6 3830.9 3845.0 7843.3 7878.2 7880.7 7882.4 7885.1 4120.8 4133.0 4139.4 4142.7 4164.4 3816.9 3820.4 3832.4 3833.6 3844.7 3097.3 3101.9 3104.2 3112.7 3135.5 899.0 900.4 922.6 927.4 931.1 913.3 913.6 925.4 932.4 1059.1 6815.0 6836.1 6858.2 6880.6 6911.2 993.7 1017.7 1069.9 1074.9 1087.2 5397.1 5448.3 5451.7 5479.2 5490.0 8413.5 8452.5 8633.4 8730.4 8792.8 8614.7 8616.5 8629.9 8652.8 8698.0 6718.6 6758.6 6844.2 6869.5 6891.4 6564.1 6579.9 6584.5 6613.8 6618.6 7590.7 7653.7 7795.5 7808.9 7812.3 7804.6 7936.6 7947.6 7969.0 7975.0 6140.4 6192.4 6209.4 6210.1 6210.7 6949.7 7093.3 7119.9 7124.8 7145.5 6527.1 6604.4 6648.1 6778.6 6791.3 5677.3 6447.9 6502.3 6594.8 6636.7 6485.6 6506.4 6517.9 6527.8 6531.6 6015.1 6053.0 6077.0 6098.1 6104.8 6152.2 6162.0 6188.0 6189.1 6195.0 05:39:52 INFO - 2015-12-16 05:39:52,725 DEBUG : BROWSER_OUTPUT: 05:39:52 INFO - 2015-12-16 05:39:52,725 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4894.19 Median:5626.32 stddev:1461.36 (26.0%) stddev-sans-first:1424.17 05:39:52 INFO - 2015-12-16 05:39:52,725 DEBUG : BROWSER_OUTPUT: Values: 298.3 304.4 306.9 311.2 311.5 5598.2 5620.2 5687.3 5763.3 5823.2 5619.9 5673.9 5673.9 5685.4 5689.3 4176.6 4297.7 4300.9 4303.8 4334.9 4292.5 4305.6 4314.1 4318.5 4326.5 4220.9 4251.9 4261.3 4267.5 4281.1 4227.0 4239.3 4241.3 4252.9 4255.5 3626.8 3693.7 3700.0 3704.6 3704.8 5317.8 5441.0 5445.5 5458.1 5459.9 4869.9 4880.5 4890.9 4894.6 4900.2 5939.6 5940.8 5947.1 5955.2 5956.3 3915.7 4011.5 4022.5 4023.7 4039.9 6193.3 6265.5 6271.4 6281.0 6288.2 4183.0 4184.2 4185.2 4194.8 4196.3 4255.6 4256.9 4258.7 4259.5 4270.0 4683.2 4841.1 4894.4 4896.7 4901.9 5890.6 5910.5 5910.9 5914.0 5916.1 1471.2 1494.3 1494.9 1496.7 1496.7 987.5 992.9 1004.6 1009.8 1029.2 5147.3 5158.9 5159.6 5165.0 5177.2 6103.1 6132.3 6143.4 6146.4 6147.9 5141.8 5143.3 5146.7 5156.0 5156.1 3236.3 3289.9 3327.6 3336.1 3338.0 5687.5 5751.0 5798.8 5817.0 5820.6 6275.0 6312.4 6313.9 6327.8 6328.0 5791.1 5824.8 5827.1 5837.5 5837.9 5978.1 6154.2 6183.2 6184.7 6189.8 5993.5 6057.8 6060.2 6068.2 6095.0 5836.9 5842.3 5848.2 5852.5 5863.2 4244.0 4283.5 4311.3 4314.7 4315.7 5943.8 6106.6 6144.3 6144.8 6149.7 5866.6 5877.5 5884.3 5889.2 5892.6 5884.5 5894.5 5900.3 5903.6 5910.2 5632.4 5735.9 5746.5 5782.3 5800.2 5957.8 6034.7 6046.0 6047.4 6061.3 5868.7 5869.0 5872.7 5881.4 5886.9 05:39:52 INFO - 2015-12-16 05:39:52,725 DEBUG : BROWSER_OUTPUT: 05:39:52 INFO - 2015-12-16 05:39:52,725 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12230.20 Median:44542.06 stddev:13670.92 (30.7%) stddev-sans-first:13681.84 05:39:52 INFO - 2015-12-16 05:39:52,725 DEBUG : BROWSER_OUTPUT: Values: 697.4 706.4 721.5 746.3 784.9 11836.8 12059.6 12116.8 12128.6 12154.6 11061.2 11240.6 11260.5 11295.0 11325.2 635.3 642.7 793.3 815.6 822.6 818.9 820.7 822.2 827.5 829.5 850.4 856.3 861.8 862.8 864.2 850.6 851.6 853.0 855.5 855.7 630.1 630.4 633.6 634.9 635.8 11796.1 11847.8 11887.1 11913.2 11968.0 10817.5 11237.6 11267.3 11488.9 11761.3 9308.5 9314.7 9322.5 9325.6 9456.4 3176.6 3217.8 3252.0 3256.1 3272.5 39397.0 39432.8 39437.6 39480.2 39672.4 9113.2 9382.0 9574.8 9709.1 9831.0 15896.0 16016.0 16028.8 16063.4 16118.8 5886.0 6023.5 6087.8 6131.0 6175.0 3607.6 3704.8 3719.9 3728.3 3751.5 2510.8 2512.9 2545.4 2547.1 2549.5 456.7 460.8 467.5 470.7 473.4 544.8 615.7 617.3 635.8 638.1 7740.2 7827.9 7902.2 7938.7 7958.7 471.9 518.5 570.0 626.8 656.5 3698.9 3721.3 3790.2 3853.5 3871.7 49031.0 49457.0 49798.3 49853.8 49886.0 44408.3 44675.8 45059.1 45693.0 45737.5 37897.2 37920.0 38831.0 39022.0 40775.2 8032.8 8108.3 8562.6 8576.8 8674.1 31373.2 31543.7 31637.2 31975.5 32098.7 38645.6 39184.0 39209.8 39439.0 39731.0 10182.1 10287.8 10357.2 10394.8 10444.2 11264.2 11425.1 11478.9 11563.1 11777.9 10695.0 11155.3 11269.8 11361.2 11622.8 10510.5 10841.5 10867.2 11227.1 11306.3 10034.2 10217.6 10311.3 10377.5 10536.3 9115.2 9342.4 9442.2 9523.5 9819.7 9013.0 9195.3 9281.4 9291.6 9325.9 05:39:52 INFO - 2015-12-16 05:39:52,725 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:39:52 INFO - 2015-12-16 05:39:52,725 DEBUG : BROWSER_OUTPUT: 05:39:53 INFO - 2015-12-16 05:39:53,425 INFO : Browser exited with error code: 0 05:39:53 INFO - 2015-12-16 05:39:53,449 INFO : Completed test dromaeo_css (00:18:35) 05:39:53 INFO - 2015-12-16 05:39:53,449 INFO : Starting test kraken 05:39:53 INFO - 2015-12-16 05:39:53,449 DEBUG : operating with platform_type : linux_ 05:39:53 INFO - 2015-12-16 05:39:53,449 INFO : Initialising browser for kraken test... 05:39:53 INFO - 2015-12-16 05:39:53,456 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiGnSYr/profile http://localhost:49543/getInfo.html 05:39:58 INFO - 2015-12-16 05:39:58,269 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:39:58 INFO - 2015-12-16 05:39:58,269 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:39:58 INFO - 2015-12-16 05:39:58,269 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:39:58 INFO - 2015-12-16 05:39:58,269 DEBUG : BROWSER_OUTPUT: __metrics 05:39:58 INFO - 2015-12-16 05:39:58,365 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] 05:39:59 INFO - 2015-12-16 05:39:59,297 INFO : Browser initialized. 05:39:59 INFO - 2015-12-16 05:39:59,298 INFO : Running cycle 1/1 for kraken test... 05:39:59 INFO - 2015-12-16 05:39:59,298 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiGnSYr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:40:11 INFO - 2015-12-16 05:40:11,296 DEBUG : BROWSER_OUTPUT: RSS: Main: 143036416 05:40:11 INFO - 2015-12-16 05:40:11,296 DEBUG : BROWSER_OUTPUT: 05:40:13 INFO - 2015-12-16 05:40:13,881 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49543/tests/kraken/driver.html?testName=audio-beat-detection) 05:40:14 INFO - 2015-12-16 05:40:14,510 DEBUG : BROWSER_OUTPUT: RSS: Main: 143900672 05:40:14 INFO - 2015-12-16 05:40:14,510 DEBUG : BROWSER_OUTPUT: 05:40:17 INFO - 2015-12-16 05:40:17,235 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49543/tests/kraken/driver.html?testName=audio-dft) 05:40:17 INFO - 2015-12-16 05:40:17,849 DEBUG : BROWSER_OUTPUT: RSS: Main: 144257024 05:40:17 INFO - 2015-12-16 05:40:17,849 DEBUG : BROWSER_OUTPUT: 05:40:20 INFO - 2015-12-16 05:40:20,789 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49543/tests/kraken/driver.html?testName=audio-fft) 05:40:21 INFO - 2015-12-16 05:40:21,370 DEBUG : BROWSER_OUTPUT: RSS: Main: 138412032 05:40:21 INFO - 2015-12-16 05:40:21,370 DEBUG : BROWSER_OUTPUT: 05:40:23 INFO - 2015-12-16 05:40:23,469 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49543/tests/kraken/driver.html?testName=audio-oscillator) 05:40:24 INFO - 2015-12-16 05:40:24,055 DEBUG : BROWSER_OUTPUT: RSS: Main: 138080256 05:40:24 INFO - 2015-12-16 05:40:24,055 DEBUG : BROWSER_OUTPUT: 05:40:26 INFO - 2015-12-16 05:40:26,349 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49543/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:40:26 INFO - 2015-12-16 05:40:26,950 DEBUG : BROWSER_OUTPUT: RSS: Main: 138735616 05:40:26 INFO - 2015-12-16 05:40:26,951 DEBUG : BROWSER_OUTPUT: 05:40:30 INFO - 2015-12-16 05:40:30,041 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49543/tests/kraken/driver.html?testName=imaging-darkroom) 05:40:30 INFO - 2015-12-16 05:40:30,647 DEBUG : BROWSER_OUTPUT: RSS: Main: 139890688 05:40:30 INFO - 2015-12-16 05:40:30,647 DEBUG : BROWSER_OUTPUT: 05:40:34 INFO - 2015-12-16 05:40:34,095 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49543/tests/kraken/driver.html?testName=imaging-desaturate) 05:40:34 INFO - 2015-12-16 05:40:34,689 DEBUG : BROWSER_OUTPUT: RSS: Main: 140681216 05:40:34 INFO - 2015-12-16 05:40:34,689 DEBUG : BROWSER_OUTPUT: 05:40:37 INFO - 2015-12-16 05:40:37,581 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49543/tests/kraken/driver.html?testName=json-parse-financial) 05:40:38 INFO - 2015-12-16 05:40:38,182 DEBUG : BROWSER_OUTPUT: RSS: Main: 140746752 05:40:38 INFO - 2015-12-16 05:40:38,183 DEBUG : BROWSER_OUTPUT: 05:40:39 INFO - 2015-12-16 05:40:39,956 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49543/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:40:40 INFO - 2015-12-16 05:40:40,546 DEBUG : BROWSER_OUTPUT: RSS: Main: 141086720 05:40:40 INFO - 2015-12-16 05:40:40,547 DEBUG : BROWSER_OUTPUT: 05:40:42 INFO - 2015-12-16 05:40:42,455 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49543/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:40:43 INFO - 2015-12-16 05:40:43,065 DEBUG : BROWSER_OUTPUT: RSS: Main: 140156928 05:40:43 INFO - 2015-12-16 05:40:43,065 DEBUG : BROWSER_OUTPUT: 05:40:45 INFO - 2015-12-16 05:40:45,278 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49543/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:40:45 INFO - 2015-12-16 05:40:45,865 DEBUG : BROWSER_OUTPUT: RSS: Main: 139907072 05:40:45 INFO - 2015-12-16 05:40:45,865 DEBUG : BROWSER_OUTPUT: 05:40:48 INFO - 2015-12-16 05:40:48,460 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49543/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:40:49 INFO - 2015-12-16 05:40:49,062 DEBUG : BROWSER_OUTPUT: RSS: Main: 140730368 05:40:49 INFO - 2015-12-16 05:40:49,062 DEBUG : BROWSER_OUTPUT: 05:40:51 INFO - 2015-12-16 05:40:51,704 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49543/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:40:52 INFO - 2015-12-16 05:40:52,306 DEBUG : BROWSER_OUTPUT: RSS: Main: 140288000 05:40:52 INFO - 2015-12-16 05:40:52,306 DEBUG : BROWSER_OUTPUT: 05:40:54 INFO - 2015-12-16 05:40:54,116 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49543/tests/kraken/driver.html?testName=ai-astar) 05:40:54 INFO - 2015-12-16 05:40:54,362 DEBUG : BROWSER_OUTPUT: RSS: Main: 141676544 05:40:54 INFO - 2015-12-16 05:40:54,362 DEBUG : BROWSER_OUTPUT: 05:40:54 INFO - 2015-12-16 05:40:54,364 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:40:54 INFO - 2015-12-16 05:40:54,364 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:40:54 INFO - 2015-12-16 05:40:54,364 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:40:54 INFO - 2015-12-16 05:40:54,364 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:40:54 INFO - 2015-12-16 05:40:54,364 DEBUG : BROWSER_OUTPUT: |0;ai-astar;115;110;115;113;116;128;116;115;115;117 05:40:54 INFO - 2015-12-16 05:40:54,364 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;157;133;155;164;130;152;161;137;170;131 05:40:54 INFO - 2015-12-16 05:40:54,365 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;165;165;164;162;165;168;164;167;164 05:40:54 INFO - 2015-12-16 05:40:54,365 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;73;72;144;71;79;72;72;94 05:40:54 INFO - 2015-12-16 05:40:54,365 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;95;139;96;96;96;132;94;97;135 05:40:54 INFO - 2015-12-16 05:40:54,365 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;110;110;111;111;111;111;111 05:40:54 INFO - 2015-12-16 05:40:54,365 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;148;149;148;149;148;149;148;148;148 05:40:54 INFO - 2015-12-16 05:40:54,365 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;97;96;96;97;96;96;95;96;97 05:40:54 INFO - 2015-12-16 05:40:54,365 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;74;77;74;73;74;76;73;73;74;73 05:40:54 INFO - 2015-12-16 05:40:54,366 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;68;68;69;69;70;70;70;72;69 05:40:54 INFO - 2015-12-16 05:40:54,366 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;88;86;85;96;85;85;88;87;88;103 05:40:54 INFO - 2015-12-16 05:40:54,366 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;120;122;124;124;124;132;132;133;122;123 05:40:54 INFO - 2015-12-16 05:40:54,367 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;153;154;150;154;143;149;150;152;139 05:40:54 INFO - 2015-12-16 05:40:54,367 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;73;69;69;70;71;71;72;73;70;73 05:40:54 INFO - 2015-12-16 05:40:54,367 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:40:54 INFO - 2015-12-16 05:40:54,367 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:40:54 INFO - 2015-12-16 05:40:54,367 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,813 05:40:54 INFO - 2015-12-16 05:40:54,367 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:40:54 INFO - 2015-12-16 05:40:54,367 DEBUG : BROWSER_OUTPUT: __startTimestamp1450273254363__endTimestamp 05:40:54 INFO - 2015-12-16 05:40:54,367 DEBUG : BROWSER_OUTPUT: 05:40:54 INFO - 2015-12-16 05:40:54,369 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:40:54 INFO - 2015-12-16 05:40:54,369 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:40:54 INFO - 2015-12-16 05:40:54,369 DEBUG : BROWSER_OUTPUT: 05:40:54 INFO - 2015-12-16 05:40:54,369 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:116.00 Median:115.50 stddev:4.64 (4.0%) stddev-sans-first:4.91 05:40:54 INFO - 2015-12-16 05:40:54,369 DEBUG : BROWSER_OUTPUT: Values: 115.0 110.0 115.0 113.0 116.0 128.0 116.0 115.0 115.0 117.0 05:40:54 INFO - 2015-12-16 05:40:54,369 DEBUG : BROWSER_OUTPUT: 05:40:54 INFO - 2015-12-16 05:40:54,369 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:149.00 Median:156.00 stddev:14.92 (9.6%) stddev-sans-first:15.54 05:40:54 INFO - 2015-12-16 05:40:54,369 DEBUG : BROWSER_OUTPUT: Values: 157.0 133.0 155.0 164.0 130.0 152.0 161.0 137.0 170.0 131.0 05:40:54 INFO - 2015-12-16 05:40:54,369 DEBUG : BROWSER_OUTPUT: 05:40:54 INFO - 2015-12-16 05:40:54,369 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:164.70 Median:165.00 stddev:1.77 (1.1%) stddev-sans-first:1.76 05:40:54 INFO - 2015-12-16 05:40:54,369 DEBUG : BROWSER_OUTPUT: Values: 163.0 165.0 165.0 164.0 162.0 165.0 168.0 164.0 167.0 164.0 05:40:54 INFO - 2015-12-16 05:40:54,369 DEBUG : BROWSER_OUTPUT: 05:40:54 INFO - 2015-12-16 05:40:54,370 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:82.10 Median:72.00 stddev:22.85 (31.7%) stddev-sans-first:23.94 05:40:54 INFO - 2015-12-16 05:40:54,370 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 73.0 72.0 144.0 71.0 79.0 72.0 72.0 94.0 05:40:54 INFO - 2015-12-16 05:40:54,370 DEBUG : BROWSER_OUTPUT: 05:40:54 INFO - 2015-12-16 05:40:54,370 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.40 Median:95.50 stddev:19.37 (20.3%) stddev-sans-first:19.93 05:40:54 INFO - 2015-12-16 05:40:54,370 DEBUG : BROWSER_OUTPUT: Values: 94.0 95.0 139.0 96.0 96.0 96.0 132.0 94.0 97.0 135.0 05:40:54 INFO - 2015-12-16 05:40:54,370 DEBUG : BROWSER_OUTPUT: 05:40:54 INFO - 2015-12-16 05:40:54,370 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.50 Median:111.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 05:40:54 INFO - 2015-12-16 05:40:54,370 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 110.0 110.0 111.0 111.0 111.0 111.0 111.0 05:40:54 INFO - 2015-12-16 05:40:54,370 DEBUG : BROWSER_OUTPUT: 05:40:54 INFO - 2015-12-16 05:40:54,370 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:148.30 Median:148.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 05:40:54 INFO - 2015-12-16 05:40:54,370 DEBUG : BROWSER_OUTPUT: Values: 148.0 148.0 149.0 148.0 149.0 148.0 149.0 148.0 148.0 148.0 05:40:54 INFO - 2015-12-16 05:40:54,371 DEBUG : BROWSER_OUTPUT: 05:40:54 INFO - 2015-12-16 05:40:54,371 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 05:40:54 INFO - 2015-12-16 05:40:54,371 DEBUG : BROWSER_OUTPUT: Values: 96.0 97.0 96.0 96.0 97.0 96.0 96.0 95.0 96.0 97.0 05:40:54 INFO - 2015-12-16 05:40:54,371 DEBUG : BROWSER_OUTPUT: 05:40:54 INFO - 2015-12-16 05:40:54,371 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:74.10 Median:74.00 stddev:1.37 (1.9%) stddev-sans-first:1.45 05:40:54 INFO - 2015-12-16 05:40:54,371 DEBUG : BROWSER_OUTPUT: Values: 74.0 77.0 74.0 73.0 74.0 76.0 73.0 73.0 74.0 73.0 05:40:54 INFO - 2015-12-16 05:40:54,371 DEBUG : BROWSER_OUTPUT: 05:40:54 INFO - 2015-12-16 05:40:54,371 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:69.50 stddev:1.17 (1.7%) stddev-sans-first:1.24 05:40:54 INFO - 2015-12-16 05:40:54,371 DEBUG : BROWSER_OUTPUT: Values: 69.0 68.0 68.0 69.0 69.0 70.0 70.0 70.0 72.0 69.0 05:40:54 INFO - 2015-12-16 05:40:54,372 DEBUG : BROWSER_OUTPUT: 05:40:54 INFO - 2015-12-16 05:40:54,372 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:89.10 Median:87.50 stddev:5.86 (6.7%) stddev-sans-first:6.20 05:40:54 INFO - 2015-12-16 05:40:54,372 DEBUG : BROWSER_OUTPUT: Values: 88.0 86.0 85.0 96.0 85.0 85.0 88.0 87.0 88.0 103.0 05:40:54 INFO - 2015-12-16 05:40:54,372 DEBUG : BROWSER_OUTPUT: 05:40:54 INFO - 2015-12-16 05:40:54,372 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:125.60 Median:124.00 stddev:4.81 (3.9%) stddev-sans-first:4.66 05:40:54 INFO - 2015-12-16 05:40:54,372 DEBUG : BROWSER_OUTPUT: Values: 120.0 122.0 124.0 124.0 124.0 132.0 132.0 133.0 122.0 123.0 05:40:54 INFO - 2015-12-16 05:40:54,372 DEBUG : BROWSER_OUTPUT: 05:40:54 INFO - 2015-12-16 05:40:54,372 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.30 Median:151.00 stddev:5.85 (3.9%) stddev-sans-first:5.15 05:40:54 INFO - 2015-12-16 05:40:54,372 DEBUG : BROWSER_OUTPUT: Values: 139.0 153.0 154.0 150.0 154.0 143.0 149.0 150.0 152.0 139.0 05:40:54 INFO - 2015-12-16 05:40:54,372 DEBUG : BROWSER_OUTPUT: 05:40:54 INFO - 2015-12-16 05:40:54,372 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.10 Median:71.50 stddev:1.60 (2.2%) stddev-sans-first:1.54 05:40:54 INFO - 2015-12-16 05:40:54,372 DEBUG : BROWSER_OUTPUT: Values: 73.0 69.0 69.0 70.0 71.0 71.0 72.0 73.0 70.0 73.0 05:40:54 INFO - 2015-12-16 05:40:54,373 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:40:54 INFO - 2015-12-16 05:40:54,373 DEBUG : BROWSER_OUTPUT: 05:40:55 INFO - 2015-12-16 05:40:55,317 INFO : Browser exited with error code: 0 05:40:55 INFO - 2015-12-16 05:40:55,367 INFO : Completed test kraken (00:01:01) 05:40:55 INFO - 2015-12-16 05:40:55,367 INFO : Starting test v8_7 05:40:55 INFO - 2015-12-16 05:40:55,367 DEBUG : operating with platform_type : linux_ 05:40:55 INFO - 2015-12-16 05:40:55,367 INFO : Initialising browser for v8_7 test... 05:40:55 INFO - 2015-12-16 05:40:55,373 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVhxk1O/profile http://localhost:49543/getInfo.html 05:41:00 INFO - 2015-12-16 05:41:00,081 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:41:00 INFO - 2015-12-16 05:41:00,081 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:41:00 INFO - 2015-12-16 05:41:00,082 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:41:00 INFO - 2015-12-16 05:41:00,082 DEBUG : BROWSER_OUTPUT: __metrics 05:41:00 INFO - 2015-12-16 05:41:00,206 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] 05:41:01 INFO - 2015-12-16 05:41:01,153 INFO : Browser initialized. 05:41:01 INFO - 2015-12-16 05:41:01,154 INFO : Running cycle 1/1 for v8_7 test... 05:41:01 INFO - 2015-12-16 05:41:01,154 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVhxk1O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:41:13 INFO - 2015-12-16 05:41:13,155 DEBUG : BROWSER_OUTPUT: RSS: Main: 142921728 05:41:13 INFO - 2015-12-16 05:41:13,156 DEBUG : BROWSER_OUTPUT: 05:41:34 INFO - 2015-12-16 05:41:34,573 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49543/tests/v8_7/run.html (next: http://localhost:49543/tests/v8_7/run.html) 05:41:34 INFO - 2015-12-16 05:41:34,823 DEBUG : BROWSER_OUTPUT: RSS: Main: 138067968 05:41:34 INFO - 2015-12-16 05:41:34,823 DEBUG : BROWSER_OUTPUT: 05:41:34 INFO - 2015-12-16 05:41:34,823 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:41:34 INFO - 2015-12-16 05:41:34,824 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:41:34 INFO - 2015-12-16 05:41:34,824 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:41:34 INFO - 2015-12-16 05:41:34,824 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:41:34 INFO - 2015-12-16 05:41:34,824 DEBUG : BROWSER_OUTPUT: |0;Richards;164.90765171503958 05:41:34 INFO - 2015-12-16 05:41:34,824 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;192.34468166955185 05:41:34 INFO - 2015-12-16 05:41:34,824 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.3296089385475 05:41:34 INFO - 2015-12-16 05:41:34,825 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5191.709844559586 05:41:34 INFO - 2015-12-16 05:41:34,825 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1062.6992561105208 05:41:34 INFO - 2015-12-16 05:41:34,825 DEBUG : BROWSER_OUTPUT: |5;Earley;700.770847932726 05:41:34 INFO - 2015-12-16 05:41:34,825 DEBUG : BROWSER_OUTPUT: |6;Boyer;12280.487804878048 05:41:34 INFO - 2015-12-16 05:41:34,826 DEBUG : BROWSER_OUTPUT: |7;RegExp;26789.473684210527 05:41:34 INFO - 2015-12-16 05:41:34,826 DEBUG : BROWSER_OUTPUT: |8;Splay;602.7727546714889 05:41:34 INFO - 2015-12-16 05:41:34,826 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4859.223300970873 05:41:34 INFO - 2015-12-16 05:41:34,826 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:41:34 INFO - 2015-12-16 05:41:34,826 DEBUG : BROWSER_OUTPUT: __startTimestamp1450273294822__endTimestamp 05:41:34 INFO - 2015-12-16 05:41:34,827 DEBUG : BROWSER_OUTPUT: 05:41:34 INFO - 2015-12-16 05:41:34,827 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:41:34 INFO - 2015-12-16 05:41:34,827 DEBUG : BROWSER_OUTPUT: Number of tests: 10 05:41:34 INFO - 2015-12-16 05:41:34,827 DEBUG : BROWSER_OUTPUT: 05:41:34 INFO - 2015-12-16 05:41:34,828 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.91 Median:164.91 stddev:0.00 (0.0%) 05:41:34 INFO - 2015-12-16 05:41:34,828 DEBUG : BROWSER_OUTPUT: Values: 164.9 05:41:34 INFO - 2015-12-16 05:41:34,828 DEBUG : BROWSER_OUTPUT: 05:41:34 INFO - 2015-12-16 05:41:34,828 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:192.34 Median:192.34 stddev:0.00 (0.0%) 05:41:34 INFO - 2015-12-16 05:41:34,829 DEBUG : BROWSER_OUTPUT: Values: 192.3 05:41:34 INFO - 2015-12-16 05:41:34,829 DEBUG : BROWSER_OUTPUT: 05:41:34 INFO - 2015-12-16 05:41:34,829 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.33 Median:279.33 stddev:0.00 (0.0%) 05:41:34 INFO - 2015-12-16 05:41:34,829 DEBUG : BROWSER_OUTPUT: Values: 279.3 05:41:34 INFO - 2015-12-16 05:41:34,829 DEBUG : BROWSER_OUTPUT: 05:41:34 INFO - 2015-12-16 05:41:34,829 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5191.71 Median:5191.71 stddev:0.00 (0.0%) 05:41:34 INFO - 2015-12-16 05:41:34,829 DEBUG : BROWSER_OUTPUT: Values: 5191.7 05:41:34 INFO - 2015-12-16 05:41:34,830 DEBUG : BROWSER_OUTPUT: 05:41:34 INFO - 2015-12-16 05:41:34,830 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1062.70 Median:1062.70 stddev:0.00 (0.0%) 05:41:34 INFO - 2015-12-16 05:41:34,830 DEBUG : BROWSER_OUTPUT: Values: 1062.7 05:41:34 INFO - 2015-12-16 05:41:34,830 DEBUG : BROWSER_OUTPUT: 05:41:34 INFO - 2015-12-16 05:41:34,830 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:700.77 Median:700.77 stddev:0.00 (0.0%) 05:41:34 INFO - 2015-12-16 05:41:34,830 DEBUG : BROWSER_OUTPUT: Values: 700.8 05:41:34 INFO - 2015-12-16 05:41:34,830 DEBUG : BROWSER_OUTPUT: 05:41:34 INFO - 2015-12-16 05:41:34,830 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12280.49 Median:12280.49 stddev:0.00 (0.0%) 05:41:34 INFO - 2015-12-16 05:41:34,830 DEBUG : BROWSER_OUTPUT: Values: 12280.5 05:41:34 INFO - 2015-12-16 05:41:34,830 DEBUG : BROWSER_OUTPUT: 05:41:34 INFO - 2015-12-16 05:41:34,831 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26789.47 Median:26789.47 stddev:0.00 (0.0%) 05:41:34 INFO - 2015-12-16 05:41:34,832 DEBUG : BROWSER_OUTPUT: Values: 26789.5 05:41:34 INFO - 2015-12-16 05:41:34,832 DEBUG : BROWSER_OUTPUT: 05:41:34 INFO - 2015-12-16 05:41:34,832 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:602.77 Median:602.77 stddev:0.00 (0.0%) 05:41:34 INFO - 2015-12-16 05:41:34,832 DEBUG : BROWSER_OUTPUT: Values: 602.8 05:41:34 INFO - 2015-12-16 05:41:34,832 DEBUG : BROWSER_OUTPUT: 05:41:34 INFO - 2015-12-16 05:41:34,832 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4859.22 Median:4859.22 stddev:0.00 (0.0%) 05:41:34 INFO - 2015-12-16 05:41:34,832 DEBUG : BROWSER_OUTPUT: Values: 4859.2 05:41:34 INFO - 2015-12-16 05:41:34,832 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:41:34 INFO - 2015-12-16 05:41:34,832 DEBUG : BROWSER_OUTPUT: 05:41:35 INFO - 2015-12-16 05:41:35,693 INFO : Browser exited with error code: 0 05:41:35 INFO - 2015-12-16 05:41:35,708 INFO : Completed test v8_7 (00:00:40) 05:41:35 INFO - 2015-12-16 05:41:35,816 INFO : Completed test suite (00:20:17) 05:41:35 INFO - 2015-12-16 05:41:35,816 DEBUG : Working with test: dromaeo_css 05:41:35 INFO - 2015-12-16 05:41:35,816 DEBUG : Generating results file: dromaeo_css 05:41:35 INFO - 2015-12-16 05:41:35,817 DEBUG : Working with test: kraken 05:41:35 INFO - 2015-12-16 05:41:35,817 DEBUG : Generating results file: kraken 05:41:35 INFO - 2015-12-16 05:41:35,818 INFO : javascript benchmark 05:41:35 INFO - 2015-12-16 05:41:35,818 DEBUG : Working with test: v8_7 05:41:35 INFO - 2015-12-16 05:41:35,818 DEBUG : Generating results file: v8_7 05:41:35 INFO - 2015-12-16 05:41:35,818 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:41:35 INFO - 2015-12-16 05:41:35,873 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,132,43]] 05:41:35 INFO - 2015-12-16 05:41:35,874 DEBUG : process_Request line: dromaeo_css 6388.17 graph.html#tests=[[72,132,43]] 05:41:35 INFO - 2015-12-16 05:41:35,874 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:41:35 INFO - 2015-12-16 05:41:35,911 DEBUG : process_Request line: kraken 1551.80 graph.html#tests=[[232,132,43]] 05:41:35 INFO - 2015-12-16 05:41:35,911 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:41:35 INFO - 2015-12-16 05:41:35,943 DEBUG : process_Request line: v8_7 21264.94 graph.html#tests=[[230,132,43]] 05:41:35 INFO - 2015-12-16 05:41:35,945 INFO : javascript benchmark 05:41:36 INFO - 2015-12-16 05:41:35,948 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2679.8124131310806, 2733.658292713411, 2769.072360759146, 2774.2648198227466, 2781.8052736308455, 4629.537046295369, 4829.323894654747, 4837.526022295553, 4873.658843880929, 4887.266909963507, 4696.8590942271785, 4709.340692303081, 4714.50497697742, 4730.763461826912, 4833.275008748689, 4374.212641724488, 4462.843800466992, 4484.304932735415, 4502.57225563571, 4565.634749220071, 4267.530571637118, 4421.867343979686, 4529.0, 4539.364488971547, 4559.566841150102, 4331.30699088146, 4423.137488189805, 4426.247537918562, 4456.131054444385, 4471.0, 4442.400275962736, 4520.09598080382, 4527.84152554659, 4538.72767633943, 4585.151746926829, 4243.511996120455, 4389.714668546566, 4423.60187583119, 4443.644508439349, 4462.263726485126, 4644.024754801496, 4660.067986402699, 4743.146233677937, 4785.856424307276, 4792.472827988919, 5141.974290128563, 5148.279240906276, 5150.201718733602, 5181.559567436772, 5183.844484665466, 4984.177610694231, 5095.847124586268, 5188.040212560651, 5189.532942035197, 5202.167653175512, 4717.693349932278, 4782.3543821584335, 4785.880352991134, 4792.400949881265, 4820.758962051884, 5344.198370244494, 5347.81282655113, 5365.597580181424, 5370.350093072814, 5381.165919282517, 4847.927281090787, 4974.303597496353, 5011.874703132451, 5121.0, 5138.486151384906, 5234.057869583511, 5234.162533994542, 5238.397584277781, 5247.422783493813, 5253.605979551549, 5153.123968925292, 5161.122609156452, 5161.638685292069, 5162.096633089194, 5213.921791173136, 4877.756112194376, 4941.752912354368, 4957.182064959241, 4985.127602669519, 5144.922826157611, 4176.749395036308, 4214.662826973835, 4231.196072746168, 4253.149370125987, 4277.016286254179, 3767.1335592813257, 3776.546814382292, 3790.033541446913, 3792.6207379261855, 3821.407681809324, 4290.377895205178, 4391.714538554984, 4439.067795762926, 4503.977480112579, 4520.299353600197, 5072.721000344943, 5075.39095308558, 5133.23001549763, 5202.14164662834, 5249.501297376743, 4391.560843915583, 4475.641948644101, 4494.12364588902, 4497.302918047708, 4517.412736344254, 4385.057212699285, 4587.518310577408, 4592.334111553872, 4604.815807367735, 4639.768011599406, 4844.781984810692, 4883.291922671122, 4923.08922849274, 4937.703737775745, 4967.478414766542, 5107.540321371094, 5165.328507294028, 5196.610254231024, 5204.609654276021, 5242.501962313574, 4675.532446755297, 4768.308595253599, 4919.0407870464915, 4947.109520286383, 4948.703077815343, 5016.924746128884, 5092.3889133302755, 5120.436751957356, 5122.052420302256, 5157.484251574812, 5180.326557547494, 5180.352455943007, 5180.9740951295, 5188.948110518846, 5231.55531779803, 4732.337472753886, 4863.708177509361, 4918.631102667386, 4994.32576602154, 4995.62532810048, 5033.395992480926, 5041.873953151201, 5047.596192304681, 5054.241863720471, 5079.441261461162, 4912.901741965212, 4935.259711043373, 4945.060438516671, 4948.035133149001, 5009.524095210949, 4832.299316599003, 4934.925976110289, 4960.305557222064, 5023.547880690609, 5135.050015747099, 4963.925541116959, 4993.975030124971, 4997.775100120431, 5022.422421421583, 5061.8734531637], "unit": "score", "name": "dojo.html", "value": 4723.543590396961}, {"lowerIsBetter": false, "replicates": [543.5488544508058, 544.2407841061716, 553.9501444869962, 558.17947617003, 573.5870173475097, 34840.82579587102, 35220.647793522025, 35332.82333588332, 35337.64662353376, 35385.0, 39331.41002884952, 39791.204175916464, 39806.40290395646, 39896.01039896009, 39902.002449938765, 7749.92250077499, 7847.9215207847765, 7908.485948413363, 7911.248431399036, 7915.050193976702, 7826.5695386753705, 7878.566678832662, 7915.960420197891, 7920.485168464032, 7967.44227904047, 7854.960725196366, 7911.52530848148, 7919.089304729972, 7936.246056624612, 7942.920570794305, 7906.762797116067, 7993.44045916786, 8005.399595030396, 8011.359091272684, 8011.799705007363, 7830.216978302181, 7936.2857342839425, 7959.761207163794, 7966.52200867944, 7974.641141148662, 35951.28097438037, 36133.45799813005, 36195.457068144, 36264.45603315952, 36364.454533182026, 2884.971150288512, 2944.8085874418093, 2966.466036113499, 3193.0, 3276.2137087099163, 27201.863990680118, 27414.725852741616, 27538.586921196198, 27725.0, 27903.441931161262, 3241.6434192238835, 3337.5160601712614, 3338.132085657722, 3345.1637090727318, 3466.376052310583, 204170.0, 204531.0, 205230.0, 205233.94766052446, 206021.9698901511, 49808.501914980385, 51015.7449212759, 51671.741641292305, 52228.21657675138, 54764.726176368866, 53479.0, 53588.0, 53662.463375366526, 53788.924221516136, 53963.19055214175, 14671.33978970949, 14775.482858099915, 14813.259337033322, 14824.481143159937, 14880.330385132695, 11185.440727963569, 11208.103351731841, 11239.381833999209, 11260.605878794202, 11279.097672186206, 18028.188731507114, 18139.72790408145, 18186.36347727823, 18202.0, 18251.45245642633, 7355.5586664800285, 7517.8120546985665, 7588.317051465395, 7649.0, 7664.156942736406, 822.59692750553, 856.3705676327894, 911.7603145373931, 921.6728061538057, 1099.428297285407, 22380.440488987904, 22885.166321820812, 23827.7617223829, 27863.582046269323, 28702.856485717857, 961.3991255465335, 964.5514835601479, 1070.4861666400172, 1098.368438148052, 1104.8177050786692, 13048.217106973612, 13944.651383715285, 14149.71700565983, 14422.350994205286, 14554.636134096521, 252459.0, 256157.0, 258234.0, 259273.0, 259342.36247556828, 46018.0, 46094.30858537125, 46133.76933115313, 46245.53754462412, 46308.0, 1239.1387985350275, 1244.464880101547, 1247.214255019332, 1251.993740031294, 1253.7775668722923, 26337.60493592636, 26494.0, 26726.732732672423, 26944.595831062157, 27048.0, 39081.80459097686, 39464.210715786096, 39573.60426395814, 39608.60391396049, 39612.405813912235, 244787.0, 246061.0, 248941.0, 249019.0, 249426.0, 28009.57985630258, 28523.714762852105, 29141.271468213465, 29186.0, 29206.0, 33849.830750846086, 33944.0, 34746.30507389788, 34838.0, 36043.81978090093, 31430.685693143692, 32040.35919281648, 32176.678233218303, 32348.83825580857, 32626.673733262363, 32047.519287210245, 32336.0, 32475.512867306537, 32494.512582311756, 32521.0, 31804.522932155574, 31935.520967185974, 32661.0, 33031.0, 33531.66468335286, 26730.732692673602, 28873.133805985854, 29385.118446446642, 29603.40793184081, 29671.703282966893, 22630.321090367317, 22892.54214915659, 22968.28963795256, 23159.30522084273, 23308.417289567897], "unit": "score", "name": "ext.html", "value": 16655.909446849146}, {"lowerIsBetter": false, "replicates": [341.1505351674331, 344.45919905747957, 345.63880744621866, 347.0871607671823, 347.24127780798955, 12554.560590379338, 12883.613491595244, 15061.62345941352, 15155.54533363998, 15858.127802970817, 15169.54491365258, 15318.770218446733, 16420.917895410537, 16457.588560286, 16533.503994880142, 4815.903681926359, 4907.1412502812045, 4949.7772600233075, 4981.1532039553185, 4986.227134794112, 4819.325294458778, 4975.850724478253, 4981.626378021645, 4982.676126051795, 5079.415867175267, 4897.902041959159, 4939.20972644377, 4946.109700253953, 4960.454350021495, 4977.303177555145, 5107.642465027449, 5220.843374698765, 5233.895322093575, 5240.3187585614005, 5255.316808814868, 5021.57316628087, 5041.19340905457, 5048.697078175322, 5053.570446512051, 5056.82301119459, 10890.455477226107, 12345.567905123278, 17771.822281777146, 18628.441146765617, 18812.62374752511, 1845.6770065238663, 1854.4251282102591, 1876.4558278099335, 1901.5151136460252, 1935.9709604355946, 8516.148385161434, 8525.06224315331, 8548.316134709208, 8648.956755216246, 8680.782980425476, 1737.7480265361578, 1763.5855573940114, 1771.2029586686042, 1774.6894293498715, 1838.7058070708622, 53646.0, 55618.0, 55980.720096399666, 57085.429145708425, 57163.0, 9759.756006099904, 9897.604095836086, 9994.900050999397, 10003.499825008721, 10035.598576056876, 12575.245485270912, 12663.303518306582, 12681.492740290285, 12702.237865728086, 12703.30131842739, 7095.432365410755, 7186.353228209382, 7226.602536860418, 7363.226861179499, 7406.0, 6607.306232845482, 6648.767293144813, 6667.933320666828, 6886.896696549556, 6907.20567134786, 4110.7328023678365, 4181.268278051329, 4196.559361267023, 4225.830966761295, 4232.640225580799, 1761.0, 1787.2850859656241, 1795.2908601102752, 1809.9276028958957, 1817.1459414075628, 864.7189663359434, 870.4472659861034, 875.6672464463463, 921.2031592672424, 951.695457453622, 15149.0, 15300.540983770505, 15559.0, 15752.842471575366, 15837.0, 867.8866958954965, 873.0352959979258, 888.4314039015036, 889.1375365895071, 964.782923842144, 8848.513331766815, 8853.29173666105, 8961.283097352196, 9095.272378209727, 9417.387869788441, 490505.0, 498213.0, 503021.0, 511417.0, 512805.0, 68863.65568172227, 72592.63703681447, 72608.0, 73052.0, 73429.0, 1046.4610725476387, 1083.0360978728938, 1087.6682611803317, 1096.9396683182501, 1129.9322040677587, 13642.0, 14057.718845623234, 14269.71460570762, 14283.0, 14549.054311469721, 32649.510257346636, 33045.17387065342, 33487.43498057548, 34654.653453465144, 34693.13267168341, 40042.599574003885, 40270.59729402669, 40832.0, 41350.79324603358, 41657.0, 8602.827943441222, 8954.194122529003, 9288.674896378594, 9289.16397524226, 9305.627774888928, 16275.837241627432, 16416.425425110065, 16508.66982660364, 16724.83275167233, 16881.66236675284, 13658.521951731642, 13843.169409835442, 14087.0, 14154.716905661624, 14303.427862885368, 13504.797428038391, 13735.519256825963, 13812.171269723849, 14047.086939349318, 14205.928970355082, 14030.508932187324, 14108.858911410754, 14182.92908535492, 14302.141871487744, 14659.48691795782, 8970.327225457986, 9107.772305692411, 9154.130357616015, 9197.574375971735, 9218.861717074384, 13665.79501307501, 13686.65783355424, 13720.313984300945, 13796.931015344859, 14368.784468233194], "unit": "score", "name": "jquery.html", "value": 8322.599963480237}, {"lowerIsBetter": false, "replicates": [1433.1830856411843, 1449.6665766873618, 1467.6917847252078, 1630.7227771278883, 1698.3461367373561, 7050.224475307725, 7146.0, 7154.713811447542, 7337.779866603997, 7343.155537113234, 7338.229485903983, 7352.816179595513, 7434.516756410843, 7437.814054648637, 7460.253974602537, 2043.2848503023934, 2090.4042347930845, 2104.715863358442, 2105.652567326397, 2108.051376880402, 2134.5410736691606, 2148.4521447030984, 2150.3656421355736, 2152.300502336739, 2165.577712346093, 1705.6247625522428, 1734.1329335332334, 2066.5143691232547, 2067.6071546406133, 2097.4651463876767, 2037.786032466592, 2037.8165965063142, 2046.7441569803775, 2052.404802607257, 2058.053295484079, 2034.4608678700156, 2046.2735728816206, 2053.0966374795194, 2070.927517536879, 2077.511784730587, 7269.563826170447, 7295.562266264041, 7326.267373262631, 7326.963365183194, 7339.045924029895, 4191.832326706928, 4194.3498757692305, 4246.214450326699, 4247.5540068293, 4252.787360631956, 2698.730126987305, 2755.3524921643398, 2761.3096725818546, 2766.2669392610974, 2805.340744924941, 852.4501696405783, 859.4542465534369, 873.9737807865774, 874.1040433555656, 898.2050884966786, 9454.0, 9472.100150485692, 9515.57179926905, 9519.095685909828, 9572.0, 3711.387621042498, 3733.4959780429836, 3741.569719482294, 3830.942535861964, 3844.961860297704, 7843.254890785366, 7878.212178782076, 7880.724174653859, 7882.448228623941, 7885.093214280431, 4120.7733574653685, 4132.979335103305, 4139.441175441277, 4142.71001029925, 4164.37534369847, 3816.866409675648, 3820.426935959573, 3832.3868181090893, 3833.5783063862955, 3844.6539811417106, 3097.3495565930957, 3101.8914337998062, 3104.177392990859, 3112.673169317234, 3135.4826453634896, 898.9640414383351, 900.401233179928, 922.5525620074259, 927.3508544019213, 931.1107891963159, 913.2511340700562, 913.5751875377981, 925.3522534225934, 932.4172392254841, 1059.0733108530037, 6814.965925170342, 6836.077129587541, 6858.177018757681, 6880.621565813828, 6911.205211400653, 993.7366597851575, 1017.6743442098457, 1069.8930106989394, 1074.9247552671238, 1087.1900434176432, 5397.082495975694, 5448.318960129984, 5451.672899626035, 5479.205515200274, 5489.972550137224, 8413.495190288602, 8452.450590711707, 8633.438826476258, 8730.388872778845, 8792.78018049541, 8614.698485552975, 8616.48301101936, 8629.870551941725, 8652.78368040804, 8698.043215246318, 6718.563293385914, 6758.648270345951, 6844.247132815386, 6869.484788640772, 6891.448684105159, 6564.113844630913, 6579.934200658124, 6584.539082264196, 6613.801585952237, 6618.569792963442, 7590.734324298623, 7653.693852245848, 7795.493292936168, 7808.882866757117, 7812.296893279405, 7804.648790804541, 7936.6428510716005, 7947.602619869099, 7969.043714754266, 7975.0, 6140.3552626975315, 6192.442680158807, 6209.441150296495, 6210.099535567408, 6210.658413787285, 6949.68726407323, 7093.255208203064, 7119.85760284781, 7124.750633727795, 7145.535540189871, 6527.086207930798, 6604.438622717123, 6648.135742353463, 6778.559393639398, 6791.252962174058, 5677.290338707661, 6447.9355206447335, 6502.317256687979, 6594.769183078569, 6636.734530618721, 6485.6108633482145, 6506.4469520091325, 6517.869642607026, 6527.771527996498, 6531.640759758259, 6015.1278064681255, 6053.0, 6077.027675571887, 6098.0852872069545, 6104.847378815565, 6152.169457123231, 6161.9691901542, 6187.969060154851, 6189.133521307111, 6195.0], "unit": "score", "name": "mootools.html", "value": 4083.0566175307545}, {"lowerIsBetter": false, "replicates": [298.3153662344918, 304.38210432821364, 306.9094617087959, 311.15676516639905, 311.4533992842561, 5598.188262701906, 5620.213170156181, 5687.289088863892, 5763.279590051244, 5823.213866128082, 5619.915701264485, 5673.914891276634, 5673.943260567404, 5685.431456854312, 5689.3172819261745, 4176.60322269384, 4297.677674174433, 4300.935485967713, 4303.8063287152, 4334.869953901388, 4292.463442069741, 4305.569443055685, 4314.1156063007165, 4318.546552611978, 4326.545712700168, 4220.873373798776, 4251.8511852085185, 4261.275583150857, 4267.48790145183, 4281.12236991416, 4226.957730422687, 4239.321708526636, 4241.34259189826, 4252.893677658068, 4255.510616279105, 3626.8186590670625, 3693.741438099334, 3700.0, 3704.6480584344445, 3704.833282502267, 5317.8404647860625, 5440.972795136038, 5445.455454454563, 5458.099413596791, 5459.945400545984, 4869.902601947942, 4880.463149053601, 4890.902181956377, 4894.632902532324, 4900.240462728283, 5939.643621382731, 5940.762369505214, 5947.137665038588, 5955.196048533479, 5956.344802071769, 3915.725899187058, 4011.538673052607, 4022.5172979242384, 4023.6982226333143, 4039.9192016159514, 6193.2877719062435, 6265.530085243579, 6271.43557079865, 6281.026440901667, 6288.151099601541, 4182.9581704182565, 4184.184084103571, 4185.24665560196, 4194.769287689206, 4196.286631272691, 4255.553166917491, 4256.936145957813, 4258.723182993095, 4259.5101563319995, 4270.039241170712, 4683.15703173432, 4841.055994081191, 4894.388201474816, 4896.6572339935865, 4901.852944411602, 5890.617109887844, 5910.468057874812, 5910.911336329958, 5913.970430147908, 5916.083007133901, 1471.2423102102211, 1494.305148106114, 1494.8953573249769, 1496.6931778985281, 1496.7305884940815, 987.4914419074182, 992.9106380425796, 1004.6483730694345, 1009.7879445316419, 1029.1818004686293, 5147.3308469898675, 5158.92261616076, 5159.613029022838, 5165.044466773659, 5177.249298851645, 6103.115048317969, 6132.294786099566, 6143.38566143391, 6146.416090471398, 6147.938520614736, 5141.820036298712, 5143.305653736773, 5146.742662866842, 5156.04613146569, 5156.149235376196, 3236.288016636336, 3289.9177520562175, 3327.5507806446303, 3336.115929278743, 3337.9833100834826, 5687.516561092353, 5751.0, 5798.768049278066, 5817.0, 5820.563457740686, 6275.0, 6312.431881130628, 6313.905291420632, 6327.778527751507, 6327.96836015817, 5791.102379131242, 5824.796132135355, 5827.125931110368, 5837.532997360201, 5837.912431313449, 5978.073398623221, 6154.169187159855, 6183.227096612924, 6184.721687523982, 6189.752409903554, 5993.520518358608, 6057.7879774207695, 6060.212172417734, 6068.21113255274, 6095.0, 5836.912446313224, 5842.269716285465, 5848.181254624441, 5852.531797456279, 5863.20846685692, 4244.002659374983, 4283.507396649426, 4311.288637374923, 4314.71954322968, 4315.741055536552, 5943.821685349447, 6106.6336019838955, 6144.293406258337, 6144.754209831557, 6149.661768602591, 5866.560007999503, 5877.529797616266, 5884.264466941632, 5889.204957330706, 5892.587518873809, 5884.470397664059, 5894.528437725057, 5900.262467191601, 5903.645781253139, 5910.1725758392995, 5632.436756324335, 5735.8566035849435, 5746.482816546531, 5782.277215348082, 5800.216970709069, 5957.821265362046, 6034.668093254913, 6046.0, 6047.365026672136, 6061.3029501606115, 5868.735906884286, 5868.970655146697, 5872.677002764889, 5881.4118588142555, 5886.882262354815], "unit": "score", "name": "prototype.html", "value": 4418.2634405462895}, {"lowerIsBetter": false, "replicates": [697.368881162548, 706.3536863769655, 721.5021635071801, 746.2984794293363, 784.9293563579284, 11836.822447663291, 12059.577914772985, 12116.81824772628, 12128.575499857507, 12154.635360939175, 11061.170412219075, 11240.550377984871, 11260.493277802498, 11295.0, 11325.150613703963, 635.3297271378693, 642.6850843086877, 793.2503783924194, 815.6370415165254, 822.56404105824, 818.9017317921857, 820.6922404098464, 822.2024636103, 827.5283088639478, 829.5396055189367, 850.3537311643165, 856.3106699107216, 861.8147098373848, 862.8490014247513, 864.1747131489444, 850.6384786465758, 851.6423102296989, 852.9914700853005, 855.5422848775906, 855.6876739989859, 630.0517720830121, 630.3853742600974, 633.5976654824174, 634.8920683483819, 635.7933671556763, 11796.05631549474, 11847.763044739057, 11887.108466864933, 11913.166078374517, 11968.0, 10817.459127043616, 11237.550497980072, 11267.26762760418, 11488.88511114895, 11761.294322340687, 9308.488033158173, 9314.720558383191, 9322.48726320052, 9325.580348884316, 9456.385334953206, 3176.6029246344206, 3217.790843595159, 3251.983740081308, 3256.1208473711963, 3272.4764037754085, 39397.01507462308, 39432.80283598564, 39437.60562394368, 39480.21039579221, 39672.404913926315, 9113.179813816789, 9381.953090234641, 9574.808503830023, 9709.126178643955, 9831.0, 15896.046237225804, 16016.0, 16028.839711602968, 16063.357465701241, 16118.75821862673, 5885.970570147122, 6023.518118550505, 6087.756489740449, 6131.049687298476, 6174.969125154345, 3607.639236076371, 3704.7777133372083, 3719.8512059517316, 3728.254349130185, 3751.531058617673, 2510.79913606911, 2512.8869200886006, 2545.350935511433, 2547.0957809977563, 2549.541082605149, 456.66207006814716, 460.82488654311817, 467.49510528628997, 470.6681789338508, 473.4271531446919, 544.8474427160402, 615.6552330694735, 617.305531277313, 635.7838334966134, 638.0970926139553, 7740.187280335484, 7827.921720782833, 7902.209779022051, 7938.682452701827, 7958.6816527339415, 471.90561887622613, 518.5177784660227, 570.0, 626.8150895485843, 656.4879394072631, 3698.9075273118387, 3721.2557488502407, 3790.2067204086807, 3853.537575490959, 3871.7096217783214, 49031.0, 49457.0, 49798.253026204635, 49853.75073124611, 49886.0, 44408.3338749919, 44675.77662111669, 45059.098818023456, 45693.0, 45737.542624573325, 37897.24205515819, 37920.0, 38831.0, 39022.0, 40775.1844963105, 8032.759017229492, 8108.270255677017, 8562.614682339185, 8576.842126313117, 8674.132586741276, 31373.21566960753, 31543.68456315499, 31637.209069772518, 31975.520367194047, 32098.67901320957, 38645.6135438642, 39184.0, 39209.803950980066, 39439.0, 39731.0, 10182.134518566005, 10287.794244115226, 10357.17142628603, 10394.844077338696, 10444.164466842787, 11264.211505194886, 11425.143114266297, 11478.88521114778, 11563.132765042486, 11777.882221177679, 10695.0, 11155.27490713101, 11269.774604508028, 11361.204715670154, 11622.82565761531, 10510.47447627631, 10841.457927103456, 10867.239293249713, 11227.101831853595, 11306.265092768943, 10034.19726421899, 10217.642382516577, 10311.329763565343, 10377.533011014371, 10536.262461627919, 9115.179633832988, 9342.439453632804, 9442.15020648145, 9523.52382380892, 9819.656312029045, 9013.0, 9195.26437884981, 9281.350305478552, 9291.62833486653, 9325.860112098188], "unit": "score", "name": "yui.html", "value": 5752.872180978336}], "name": "dromaeo_css", "value": 6388.171661356714}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [115.0, 110.0, 115.0, 113.0, 116.0, 128.0, 116.0, 115.0, 115.0, 117.0], "unit": "score", "name": "ai-astar", "value": 116.0}, {"lowerIsBetter": true, "replicates": [157.0, 133.0, 155.0, 164.0, 130.0, 152.0, 161.0, 137.0, 170.0, 131.0], "unit": "score", "name": "audio-beat-detection", "value": 149.0}, {"lowerIsBetter": true, "replicates": [163.0, 165.0, 165.0, 164.0, 162.0, 165.0, 168.0, 164.0, 167.0, 164.0], "unit": "score", "name": "audio-dft", "value": 164.7}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 73.0, 72.0, 144.0, 71.0, 79.0, 72.0, 72.0, 94.0], "unit": "score", "name": "audio-fft", "value": 82.1}, {"lowerIsBetter": true, "replicates": [94.0, 95.0, 139.0, 96.0, 96.0, 96.0, 132.0, 94.0, 97.0, 135.0], "unit": "score", "name": "audio-oscillator", "value": 107.4}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 110.0, 110.0, 111.0, 111.0, 111.0, 111.0, 111.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.5}, {"lowerIsBetter": true, "replicates": [148.0, 148.0, 149.0, 148.0, 149.0, 148.0, 149.0, 148.0, 148.0, 148.0], "unit": "score", "name": "imaging-darkroom", "value": 148.3}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 96.0, 96.0, 97.0, 96.0, 96.0, 95.0, 96.0, 97.0], "unit": "score", "name": "imaging-desaturate", "value": 96.2}, {"lowerIsBetter": true, "replicates": [74.0, 77.0, 74.0, 73.0, 74.0, 76.0, 73.0, 73.0, 74.0, 73.0], "unit": "score", "name": "json-parse-financial", "value": 74.1}, {"lowerIsBetter": true, "replicates": [69.0, 68.0, 68.0, 69.0, 69.0, 70.0, 70.0, 70.0, 72.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 69.4}, {"lowerIsBetter": true, "replicates": [88.0, 86.0, 85.0, 96.0, 85.0, 85.0, 88.0, 87.0, 88.0, 103.0], "unit": "score", "name": "stanford-crypto-aes", "value": 89.1}, {"lowerIsBetter": true, "replicates": [120.0, 122.0, 124.0, 124.0, 124.0, 132.0, 132.0, 133.0, 122.0, 123.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 125.6}, {"lowerIsBetter": true, "replicates": [139.0, 153.0, 154.0, 150.0, 154.0, 143.0, 149.0, 150.0, 152.0, 139.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 148.3}, {"lowerIsBetter": true, "replicates": [73.0, 69.0, 69.0, 70.0, 71.0, 71.0, 72.0, 73.0, 70.0, 73.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 71.1}], "name": "kraken", "value": 1551.7999999999997}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.90765171503958], "unit": "score", "name": "Richards", "value": 214.07132799999994}, {"lowerIsBetter": false, "replicates": [192.34468166955185], "unit": "score", "name": "DeltaBlue", "value": 343.7474820000001}, {"lowerIsBetter": false, "replicates": [279.3296089385475], "unit": "score", "name": "Encrypt", "value": 952.9279799999998}, {"lowerIsBetter": false, "replicates": [5191.709844559586], "unit": "score", "name": "Decrypt", "value": 51.270392215568904}, {"lowerIsBetter": false, "replicates": [1062.6992561105208], "unit": "score", "name": "RayTrace", "value": 696.3296489999998}, {"lowerIsBetter": false, "replicates": [700.770847932726], "unit": "score", "name": "Earley", "value": 951.0427009999997}, {"lowerIsBetter": false, "replicates": [12280.487804878048], "unit": "score", "name": "Boyer", "value": 54.27007547169812}, {"lowerIsBetter": false, "replicates": [26789.473684210527], "unit": "score", "name": "RegExp", "value": 34.0053339882122}, {"lowerIsBetter": false, "replicates": [602.7727546714889], "unit": "score", "name": "Splay", "value": 135.19356900000005}, {"lowerIsBetter": false, "replicates": [4859.223300970873], "unit": "score", "name": "NavierStokes", "value": 305.39860139860156}], "name": "v8_7", "value": 21264.942027531353}]} 05:41:36 INFO - RETURN: dromaeo_css: 6388.17 05:41:36 INFO - RETURN: kraken: 1551.80 05:41:36 INFO - RETURN: v8_7: 21264.94 05:41:36 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,43]]", "result": "21264.94"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,43]]", "result": "1551.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,43]]", "result": "6388.17"}}} 05:41:36 INFO - Return code: 0 05:41:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:41:36 INFO - # TBPL SUCCESS # 05:41:36 INFO - Running post-action listener: _resource_record_post_action 05:41:36 INFO - Running post-run listener: _resource_record_post_run 05:41:37 INFO - Total resource usage - Wall time: 1233s; CPU: 13.0%; Read bytes: 4096; Write bytes: 508731392; Read time: 8; Write time: 481816 05:41:37 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 231071744; Read time: 0; Write time: 297764 05:41:37 INFO - run-tests - Wall time: 1219s; CPU: 13.0%; Read bytes: 4096; Write bytes: 277159936; Read time: 8; Write time: 184024 05:41:37 INFO - Running post-run listener: _upload_blobber_files 05:41:37 INFO - Blob upload gear active. 05:41:37 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:41:37 INFO - Copying logs to upload dir... 05:41:37 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1266.856064 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2015-12-16 05:41:36.794378) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 05:41:36.798053) ========= 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=d60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009867 build_url: 'https://queue.taskcluster.net/v1/task/ANpTl2gpRymqbyq130qVZg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 05:41:36.854023) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:41:36.854489) ========= 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=d60a7c61e513c1f504d0d12f000001b4-1450272028.683629-261033966 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004468 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:41:36.906597) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-16 05:41:36.907201) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-16 05:41:36.907829) ========= ========= Total master_lag: 0.26 =========