builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-067 starttime: 1449662677.64 results: success (0) buildid: 20151209030353 builduid: 3eb57ef47a004eb38708c1d60f0a6a73 revision: 319be5e7ce3061c7c16f24d750b6dacdbcac4c35 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:37.642290) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:37.642927) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:37.643255) ========= 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=3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006322 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:37.667889) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:37.668223) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:37.697976) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:37.698350) ========= 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=3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004613 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:37.752359) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:37.752636) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:37.753001) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:37.753290) ========= 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=3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-09 04:04:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 47.1M=0s 2015-12-09 04:04:40 (47.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110258 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:37.884267) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:37.884549) ========= 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=3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022617 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:37.952275) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:37.952613) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 319be5e7ce3061c7c16f24d750b6dacdbcac4c35 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 319be5e7ce3061c7c16f24d750b6dacdbcac4c35 --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=3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-09 04:04:40,319 truncating revision to first 12 chars 2015-12-09 04:04:40,319 Setting DEBUG logging. 2015-12-09 04:04:40,319 attempt 1/10 2015-12-09 04:04:40,319 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/319be5e7ce30?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-09 04:04:40,559 unpacking tar archive at: mozilla-central-319be5e7ce30/testing/mozharness/ program finished with exit code 0 elapsedTime=0.504523 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:38.470333) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:38.470634) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:38.480023) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:38.480359) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-09 04:04:38.480820) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2015-12-09 04:04:38.481171) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:04:40 INFO - MultiFileLogger online at 20151209 04:04:40 in /builds/slave/test 04:04:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 04:04:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:04:40 INFO - {'append_to_log': False, 04:04:40 INFO - 'base_work_dir': '/builds/slave/test', 04:04:40 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 04:04:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:04:40 INFO - 'branch': 'Firefox-Non-PGO', 04:04:40 INFO - 'buildbot_json_path': 'buildprops.json', 04:04:40 INFO - 'config_files': ('talos/linux_config.py',), 04:04:40 INFO - 'default_actions': ('clobber', 04:04:40 INFO - 'read-buildbot-config', 04:04:40 INFO - 'download-and-extract', 04:04:40 INFO - 'populate-webroot', 04:04:40 INFO - 'create-virtualenv', 04:04:40 INFO - 'install', 04:04:40 INFO - 'run-tests'), 04:04:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:04:40 INFO - 'download_minidump_stackwalk': True, 04:04:40 INFO - 'download_symbols': 'ondemand', 04:04:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:04:40 INFO - 'tooltool.py': '/tools/tooltool.py', 04:04:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:04:40 INFO - '/tools/misc-python/virtualenv.py')}, 04:04:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:04:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:04:40 INFO - 'installer_path': 'installer.exe', 04:04:40 INFO - 'log_level': 'info', 04:04:40 INFO - 'log_name': 'talos', 04:04:40 INFO - 'log_to_console': True, 04:04:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:04:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:04:40 INFO - 'opt_config_files': (), 04:04:40 INFO - 'pip_index': False, 04:04:40 INFO - 'sps_profile': False, 04:04:40 INFO - 'sps_profile_interval': 0, 04:04:40 INFO - 'suite': 'dromaeojs-e10s', 04:04:40 INFO - 'system_bits': '64', 04:04:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:04:40 INFO - 'title': 'talos-linux64-ix-067', 04:04:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:04:40 INFO - 'use_talos_json': True, 04:04:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:04:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:04:40 INFO - 'work_dir': 'build'} 04:04:40 INFO - ##### 04:04:40 INFO - ##### Running clobber step. 04:04:40 INFO - ##### 04:04:40 INFO - Running pre-action listener: _resource_record_pre_action 04:04:40 INFO - Running main action method: clobber 04:04:40 INFO - rmtree: /builds/slave/test/build 04:04:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:04:41 INFO - Running post-action listener: _resource_record_post_action 04:04:41 INFO - ##### 04:04:41 INFO - ##### Running read-buildbot-config step. 04:04:41 INFO - ##### 04:04:41 INFO - Running pre-action listener: _resource_record_pre_action 04:04:41 INFO - Running main action method: read_buildbot_config 04:04:41 INFO - Using buildbot properties: 04:04:41 INFO - { 04:04:41 INFO - "properties": { 04:04:41 INFO - "buildnumber": 165, 04:04:41 INFO - "product": "firefox", 04:04:41 INFO - "script_repo_revision": "production", 04:04:41 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:04:41 INFO - "repository": "", 04:04:41 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 04:04:41 INFO - "buildid": "20151209030353", 04:04:41 INFO - "slavename": "talos-linux64-ix-067", 04:04:41 INFO - "pgo_build": "False", 04:04:41 INFO - "basedir": "/builds/slave/test", 04:04:41 INFO - "project": "", 04:04:41 INFO - "platform": "ubuntu64_hw", 04:04:41 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:04:41 INFO - "slavebuilddir": "test", 04:04:41 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 04:04:41 INFO - "branch": "mozilla-central", 04:04:41 INFO - "repo_path": "mozilla-central", 04:04:41 INFO - "stage_platform": "linux64", 04:04:41 INFO - "builduid": "3eb57ef47a004eb38708c1d60f0a6a73", 04:04:41 INFO - "revision": "319be5e7ce3061c7c16f24d750b6dacdbcac4c35" 04:04:41 INFO - }, 04:04:41 INFO - "sourcestamp": { 04:04:41 INFO - "repository": "", 04:04:41 INFO - "hasPatch": false, 04:04:41 INFO - "project": "", 04:04:41 INFO - "branch": "mozilla-central-linux64-talos", 04:04:41 INFO - "changes": [ 04:04:41 INFO - { 04:04:41 INFO - "category": null, 04:04:41 INFO - "files": [ 04:04:41 INFO - { 04:04:41 INFO - "url": null, 04:04:41 INFO - "name": "https://queue.taskcluster.net/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 04:04:41 INFO - } 04:04:41 INFO - ], 04:04:41 INFO - "repository": "", 04:04:41 INFO - "rev": "319be5e7ce3061c7c16f24d750b6dacdbcac4c35", 04:04:41 INFO - "who": "cbook@mozilla.com", 04:04:41 INFO - "when": 1449662644, 04:04:41 INFO - "number": 7145008, 04:04:41 INFO - "comments": "merge mozilla-inbound to mozilla-central a=merge", 04:04:41 INFO - "project": "", 04:04:41 INFO - "at": "Wed 09 Dec 2015 04:04:04", 04:04:41 INFO - "branch": "mozilla-central-linux64-talos", 04:04:41 INFO - "revlink": "", 04:04:41 INFO - "properties": [ 04:04:41 INFO - [ 04:04:41 INFO - "buildid", 04:04:41 INFO - "20151209030353", 04:04:41 INFO - "Change" 04:04:41 INFO - ], 04:04:41 INFO - [ 04:04:41 INFO - "builduid", 04:04:41 INFO - "3eb57ef47a004eb38708c1d60f0a6a73", 04:04:41 INFO - "Change" 04:04:41 INFO - ], 04:04:41 INFO - [ 04:04:41 INFO - "pgo_build", 04:04:41 INFO - "False", 04:04:41 INFO - "Change" 04:04:41 INFO - ] 04:04:41 INFO - ], 04:04:41 INFO - "revision": "319be5e7ce3061c7c16f24d750b6dacdbcac4c35" 04:04:41 INFO - } 04:04:41 INFO - ], 04:04:41 INFO - "revision": "319be5e7ce3061c7c16f24d750b6dacdbcac4c35" 04:04:41 INFO - } 04:04:41 INFO - } 04:04:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 04:04:41 INFO - Running post-action listener: _resource_record_post_action 04:04:41 INFO - ##### 04:04:41 INFO - ##### Running download-and-extract step. 04:04:41 INFO - ##### 04:04:41 INFO - Running pre-action listener: _resource_record_pre_action 04:04:41 INFO - Running main action method: download_and_extract 04:04:41 INFO - mkdir: /builds/slave/test/build/tests 04:04: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')]} 04:04:41 INFO - https://queue.taskcluster.net/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 04:04:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/test_packages.json 04:04:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/test_packages.json 04:04:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 04:04:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 04:04:42 INFO - Downloaded 1302 bytes. 04:04:42 INFO - Reading from file /builds/slave/test/build/test_packages.json 04:04:42 INFO - Using the following test package requirements: 04:04:42 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 04:04:42 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:04:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:04:42 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:04:42 INFO - u'jsshell-linux-x86_64.zip'], 04:04:42 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:04:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:04:42 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 04:04:42 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:04:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:04:42 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:04:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:04:42 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:04:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:04:42 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 04:04:42 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:04:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:04:42 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:04: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')]} 04:04:42 INFO - https://queue.taskcluster.net/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:04:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:04:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:04:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:04:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:04:43 INFO - Downloaded 21859993 bytes. 04:04:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 04:04:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:04:43 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 04:04:44 INFO - Return code: 0 04:04:44 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:04: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')]} 04:04:44 INFO - https://queue.taskcluster.net/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:04:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:04:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:04:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:04:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:04:56 INFO - Downloaded 21859993 bytes. 04:04:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 04:04:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:04:56 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 04:04:57 INFO - Return code: 0 04:04:57 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')]} 04:04:57 INFO - https://queue.taskcluster.net/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:04:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 04:04:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 04:04:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 04:04:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:04:59 INFO - Downloaded 11132038 bytes. 04:04:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 04:04:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 04:04:59 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 04:04:59 INFO - Return code: 0 04:04: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')]} 04:04:59 INFO - https://queue.taskcluster.net/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:04:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 04:04:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 04:04:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 04:04:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:05:00 INFO - Downloaded 57729516 bytes. 04:05:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 04:05:00 INFO - mkdir: /builds/slave/test/properties 04:05:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:05:00 INFO - Writing to file /builds/slave/test/properties/build_url 04:05:00 INFO - Contents: 04:05:00 INFO - build_url:https://queue.taskcluster.net/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 04:05:00 INFO - Running post-action listener: _resource_record_post_action 04:05:00 INFO - Running post-action listener: set_extra_try_arguments 04:05:00 INFO - ##### 04:05:00 INFO - ##### Running populate-webroot step. 04:05:00 INFO - ##### 04:05:00 INFO - Running pre-action listener: _resource_record_pre_action 04:05:00 INFO - Running main action method: populate_webroot 04:05:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:05:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:05:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:05:00 INFO - '--tppagecycles', 04:05:00 INFO - '7'], 04:05:00 INFO - 'tests': ['tsvgm']}}, 04:05:00 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:05:00 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:05:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:05:00 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:05:00 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:05:00 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:05:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:05:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:00 INFO - 'talos_options': ['--e10s'], 04:05:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:05:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:00 INFO - 'tests': ['damp', 'tps']}, 04:05:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:00 INFO - 'talos_options': ['--e10s'], 04:05:00 INFO - 'tests': ['damp', 'tps']}, 04:05:00 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:05:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:05:00 INFO - 'other': {'tests': ['a11yr', 04:05:00 INFO - 'ts_paint', 04:05:00 INFO - 'tpaint', 04:05:00 INFO - 'sessionrestore', 04:05:00 INFO - 'sessionrestore_no_auto_restore']}, 04:05:00 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:05:00 INFO - 'tests': ['a11yr', 04:05:00 INFO - 'ts_paint', 04:05:00 INFO - 'tpaint', 04:05:00 INFO - 'sessionrestore', 04:05:00 INFO - 'sessionrestore_no_auto_restore']}, 04:05:00 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:05:00 INFO - 'tests': ['a11yr', 04:05:00 INFO - 'ts_paint', 04:05:00 INFO - 'tpaint', 04:05:00 INFO - 'sessionrestore', 04:05:00 INFO - 'sessionrestore_no_auto_restore']}, 04:05:00 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:05:00 INFO - 'tests': ['a11yr', 04:05:00 INFO - 'ts_paint', 04:05:00 INFO - 'tpaint', 04:05:00 INFO - 'sessionrestore', 04:05:00 INFO - 'sessionrestore_no_auto_restore']}, 04:05:00 INFO - 'other_l64': {'tests': ['a11yr', 04:05:00 INFO - 'ts_paint', 04:05:00 INFO - 'tpaint', 04:05:00 INFO - 'sessionrestore', 04:05:00 INFO - 'sessionrestore_no_auto_restore']}, 04:05:00 INFO - 'other_nol64': {'tests': ['a11yr', 04:05:00 INFO - 'ts_paint', 04:05:00 INFO - 'tpaint', 04:05:00 INFO - 'sessionrestore', 04:05:00 INFO - 'sessionrestore_no_auto_restore']}, 04:05:00 INFO - 'svgr': {'tests': ['tsvgx', 04:05:00 INFO - 'tsvgr_opacity', 04:05:00 INFO - 'tart', 04:05:00 INFO - 'tscrollx', 04:05:00 INFO - 'cart']}, 04:05:00 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:05:00 INFO - 'tests': ['tsvgx', 04:05:00 INFO - 'tsvgr_opacity', 04:05:00 INFO - 'tart', 04:05:00 INFO - 'tscrollx', 04:05:00 INFO - 'cart']}, 04:05:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:00 INFO - 'tests': ['tp5o']}, 04:05:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:00 INFO - 'talos_options': ['--e10s'], 04:05:00 INFO - 'tests': ['tp5o']}, 04:05:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:00 INFO - 'talos_options': ['--xperf_path', 04:05:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:05:00 INFO - 'tests': ['tp5n']}, 04:05:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:00 INFO - 'talos_options': ['--e10s', 04:05:00 INFO - '--xperf_path', 04:05:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:05:00 INFO - 'tests': ['tp5n']}}, 04:05:00 INFO - 'talos.zip': {'path': '', 04:05:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:05:00 INFO - Running post-action listener: _resource_record_post_action 04:05:00 INFO - ##### 04:05:00 INFO - ##### Running create-virtualenv step. 04:05:00 INFO - ##### 04:05:00 INFO - Running pre-action listener: _resource_record_pre_action 04:05:00 INFO - Running main action method: create_virtualenv 04:05:00 INFO - Creating virtualenv /builds/slave/test/build/venv 04:05:00 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 04:05:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:05:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:05:00 INFO - Using real prefix '/usr' 04:05:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:05:01 INFO - Installing distribute.............................................................................................................................................................................................done. 04:05:03 INFO - Installing pip.................done. 04:05:03 INFO - Return code: 0 04:05:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:05:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:05:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d33a7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1214240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a30b0>, '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 0x12a3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a4440>, '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': '3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026', '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 04:05:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:05:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:05:03 INFO - Using env: {'DISPLAY': ':0', 04:05:03 INFO - 'HOME': '/home/cltbld', 04:05:03 INFO - 'LANG': 'en_US.UTF-8', 04:05:03 INFO - 'LANGUAGE': 'en_US:en', 04:05:03 INFO - 'LOGNAME': 'cltbld', 04:05:03 INFO - 'MAIL': '/var/mail/cltbld', 04:05:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:03 INFO - 'MOZ_NO_REMOTE': '1', 04:05:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:03 INFO - 'NO_EM_RESTART': '1', 04:05:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:03 INFO - 'PWD': '/builds/slave/test', 04:05:03 INFO - 'SHELL': '/bin/bash', 04:05:03 INFO - 'SHLVL': '1', 04:05:03 INFO - 'TERM': 'linux', 04:05:03 INFO - 'TMOUT': '86400', 04:05:03 INFO - 'USER': 'cltbld', 04:05:03 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026', 04:05:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:03 INFO - '_': '/tools/buildbot/bin/python'} 04:05:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:03 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 04:05:03 INFO - Cleaning up... 04:05:03 INFO - Return code: 0 04:05:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:05:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:05:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f8d33a7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1214240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a30b0>, '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 0x12a3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a4440>, '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': '3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026', '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 04:05:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:05:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:05:03 INFO - Using env: {'DISPLAY': ':0', 04:05:03 INFO - 'HOME': '/home/cltbld', 04:05:03 INFO - 'LANG': 'en_US.UTF-8', 04:05:03 INFO - 'LANGUAGE': 'en_US:en', 04:05:03 INFO - 'LOGNAME': 'cltbld', 04:05:03 INFO - 'MAIL': '/var/mail/cltbld', 04:05:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:03 INFO - 'MOZ_NO_REMOTE': '1', 04:05:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:03 INFO - 'NO_EM_RESTART': '1', 04:05:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:03 INFO - 'PWD': '/builds/slave/test', 04:05:03 INFO - 'SHELL': '/bin/bash', 04:05:03 INFO - 'SHLVL': '1', 04:05:03 INFO - 'TERM': 'linux', 04:05:03 INFO - 'TMOUT': '86400', 04:05:03 INFO - 'USER': 'cltbld', 04:05:03 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026', 04:05:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:03 INFO - '_': '/tools/buildbot/bin/python'} 04:05:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:03 INFO - Downloading/unpacking psutil>=0.7.1 04:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:05 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 04:05:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 04:05:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:05:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:05:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:05:05 INFO - Installing collected packages: psutil 04:05:05 INFO - Running setup.py install for psutil 04:05:05 INFO - building 'psutil._psutil_linux' extension 04:05:05 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 04:05:05 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 04:05:05 INFO - building 'psutil._psutil_posix' extension 04:05:05 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 04:05:06 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 04:05:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:05:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:05:06 INFO - Successfully installed psutil 04:05:06 INFO - Cleaning up... 04:05:06 INFO - Return code: 0 04:05:06 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:05:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:06 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 0x7f8d33a7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1214240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a30b0>, '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 0x12a3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a4440>, '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': '3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026', '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 04:05:06 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 04:05:06 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 04:05:06 INFO - Using env: {'DISPLAY': ':0', 04:05:06 INFO - 'HOME': '/home/cltbld', 04:05:06 INFO - 'LANG': 'en_US.UTF-8', 04:05:06 INFO - 'LANGUAGE': 'en_US:en', 04:05:06 INFO - 'LOGNAME': 'cltbld', 04:05:06 INFO - 'MAIL': '/var/mail/cltbld', 04:05:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:06 INFO - 'MOZ_NO_REMOTE': '1', 04:05:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:06 INFO - 'NO_EM_RESTART': '1', 04:05:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:06 INFO - 'PWD': '/builds/slave/test', 04:05:06 INFO - 'SHELL': '/bin/bash', 04:05:06 INFO - 'SHLVL': '1', 04:05:06 INFO - 'TERM': 'linux', 04:05:06 INFO - 'TMOUT': '86400', 04:05:06 INFO - 'USER': 'cltbld', 04:05:06 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026', 04:05:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:06 INFO - '_': '/tools/buildbot/bin/python'} 04:05:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:06 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:05:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 04:05:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:05:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:05:08 INFO - Installing collected packages: mozsystemmonitor 04:05:08 INFO - Running setup.py install for mozsystemmonitor 04:05:08 INFO - Successfully installed mozsystemmonitor 04:05:08 INFO - Cleaning up... 04:05:08 INFO - Return code: 0 04:05:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:05:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:08 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 0x7f8d33a7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1214240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a30b0>, '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 0x12a3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a4440>, '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': '3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026', '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 04:05:08 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 04:05:08 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 04:05:08 INFO - Using env: {'DISPLAY': ':0', 04:05:08 INFO - 'HOME': '/home/cltbld', 04:05:08 INFO - 'LANG': 'en_US.UTF-8', 04:05:08 INFO - 'LANGUAGE': 'en_US:en', 04:05:08 INFO - 'LOGNAME': 'cltbld', 04:05:08 INFO - 'MAIL': '/var/mail/cltbld', 04:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:08 INFO - 'MOZ_NO_REMOTE': '1', 04:05:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:08 INFO - 'NO_EM_RESTART': '1', 04:05:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:08 INFO - 'PWD': '/builds/slave/test', 04:05:08 INFO - 'SHELL': '/bin/bash', 04:05:08 INFO - 'SHLVL': '1', 04:05:08 INFO - 'TERM': 'linux', 04:05:08 INFO - 'TMOUT': '86400', 04:05:08 INFO - 'USER': 'cltbld', 04:05:08 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026', 04:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:08 INFO - '_': '/tools/buildbot/bin/python'} 04:05:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:08 INFO - Downloading/unpacking blobuploader==1.2.4 04:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:10 INFO - Downloading blobuploader-1.2.4.tar.gz 04:05:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 04:05:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:05:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:05:10 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 04:05:10 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 04:05:10 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 04:05:10 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 04:05:10 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 04:05:10 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 04:05:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 04:05:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:05:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:05:10 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 04:05:10 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 04:05:10 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 04:05:10 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 04:05:10 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 04:05:10 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 04:05:10 INFO - Downloading docopt-0.6.1.tar.gz 04:05:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 04:05:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:05:10 INFO - Installing collected packages: blobuploader, requests, docopt 04:05:10 INFO - Running setup.py install for blobuploader 04:05:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:05:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:05:11 INFO - Running setup.py install for requests 04:05:11 INFO - Running setup.py install for docopt 04:05:11 INFO - Successfully installed blobuploader requests docopt 04:05:11 INFO - Cleaning up... 04:05:11 INFO - Return code: 0 04:05:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:05:11 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')]} 04:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:11 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')]} 04:05:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:11 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 0x7f8d33a7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1214240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a30b0>, '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 0x12a3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a4440>, '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': '3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026', '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 04:05:11 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 04:05:11 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 04:05:11 INFO - Using env: {'DISPLAY': ':0', 04:05:11 INFO - 'HOME': '/home/cltbld', 04:05:11 INFO - 'LANG': 'en_US.UTF-8', 04:05:11 INFO - 'LANGUAGE': 'en_US:en', 04:05:11 INFO - 'LOGNAME': 'cltbld', 04:05:11 INFO - 'MAIL': '/var/mail/cltbld', 04:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:11 INFO - 'MOZ_NO_REMOTE': '1', 04:05:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:11 INFO - 'NO_EM_RESTART': '1', 04:05:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:11 INFO - 'PWD': '/builds/slave/test', 04:05:11 INFO - 'SHELL': '/bin/bash', 04:05:11 INFO - 'SHLVL': '1', 04:05:11 INFO - 'TERM': 'linux', 04:05:11 INFO - 'TMOUT': '86400', 04:05:11 INFO - 'USER': 'cltbld', 04:05:11 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026', 04:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:11 INFO - '_': '/tools/buildbot/bin/python'} 04:05:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:05:11 INFO - Running setup.py (path:/tmp/pip-KdQUwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:05:11 INFO - Running setup.py (path:/tmp/pip-gcyXJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:05:11 INFO - Running setup.py (path:/tmp/pip-g_f7Wl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:05:11 INFO - Running setup.py (path:/tmp/pip-GnQOjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:05:12 INFO - Running setup.py (path:/tmp/pip-IHJ8J9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:05:12 INFO - Running setup.py (path:/tmp/pip-aDijum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:05:12 INFO - Running setup.py (path:/tmp/pip-HfRw5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:05:12 INFO - Running setup.py (path:/tmp/pip-ZPBfNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:05:12 INFO - Running setup.py (path:/tmp/pip-jLalT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:05:12 INFO - Running setup.py (path:/tmp/pip-4XVwKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:05:12 INFO - Running setup.py (path:/tmp/pip-hQHl2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:05:12 INFO - Running setup.py (path:/tmp/pip-wLdpRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:05:12 INFO - Running setup.py (path:/tmp/pip-gCe_P0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:05:12 INFO - Running setup.py (path:/tmp/pip-0FcOOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:05:12 INFO - Running setup.py (path:/tmp/pip-OJYQiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:05:12 INFO - Running setup.py (path:/tmp/pip-cS98mF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:05:12 INFO - Running setup.py (path:/tmp/pip-Gbz82L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:05:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:05:12 INFO - Running setup.py install for manifestparser 04:05:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:05:13 INFO - Running setup.py install for mozcrash 04:05:13 INFO - Running setup.py install for mozdebug 04:05:13 INFO - Running setup.py install for mozdevice 04:05:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:05:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:05:13 INFO - Running setup.py install for mozfile 04:05:13 INFO - Running setup.py install for mozhttpd 04:05:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:05:13 INFO - Running setup.py install for mozinfo 04:05:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:05:13 INFO - Running setup.py install for mozInstall 04:05:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:05:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:05:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:05:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:05:13 INFO - Running setup.py install for mozleak 04:05:13 INFO - Running setup.py install for mozlog 04:05:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:05:14 INFO - Running setup.py install for moznetwork 04:05:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:05:14 INFO - Running setup.py install for mozprocess 04:05:14 INFO - Running setup.py install for mozprofile 04:05:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:05:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:05:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:05:14 INFO - Running setup.py install for mozrunner 04:05:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:05:14 INFO - Running setup.py install for mozscreenshot 04:05:14 INFO - Running setup.py install for moztest 04:05:14 INFO - Running setup.py install for mozversion 04:05:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:05:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:05:14 INFO - Cleaning up... 04:05:14 INFO - Return code: 0 04:05:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:05:14 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')]} 04:05:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:14 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')]} 04:05:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:14 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 0x7f8d33a7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1214240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a30b0>, '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 0x12a3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a4440>, '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': '3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026', '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 04:05:14 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 04:05:14 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 04:05:14 INFO - Using env: {'DISPLAY': ':0', 04:05:14 INFO - 'HOME': '/home/cltbld', 04:05:14 INFO - 'LANG': 'en_US.UTF-8', 04:05:14 INFO - 'LANGUAGE': 'en_US:en', 04:05:14 INFO - 'LOGNAME': 'cltbld', 04:05:14 INFO - 'MAIL': '/var/mail/cltbld', 04:05:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:14 INFO - 'MOZ_NO_REMOTE': '1', 04:05:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:14 INFO - 'NO_EM_RESTART': '1', 04:05:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:14 INFO - 'PWD': '/builds/slave/test', 04:05:14 INFO - 'SHELL': '/bin/bash', 04:05:14 INFO - 'SHLVL': '1', 04:05:14 INFO - 'TERM': 'linux', 04:05:14 INFO - 'TMOUT': '86400', 04:05:14 INFO - 'USER': 'cltbld', 04:05:14 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026', 04:05:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:14 INFO - '_': '/tools/buildbot/bin/python'} 04:05:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:05:14 INFO - Running setup.py (path:/tmp/pip-dV7_l0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:05:15 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)) 04:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:05:15 INFO - Running setup.py (path:/tmp/pip-YvkCCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:05:15 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)) 04:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:05:15 INFO - Running setup.py (path:/tmp/pip-WZFwhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:05:15 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)) 04:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:05:15 INFO - Running setup.py (path:/tmp/pip-e3lGiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:05:15 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)) 04:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:05:15 INFO - Running setup.py (path:/tmp/pip-H0qEsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:05:15 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)) 04:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:05:15 INFO - Running setup.py (path:/tmp/pip-Qngov5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:05:15 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)) 04:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:05:15 INFO - Running setup.py (path:/tmp/pip-weu9Z7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:05:15 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)) 04:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:05:15 INFO - Running setup.py (path:/tmp/pip-dKY1UK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:05:15 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)) 04:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:05:15 INFO - Running setup.py (path:/tmp/pip-Lv9lGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:05:15 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)) 04:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:05:15 INFO - Running setup.py (path:/tmp/pip-oB5UvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:05:15 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)) 04:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:05:15 INFO - Running setup.py (path:/tmp/pip-cnSYcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:05:15 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)) 04:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:05:15 INFO - Running setup.py (path:/tmp/pip-yg6lUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:05:15 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)) 04:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:05:15 INFO - Running setup.py (path:/tmp/pip-SxjgtF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:05:15 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)) 04:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:05:15 INFO - Running setup.py (path:/tmp/pip-i_Cw_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:05:15 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)) 04:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:05:15 INFO - Running setup.py (path:/tmp/pip-rKQl9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:05:16 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)) 04:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:05:16 INFO - Running setup.py (path:/tmp/pip-VJQylQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:05:16 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)) 04:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:05:16 INFO - Running setup.py (path:/tmp/pip-sLjYcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:05:16 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)) 04:05:16 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)) 04:05:16 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)) 04:05:16 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)) 04:05:16 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)) 04:05:16 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)) 04:05:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:17 INFO - Downloading blessings-1.5.1.tar.gz 04:05:17 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 04:05:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:05:17 INFO - Installing collected packages: blessings 04:05:17 INFO - Running setup.py install for blessings 04:05:18 INFO - Successfully installed blessings 04:05:18 INFO - Cleaning up... 04:05:18 INFO - Return code: 0 04:05:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:05:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:05:18 INFO - Reading from file tmpfile_stdout 04:05:18 INFO - Current package versions: 04:05:18 INFO - argparse == 1.2.1 04:05:18 INFO - blessings == 1.5.1 04:05:18 INFO - blobuploader == 1.2.4 04:05:18 INFO - docopt == 0.6.1 04:05:18 INFO - manifestparser == 1.1 04:05:18 INFO - mozInstall == 1.12 04:05:18 INFO - mozcrash == 0.16 04:05:18 INFO - mozdebug == 0.1 04:05:18 INFO - mozdevice == 0.47 04:05:18 INFO - mozfile == 1.2 04:05:18 INFO - mozhttpd == 0.7 04:05:18 INFO - mozinfo == 0.9 04:05:18 INFO - mozleak == 0.1 04:05:18 INFO - mozlog == 3.1 04:05:18 INFO - moznetwork == 0.27 04:05:18 INFO - mozprocess == 0.22 04:05:18 INFO - mozprofile == 0.27 04:05:18 INFO - mozrunner == 6.11 04:05:18 INFO - mozscreenshot == 0.1 04:05:18 INFO - mozsystemmonitor == 0.0 04:05:18 INFO - moztest == 0.7 04:05:18 INFO - mozversion == 1.4 04:05:18 INFO - psutil == 3.1.1 04:05:18 INFO - requests == 1.2.3 04:05:18 INFO - wsgiref == 0.1.2 04:05:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:05:18 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')]} 04:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:18 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')]} 04:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:18 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 0x7f8d33a7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1214240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a30b0>, '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 0x12a3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a4440>, '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': '3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026', '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 04:05:18 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 04:05:18 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 04:05:18 INFO - Using env: {'DISPLAY': ':0', 04:05:18 INFO - 'HOME': '/home/cltbld', 04:05:18 INFO - 'LANG': 'en_US.UTF-8', 04:05:18 INFO - 'LANGUAGE': 'en_US:en', 04:05:18 INFO - 'LOGNAME': 'cltbld', 04:05:18 INFO - 'MAIL': '/var/mail/cltbld', 04:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:18 INFO - 'MOZ_NO_REMOTE': '1', 04:05:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:18 INFO - 'NO_EM_RESTART': '1', 04:05:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:18 INFO - 'PWD': '/builds/slave/test', 04:05:18 INFO - 'SHELL': '/bin/bash', 04:05:18 INFO - 'SHLVL': '1', 04:05:18 INFO - 'TERM': 'linux', 04:05:18 INFO - 'TMOUT': '86400', 04:05:18 INFO - 'USER': 'cltbld', 04:05:18 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026', 04:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:18 INFO - '_': '/tools/buildbot/bin/python'} 04:05:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:18 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)) 04:05:18 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)) 04:05:18 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)) 04:05:18 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)) 04:05:18 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)) 04:05:18 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)) 04:05:18 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)) 04:05:18 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)) 04:05:18 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)) 04:05:18 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)) 04:05:18 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)) 04:05:18 INFO - Cleaning up... 04:05:18 INFO - Return code: 0 04:05:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:05:18 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')]} 04:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:18 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')]} 04:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:18 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 0x7f8d33a7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1214240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a30b0>, '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 0x12a3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a4440>, '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': '3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026', '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 04:05:18 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 04:05:18 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 04:05:18 INFO - Using env: {'DISPLAY': ':0', 04:05:18 INFO - 'HOME': '/home/cltbld', 04:05:18 INFO - 'LANG': 'en_US.UTF-8', 04:05:18 INFO - 'LANGUAGE': 'en_US:en', 04:05:18 INFO - 'LOGNAME': 'cltbld', 04:05:18 INFO - 'MAIL': '/var/mail/cltbld', 04:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:18 INFO - 'MOZ_NO_REMOTE': '1', 04:05:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:18 INFO - 'NO_EM_RESTART': '1', 04:05:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:18 INFO - 'PWD': '/builds/slave/test', 04:05:18 INFO - 'SHELL': '/bin/bash', 04:05:18 INFO - 'SHLVL': '1', 04:05:18 INFO - 'TERM': 'linux', 04:05:18 INFO - 'TMOUT': '86400', 04:05:18 INFO - 'USER': 'cltbld', 04:05:18 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026', 04:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:18 INFO - '_': '/tools/buildbot/bin/python'} 04:05:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:18 INFO - Downloading/unpacking jsonschema 04:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:05:20 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 04:05:20 INFO - Downloading/unpacking functools32 (from jsonschema) 04:05:20 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 04:05:20 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 04:05:20 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 04:05:20 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 04:05:20 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 04:05:20 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 04:05:20 INFO - Downloading functools32-3.2.3-2.tar.gz 04:05:20 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 04:05:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:05:20 INFO - warning: no files found matching '*.txt' 04:05:20 INFO - no previously-included directories found matching 'build' 04:05:20 INFO - no previously-included directories found matching 'dist' 04:05:20 INFO - no previously-included directories found matching '.git*' 04:05:20 INFO - Installing collected packages: jsonschema, functools32 04:05:20 INFO - Running setup.py install for functools32 04:05:20 INFO - warning: no files found matching '*.txt' 04:05:20 INFO - no previously-included directories found matching 'build' 04:05:20 INFO - no previously-included directories found matching 'dist' 04:05:20 INFO - no previously-included directories found matching '.git*' 04:05:20 INFO - Successfully installed jsonschema functools32 04:05:20 INFO - Cleaning up... 04:05:20 INFO - Return code: 0 04:05:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:05:20 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')]} 04:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:20 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')]} 04:05:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:20 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 0x7f8d33a7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1214240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a30b0>, '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 0x12a3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a4440>, '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': '3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026', '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 04:05:20 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 04:05:20 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 04:05:20 INFO - Using env: {'DISPLAY': ':0', 04:05:20 INFO - 'HOME': '/home/cltbld', 04:05:20 INFO - 'LANG': 'en_US.UTF-8', 04:05:20 INFO - 'LANGUAGE': 'en_US:en', 04:05:20 INFO - 'LOGNAME': 'cltbld', 04:05:20 INFO - 'MAIL': '/var/mail/cltbld', 04:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:20 INFO - 'MOZ_NO_REMOTE': '1', 04:05:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:20 INFO - 'NO_EM_RESTART': '1', 04:05:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:20 INFO - 'PWD': '/builds/slave/test', 04:05:20 INFO - 'SHELL': '/bin/bash', 04:05:20 INFO - 'SHLVL': '1', 04:05:20 INFO - 'TERM': 'linux', 04:05:20 INFO - 'TMOUT': '86400', 04:05:20 INFO - 'USER': 'cltbld', 04:05:20 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026', 04:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:20 INFO - '_': '/tools/buildbot/bin/python'} 04:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:21 INFO - Downloading/unpacking flake8 04:05:21 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 04:05:21 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 04:05:21 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 04:05:21 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 04:05:21 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 04:05:21 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 04:05:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:05:22 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 04:05:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:05:22 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 04:05:22 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 04:05:22 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 04:05:22 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 04:05:22 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 04:05:22 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 04:05:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:05:22 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 04:05:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:05:22 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 04:05:22 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 04:05:22 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 04:05:22 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 04:05:22 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 04:05:22 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 04:05:23 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 04:05:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:05:23 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 04:05:23 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 04:05:23 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 04:05:23 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 04:05:23 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 04:05:23 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 04:05:23 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 04:05:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:05:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:05:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:05:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:05:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:05:23 INFO - no previously-included directories found matching 'docs/_build' 04:05:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:05:23 INFO - Running setup.py install for pep8 04:05:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:05:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:05:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:05:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:05:23 INFO - no previously-included directories found matching 'docs/_build' 04:05:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:05:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:05:23 INFO - Cleaning up... 04:05:23 INFO - Return code: 0 04:05:23 INFO - Running post-action listener: _resource_record_post_action 04:05:23 INFO - Running post-action listener: _start_resource_monitoring 04:05:23 INFO - Starting resource monitoring. 04:05:23 INFO - ##### 04:05:23 INFO - ##### Running install step. 04:05:23 INFO - ##### 04:05:23 INFO - Running pre-action listener: _resource_record_pre_action 04:05:23 INFO - Running main action method: install 04:05:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:05:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:05:23 INFO - Reading from file tmpfile_stdout 04:05:23 INFO - Detecting whether we're running mozinstall >=1.0... 04:05:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:05:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:05:23 INFO - Reading from file tmpfile_stdout 04:05:23 INFO - Output received: 04:05:23 INFO - Usage: mozinstall [options] installer 04:05:23 INFO - Options: 04:05:23 INFO - -h, --help show this help message and exit 04:05:23 INFO - -d DEST, --destination=DEST 04:05:23 INFO - Directory to install application into. [default: 04:05:23 INFO - "/builds/slave/test"] 04:05:23 INFO - --app=APP Application being installed. [default: firefox] 04:05:23 INFO - mkdir: /builds/slave/test/build/application 04:05:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:05:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:05:38 INFO - Reading from file tmpfile_stdout 04:05:38 INFO - Output received: 04:05:38 INFO - /builds/slave/test/build/application/firefox/firefox 04:05:38 INFO - Running post-action listener: _resource_record_post_action 04:05:38 INFO - ##### 04:05:38 INFO - ##### Running run-tests step. 04:05:38 INFO - ##### 04:05:38 INFO - Running pre-action listener: _resource_record_pre_action 04:05:38 INFO - Running main action method: run_tests 04:05:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:05:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:05:38 INFO - Python 2.7.3 04:05:38 INFO - Return code: 0 04:05:38 INFO - grabbing minidump binary from tooltool 04:05:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:05:38 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 0x12a30b0>, '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 0x12a3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a4440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:05:38 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 04:05:38 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 04:05:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:05:38 INFO - Return code: 0 04:05:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:05:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:05:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:05:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:05:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:05:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:05:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:05:39 INFO - Return code: 0 04:05:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--webServer', 'localhost'] in /builds/slave/test/build 04:05:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --webServer localhost 04:05:39 INFO - Using env: {'DISPLAY': ':0', 04:05:39 INFO - 'HOME': '/home/cltbld', 04:05:39 INFO - 'LANG': 'en_US.UTF-8', 04:05:39 INFO - 'LANGUAGE': 'en_US:en', 04:05:39 INFO - 'LOGNAME': 'cltbld', 04:05:39 INFO - 'MAIL': '/var/mail/cltbld', 04:05:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:05:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:05:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:39 INFO - 'MOZ_NO_REMOTE': '1', 04:05:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:05:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:39 INFO - 'NO_EM_RESTART': '1', 04:05:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:39 INFO - 'PWD': '/builds/slave/test', 04:05:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:05:39 INFO - 'SHELL': '/bin/bash', 04:05:39 INFO - 'SHLVL': '1', 04:05:39 INFO - 'TERM': 'linux', 04:05:39 INFO - 'TMOUT': '86400', 04:05:39 INFO - 'USER': 'cltbld', 04:05:39 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026', 04:05:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:39 INFO - '_': '/tools/buildbot/bin/python'} 04:05:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--webServer', 'localhost'] with output_timeout 3600 04:05:39 INFO - mozversion INFO | application_buildid: 20151209030353 04:05:39 INFO - mozversion INFO | application_changeset: 319be5e7ce3061c7c16f24d750b6dacdbcac4c35 04:05:39 INFO - mozversion INFO | application_display_name: Nightly 04:05:39 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:05:39 INFO - mozversion INFO | application_name: Firefox 04:05:39 INFO - mozversion INFO | application_remotingname: firefox 04:05:39 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 04:05:39 INFO - mozversion INFO | application_vendor: Mozilla 04:05:39 INFO - mozversion INFO | application_version: 45.0a1 04:05:39 INFO - mozversion INFO | platform_buildid: 20151209030353 04:05:39 INFO - mozversion INFO | platform_changeset: 319be5e7ce3061c7c16f24d750b6dacdbcac4c35 04:05:39 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 04:05:39 INFO - mozversion INFO | platform_version: 45.0a1 04:05:39 INFO - 2015-12-09 04:05:39,726 DEBUG : using testdate: 1449662739 04:05:39 INFO - 2015-12-09 04:05:39,726 DEBUG : actual date: 1449662739 04:05:39 INFO - 2015-12-09 04:05:39,775 INFO : starting webserver on 'localhost:41286' 04:05:39 INFO - 2015-12-09 04:05:39,776 INFO : Starting test suite talos-linux64-ix-067.e 04:05:39 INFO - 2015-12-09 04:05:39,776 INFO : Starting test dromaeo_css 04:05:39 INFO - 2015-12-09 04:05:39,776 DEBUG : operating with platform_type : linux_ 04:05:39 INFO - 2015-12-09 04:05:39,777 INFO : Initialising browser for dromaeo_css test... 04:05:39 INFO - 2015-12-09 04:05:39,793 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpE8ofE7/profile http://localhost:41286/getInfo.html 04:05:44 INFO - 2015-12-09 04:05:44,555 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:05:44 INFO - 2015-12-09 04:05:44,555 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:05:44 INFO - 2015-12-09 04:05:44,556 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:05:44 INFO - 2015-12-09 04:05:44,556 DEBUG : BROWSER_OUTPUT: __metrics 04:05:44 INFO - 2015-12-09 04:05:44,657 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] 04:05:45 INFO - 2015-12-09 04:05:45,538 INFO : Browser initialized. 04:05:45 INFO - 2015-12-09 04:05:45,539 INFO : Running cycle 1/1 for dromaeo_css test... 04:05:45 INFO - 2015-12-09 04:05:45,539 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpE8ofE7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:05:57 INFO - 2015-12-09 04:05:57,589 DEBUG : BROWSER_OUTPUT: RSS: Main: 143368192 04:05:57 INFO - 2015-12-09 04:05:57,590 DEBUG : BROWSER_OUTPUT: 04:06:47 INFO - 2015-12-09 04:06:47,215 DEBUG : BROWSER_OUTPUT: 1449662807211 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] 04:08:58 INFO - 2015-12-09 04:08:58,959 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41286/tests/dromaeo/cssquery-ext.html) 04:08:59 INFO - 2015-12-09 04:08:59,922 DEBUG : BROWSER_OUTPUT: RSS: Main: 145915904 04:08:59 INFO - 2015-12-09 04:08:59,922 DEBUG : BROWSER_OUTPUT: 04:12:01 INFO - 2015-12-09 04:12:01,857 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/dromaeo/cssquery-ext.html (next: http://localhost:41286/tests/dromaeo/cssquery-jquery.html) 04:12:02 INFO - 2015-12-09 04:12:02,450 DEBUG : BROWSER_OUTPUT: RSS: Main: 144687104 04:12:02 INFO - 2015-12-09 04:12:02,451 DEBUG : BROWSER_OUTPUT: 04:15:04 INFO - 2015-12-09 04:15:04,259 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41286/tests/dromaeo/cssquery-mootools.html) 04:15:04 INFO - 2015-12-09 04:15:04,860 DEBUG : BROWSER_OUTPUT: RSS: Main: 144875520 04:15:04 INFO - 2015-12-09 04:15:04,860 DEBUG : BROWSER_OUTPUT: 04:18:06 INFO - 2015-12-09 04:18:06,605 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41286/tests/dromaeo/cssquery-prototype.html) 04:18:07 INFO - 2015-12-09 04:18:07,190 DEBUG : BROWSER_OUTPUT: RSS: Main: 144551936 04:18:07 INFO - 2015-12-09 04:18:07,190 DEBUG : BROWSER_OUTPUT: 04:21:09 INFO - 2015-12-09 04:21:09,721 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41286/tests/dromaeo/cssquery-yui.html) 04:21:10 INFO - 2015-12-09 04:21:10,323 DEBUG : BROWSER_OUTPUT: RSS: Main: 145260544 04:21:10 INFO - 2015-12-09 04:21:10,323 DEBUG : BROWSER_OUTPUT: 04:24:12 INFO - 2015-12-09 04:24:12,600 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/dromaeo/cssquery-yui.html (next: http://localhost:41286/tests/dromaeo/cssquery-dojo.html) 04:24:12 INFO - 2015-12-09 04:24:12,850 DEBUG : BROWSER_OUTPUT: RSS: Main: 147247104 04:24:12 INFO - 2015-12-09 04:24:12,850 DEBUG : BROWSER_OUTPUT: 04:24:12 INFO - 2015-12-09 04:24:12,852 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:24:12 INFO - 2015-12-09 04:24:12,852 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:24:12 INFO - 2015-12-09 04:24:12,852 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:24:12 INFO - 2015-12-09 04:24:12,852 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:24:12 INFO - 2015-12-09 04:24:12,854 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2659.1756555467805;2693.622892795008;2728.7953403494744;2738.657667791526;2746.1898739871735;4500.167469018225;4723.48041715411;4774.761261936902;4778.4504781950045;4790.425148982118;4664.440267167936;4737.715737055779;4772.546608072227;4796.2086255767845;4797.208460604005;4298.763568003758;4378.62781663559;4443.466783985927;4448.177087238855;4482.551744825524;4323.978380108111;4350.390945267665;4437.445319335083;4465.754383508905;4495.483019452756;4235.470566179228;4253.468316460442;4360.738355698668;4374.606285434326;4380.7152535085115;4402.493713222988;4414.84548040817;4430.224710675651;4432.822687092513;4445.599896009375;4396.010897548058;4408.515063343029;4417.712848664826;4434.84478043267;4440.888977775549;4448.5773851484055;4573.245414506602;4586.564276393738;4711.646626502992;4719.339292499053;5079.492050794928;5119.513646203604;5138.30632864566;5149.588032957355;5189;5174.327337446146;5174.818881339173;5189.2475591039465;5193.195054766479;5195.116830138885;4576.244919588264;4683;4687.179743544866;4753.31076993834;4809.663323567387;5187.844364669066;5256.448072952285;5284.392294886137;5293.708846013429;5343.385510666245;4859.902801943941;5056.342675452168;5078.238264260391;5094.108531007059;5107.6169287302855;4962.305277261186;5010.5240002199735;5033.597312215014;5038.3198268234055;5098.490150984871;5055.646104772704;5057.949420505822;5066.442691303953;5086.3896332440345;5089.109405853961;4860.878478038077;4976.154053810861;4982.153033984231;4984.626153038536;5012.774425150877;4121.443605113272;4135.31767258399;4135.7518548887165;4177.874663760092;4179.644730197967;3620.6741393274733;3666.5783434904793;3685.3919103348185;3686.078480379905;3695.9445608315896;4246.278132717445;4349.826006959749;4355.499117601453;4394.516603173648;4405.977970110193;5124.8206312778875;5150.253213284052;5154.020736060136;5169.431362550116;5170.3278573785165;4439.289713645801;4449.087936972913;4450.132224216311;4470.597646211857;4476.104779044204;4258.254805409041;4303.548127446573;4402.427684401007;4444.31113177458;4521.231390663529;4815.494373090775;4834.540718631724;4854.708717476963;4867.075255701406;4901.2893130496595;5150.871228219175;5171.095058364846;5171.612129090408;5176.663516871391;5233.110371236975;4707.62339012885;4795.592374648194;4860.975695121501;4892.241702536113;4932.8520144395725;5041.269015992734;5048.3941926969;5053.898922021613;5076.746162691925;5100.48995100487;4909.3372394727685;5149.588032957429;5151.330327057459;5182.093133701663;5184.066867963803;4844.95155048445;4850.078485087822;4890.975545122252;4918.2868484068895;4955.851324460272;4895.290182923527;4906.55840974314;4907.092187945099;4909.0101643156;4930.482299358516;4637.76811159434;4816.060868130681;4830.903381932412;4846.5638092571835;4907.33750943618;4777.163996300564;4855.514448555116;4860.854174374775;4886.169351210374;4940.6788558743565;4917.729524876167;4935;4944.653874228769;4946.802127914843;4946.950530494649 04:24:12 INFO - 2015-12-09 04:24:12,857 DEBUG : BROWSER_OUTPUT: |1;ext.html;493.84690745868807;525.9368875734912;531.2535887078654;533.8932213557287;542.0568211312318;25589.232323030315;26172.73827261729;33820.830895845516;34187;34193;40681.59318406815;41282.7935860321;41416.378754318706;41472.37791433131;41492;7851.332636725885;8029.839403211932;8058.315043221333;8069.273765361117;8096.716614918479;7875.4487185897015;8003.079645840716;8003.679852805881;8011.519308841459;8039.155888631668;7956.880646790303;8010.919890801105;8028.518288902685;8064.475809072392;8071.31393831525;7676.385889128856;7713;7766.18455062216;7772.805679857992;7776.533407995482;7846.489978151402;7961.601919903982;7977.401694872908;7977.441579089466;7985;37563.62436375656;38005.42991855124;38079.23841523182;38082;38112.618873811465;3022.2746540830044;3063.127008802503;3075.0928476099602;3104.767142464324;3563.9109022274392;28726.281842954093;28727.569086463718;28923;28945.131646050653;29421.55867661987;3393.6606339366112;3548.449990251515;3570.4644303354703;3601.963980360189;3610.837512311952;322947;329919;330198;330573;334992;39131.41302880459;39324.21351572953;39574.80212598918;56643.433565663814;57511;36937;37125.814370927976;37237.06907327284;37284.25431491355;37445.25109497849;13037.608871733863;13134.802977955338;13147.0797044206;13158.539451119357;13186.604401867959;10176.592936282614;10183.083522483012;10254.53854576546;10255.743606409751;10301.639442619473;13567.728645427234;13660.65848353799;13668.658283542793;13677.31613419325;13765.587032389045;5429.67421954684;5529.198266251451;5588.748506317144;5591.161325801162;5617.971910140505;950.3775027357092;968.6270785747538;999.3953658036928;1008.3344992305043;1014.7057353367588;25923;26042.609360859602;26107.608385874228;26264;26301.605475917877;918.320442872283;931.1526510875071;976.9120779129912;1029.0738335497963;1050.0654417568371;13263.469461221443;13388.531401400904;13479.326033698471;14837.480688176078;15477.148756818484;316028;319624;320185;322729;322807;48257;48450;48885.75557122192;49207.50792492029;49576.256356154685;1213.5631172777794;1236.3076677060874;1240.4170040081146;1262.6464589914838;1265.1270623270098;27855;27988.60056997101;28311.15066548007;28454.288642784097;28577.857110715144;40738;41180.79409602933;41303.58696413117;41304;41388;252423;252432;252522;254195;255283;30048;41897.37153942754;43404;43580.782096089315;43617.78191109024;42626;43953.78023109864;46547.6035718929;46626.76686616681;47318;32922.01233962985;33778.8311058453;33901.15247118743;33922.3215535683;33924.49113263254;33186.33627327488;33684.326313474085;33904.66095339015;33916.660833391354;34021.31957360789;33414.665853341154;33468.49797252994;33477.33045339128;33536.329273414885;33727.83136084304;28733;29589;30572.541411878396;30601.23496912506;30669.23326916845;23539.41151471227;24090.450353228265;24208.488042480727;24362.63456048196;24502.63246051275 04:24:12 INFO - 2015-12-09 04:24:12,857 DEBUG : BROWSER_OUTPUT: |2;jquery.html;273.35760961739913;274.04085700049825;274.0572430837917;275.225240946735;278.94560560690667;12606.81089783654;14642;15084;15089.773653395194;16064.919675401607;14802.703945921076;14877.330520126594;14913.32890019949;15039.924800375982;15148.772768408482;4519.847438903075;4524.072565124142;4531.3203019547;4537.137943790669;4705.694129881546;4799.592034677056;4808.663393562452;4840.56434920857;4842.443119041302;4845.975770121145;4799.088173247089;4799.160146974283;4867.902641947159;5019.54824065834;5032.672876263049;4961.330220420233;4966.652334336562;4980.377452818398;4993.525615066563;4994.625403094764;5146.408163061258;5154.768035438415;5160.870978225536;5165.690058596496;5178.766955487012;11120.332780033224;11277.718057048558;13389;18670.81329186704;18852.434426967215;1786.1872847854195;1845.6862333403353;1847.5750577367146;1847.7228415737613;1961.5096225943514;8756.781080472976;8959.238464730506;9065.093490650948;10168.644097456627;12841.614751557468;1768.9911550442293;1794.2105473591578;1835.2292037344348;1860.3209828412532;1869.2896699254331;56218.71890640521;57340;57802.421975780126;58338;58648.41351586515;9399.671011514563;9514.238860891111;9524.90475095254;9613.423194608346;9653.276004299705;11925.22486038422;11960.521579136912;11986.220895641756;11996.400179990966;12013.099017573717;6785.457163426914;6924.099867017357;6958.269381714948;6960.686769095403;7153.60655163971;6597.637129957802;6720.697568609425;6729.3270672933295;6738.460923126138;6774.559653622498;3865.40086286626;3896.8051597420017;3935.8032098394965;3940.9605903941165;4114.321137012359;1602.359056377435;1638.9262483188286;1666.4084250091285;1694.1698567701983;1714.79422469302;775.46880386934;784.7645706288091;879.3273146043282;892.2727976698969;1008.0393634381059;9827.901720982842;9850.950745246228;10049.045340692623;10128.848067278997;15433.614159645875;351.3342216499719;362.5069904929294;362.896574476273;364.70641133886943;368.6129563957833;5342.9465705342445;5367.973160134175;5385.892282154413;5405.243265942771;5465.781368745205;321711;324879;328267;328369;489224;78372;78415.2158478408;78591;80040.59979700064;80126.5993670028;403.93537034074404;406.69497876592555;408.0431388394211;408.6424378668665;409.57199726286103;9523.333366664267;9575.425474471554;9575.712728618153;9749.610015599297;9771.853422198816;34111.82944085363;35232.47151292784;35960.28079438449;36404;36606.816965915;42781.57218427776;43694.78152609216;45851;46462.30306545472;47797.76101119472;8247.835043299221;8723.258523025614;8740.60667269987;8773.868391973998;8778.824423511622;15414.152221627917;15584.688306233586;15693.843061569238;16142.919285403497;16221.67556648884;13145.474181033034;13829.170249785046;13910.304484775923;13956.79064814049;13966.092204006707;13032.218066916015;13096.541621043218;13630.182189068688;14030.719385612434;14125.011249212457;13845.16928984264;14072.296385180905;14180.29098545048;14324.641883952985;14423.423063077778;8695.782605434662;8697.304215662605;8917.28661707049;8920.24177944882;8940.15068568485;9401.435913845191;12626.558070467858;13369.331533423096;13539;14618.780718289447 04:24:12 INFO - 2015-12-09 04:24:12,861 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1391.0401822742313;1406.3109076552487;1463.253740592299;1646.390835390905;1715.9571010724726;5716.942830571693;5855.912161317573;6291.937080629198;6822.351876571724;6919.342662447072;6540.476761859052;6609.603423794576;6649.700763465644;6697.799066028015;6718.966405167967;1989.4230673104819;2035.6234096692085;2035.9694604580943;2037.684158955357;2038.775734669185;2066.886321252338;2080.854340196187;2083.0834432849497;2112.8204102651216;2119;2042.0300357330298;2056.362527616444;2080.750309962799;2081.604495145925;2090.1012564597218;2029.9898500507477;2058.259026750369;2060.1553363121125;2061.0210150178696;2061.5876824635134;1964.5088727818045;1977.2486455147096;1989.1347263940231;1990.8805471671747;1994.3917105282883;6810.28492008337;6822.761203357859;6839.316068393171;6903.206131294915;6924.861502769967;4068.593140685937;4167.666586673089;4178.895527611804;4196;4208.158368326316;2665.6801183857865;2745.7254274572583;2746.0663374452583;2752;2761.1716485054603;821.4414198345123;827.2678679368742;847.7753395350237;856.3662889461816;872.4242000279785;9037;9061.909380906172;9072.999999999935;9085.682001129928;9143.405678630868;3754.81225938702;3761.9247615047548;3832.0611450194324;3840.347140986039;3859.536855577349;7882.5270483771155;7891.329237014918;7894.447388682737;7903.012123484565;7909.802254943557;4084.1219137885496;4100.466939297872;4134.545200027995;4134.896627584334;4177.624013838769;3620.5474315710535;3674.4488326751202;3699.741018128759;3737.5328083989502;3796.1838204786104;3020.7432368248947;3044.2845931206148;3061.280599059219;3094.7214750672097;3104.674009229044;907.3149771922289;907.8184363127269;909.4497828813472;911.0752586125088;921.4609453469661;1176.5764324843049;1180.816973369129;1191.5948577483698;1199.7960346740902;1208.015467394071;6323.27282362525;6393.936060639334;6402.071699603624;6449.677516124175;6502.219733631994;392.0864385980658;398.0546202768425;398.47600405466716;398.89030516607585;402.368281797575;5196.038732834438;5212.843614691566;5214.0875346814155;5287.4448182941005;5294.258803767476;7658.578778167254;7878.842423151505;7949.920500794918;8122.55325957078;8181.3863960203225;7692.230776922375;7772.533647981139;7779.961100194463;7818.061832580127;7877.803054923673;6448.097266382711;6472.676366181672;6516.478681705452;6524.054012168209;6541.738330466728;6272.905906411316;6275.780347687809;6292.2134733158355;6335.859545281709;6350.110984462087;7035.47233957445;7128.786136415916;7164.785056448315;7295.635218239173;7311;7127.679254433459;7208.171060328129;7243.45674074457;7267.563946163247;7428.554286742813;5227.398849132398;5362.1688638259575;5951.613145145551;6026.548008899438;6043.576949613484;6317.842053948688;6322;6327.715252813542;6332.335104813928;6332.493400527855;5811.767529298781;5939.792107276225;6006.249218847644;6051;6092.543059270483;6077.392260773887;6127.632342059491;6131.233595800525;6157.53818463626;6263.561550691408;6027.065804800263;6045.214122164267;6056.788012419544;6086.02623580225;6089.48239399656;5669.036263835075;5694.544436444991;5700.515456186271;5713.714314284352;5728.484436400578;5545.667259964415;5592.287740445961;5655.179998900218;5655.236543066633;5658.773649053992 04:24:12 INFO - 2015-12-09 04:24:12,861 DEBUG : BROWSER_OUTPUT: |4;prototype.html;248.8942199565185;252.3187394036103;252.3715947291244;254.19293742367978;255.08551841647693;5412.864678383043;5498.120300751881;5524.502794748471;5638.323401191858;5643.322801263844;5332.946670533293;5475.753591088401;5491.862703432416;5507.9449205507935;5508.228847961279;4126.669866410687;4143.6892233082635;4153.044799696072;4155.584441555835;4161.230172418097;4161.916761664765;4164.146349998258;4166.020985068507;4166.208420400129;4170.9582904170875;4048.2915489789316;4117.97941010296;4130.194612050651;4133.979330103361;4154.459920210384;4158.7296825706235;4161.604647558477;4161.729487583298;4164.375343698439;4175.206710724983;3562.7327950403565;3565.358235517606;3567.16171699653;3574.3566158091535;3576.5708115026364;5366.114591092465;5373.973130134364;5376.4085950545405;5396.892062158774;5400.648957817729;4443.488998765119;4749.4775574686755;4752.42970843501;4753.643476739224;4759.833405830814;5468.788883248222;5600.691961942091;5753.338366087912;5767.33675627304;5772.076467765138;3832.885013449601;3835.67396771272;3838.560539797576;3844.038990252437;3845.1348446599573;6107.908381374283;6137.693115344215;6140.385961403824;6140.907886381708;6149.262088549358;3868.052327179859;3923.2741942740686;3924.50943632046;3950.525936887592;3953.1105501261986;4006.7996600169877;4007.479027726377;4009.2382447334912;4013.3779264214095;4022.0749227677798;4695.741734204583;4698.506656801055;4698.694584851974;4708.9058218835435;4713.646476514206;5792.536597072224;5821.15593238978;5823.388544202882;5828.88342233153;5851.473367396955;1406.8311802583755;1430.5064752660317;1434.6628542292553;1436.1670231265841;1437.583100900748;876.4784952952983;882.8101958078915;897.9506127162938;924.2282693950492;953.1421720451649;5002.89994200114;5047.268146118866;5060.772265247999;5080.949190508121;5084.593232541388;6015.518758499309;6028.668423236764;6029.246344206974;6030.698465076729;6045.969770151121;5071.18860982241;5075.314832497639;5080.77136528857;5080.923786143211;5085.593152547861;3157.3053928135773;3228.2252259458032;3257.38109759145;3276.737860971117;3285.65500622436;5747.1379293106365;5747.770089196469;5752;5756.683382413924;5757.913631295534;6305.779297724649;6306.779262725782;6308.675178212535;6312.084747711556;6330.272018717815;5647.745851436695;5691.943080569225;5704.828855134353;5743.8564035899435;5743.885122297531;6078.817635470766;6128.1726966858905;6128.693565321805;6141.692915354125;6142.355052719578;5965.135055417027;5969.3732158122775;5974.522038237017;5978.3423823378525;5979.252593425822;5694.4590263924865;5727.940331038772;5728.856778580569;5732.828015159552;5734.942650573441;3745.3258413484746;3869.303525365461;3870.7871067091583;3880;3887.378019516864;6058.545609079425;6085.908711369421;6099.237595300588;6110;6112.449879510866;5830.76676932918;5852.502537284378;5864.853378665567;5865.589408741347;5868.471837534472;5669.404712505293;5842.853928651818;5851.444112809277;5855.151003104441;5858.9121163181735;5764.567657425626;5766.279215098113;5766.452187042224;5766.654000759968;5781.595288329777;5903.380145084876;5904.675242861684;5922.289325280994;5922.437368449991;5936.22829032223;5619.8595035124445;5771.7402716877;5772.682502462405;5777.22007528995;5784.652920824764 04:24:12 INFO - 2015-12-09 04:24:12,864 DEBUG : BROWSER_OUTPUT: |5;yui.html;642.3480167629854;654.5450911616429;683.0028158888023;716.0691101567962;731.2796895058368;11349.829752553718;11499.137564682662;11502.194846360737;11503.482343294561;11515.251508651932;11815.232009919371;11903.702407439818;11960.641180764569;11984.681685014662;12015.579454719087;621.7295476467751;628.4438272129153;785.5365334452672;793.5199204481293;800.6957356204634;788.0228516639355;791.133708589094;793.1513280789558;795.2564352330589;797.6011994002998;826.9090400055989;828.9709860154895;833.6415341403194;834.7579202031403;835.0939230934443;818.4720855048461;829.5810615639094;829.9004119505638;830.9750707478785;831.1065604475183;615.8214117905846;616.2174038970483;616.4605969776445;617.857892684685;618;11318.547258109666;11348.489317980711;11395.829062564067;11405.714857128554;11422.25755325901;10530.210234232387;11407.942960285229;11535.865864815036;11572;11649.534018639244;9367.672131475434;9427.905720942772;9447.763805904839;9451.480168590724;9494.810103797954;3306.487494438366;3336.2993771308056;3369.7809642373168;3390.643982381839;3466.6879980801596;39495.8025209875;39775.40336894949;39817.40273895894;40200.99999999971;40234;9184.586693598803;9403.529823508798;9485.525723713787;9537.189338906272;9628.614855405705;15993.760093598605;16008.359665613245;16027.198640068184;16040.037597744173;16089.839101609068;6155.10750941111;6217.782377616762;6264.780732674334;6304.148939893147;6307.716152773136;3592.588549368303;3638.43604241343;3648.416253399443;3657.908552286214;3734.327820992193;2547.681539807524;2579.8710064497077;2602.7137014928344;2602.8698565071672;2629.6318515407856;486.6301610775836;489.4860396583644;497.88548633814423;500.32456184151107;500.4895007092745;480.6107053286849;501.69471279470775;562.3364429972671;583.0991118721586;614.775606903477;7250.709971601183;7584.5070070445245;7643.7324693635455;7952.085510166289;8371.832563348698;471.42015321154486;479.2212654436541;485.2938973793122;510.3314657798296;602.3284038297267;3320.518524813937;3488.965110348864;3560.964390356115;3681.392570225936;3697.352963231424;47068.5293147071;47906;48570;48617;48904.26643600349;44531.77734111374;44614.55385446169;44677.10645787066;44721.32918006233;45417.31874021892;41357.172856542704;41532.58467415287;41719.58280417157;42101.78949105235;42137.57862421337;7117.537360071578;7304;7325.816854578678;7419.666115024945;7557.206493318123;30914.76340946427;30930.226744331572;31110.533341999435;31179.84410077935;31592.210194745316;35302.29395412026;36126.819365904055;36511.269774603825;39462.80268598753;39721;9291.86062209081;9294.535273236446;9352;9411;9585.664501742405;10196.516680718849;10217.642382516577;10282.228832837716;10357.792844143225;10506.21203409732;9311.860322095039;9410.24718022543;9470.100340467645;9575.138237558653;10252.128569071712;9539.52302384864;9860.753481163028;9981.550830212513;10023.148032417326;10188.235882309005;9357.111074447917;9442.858357124785;9495.24038076966;9530.285228607743;9657.179139773198;8231.711890083818;8291.33669306442;8401.663933442594;8465.534395608054;8873.467591944245;7772.183920688246;7836.583655381282;7870.409719270963;8021.719239826578;8251.092379838094 04:24:12 INFO - 2015-12-09 04:24:12,865 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:24:12 INFO - 2015-12-09 04:24:12,865 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:24:12 INFO - 2015-12-09 04:24:12,865 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,324 04:24:12 INFO - 2015-12-09 04:24:12,865 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:24:12 INFO - 2015-12-09 04:24:12,865 DEBUG : BROWSER_OUTPUT: __startTimestamp1449663852850__endTimestamp 04:24:12 INFO - 2015-12-09 04:24:12,865 DEBUG : BROWSER_OUTPUT: 04:24:12 INFO - 2015-12-09 04:24:12,865 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:24:12 INFO - 2015-12-09 04:24:12,865 DEBUG : BROWSER_OUTPUT: Number of tests: 6 04:24:12 INFO - 2015-12-09 04:24:12,865 DEBUG : BROWSER_OUTPUT: 04:24:12 INFO - 2015-12-09 04:24:12,865 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4691.19 Median:4834.54 stddev:503.44 (10.4%) stddev-sans-first:479.08 04:24:12 INFO - 2015-12-09 04:24:12,866 DEBUG : BROWSER_OUTPUT: Values: 2659.2 2693.6 2728.8 2738.7 2746.2 4500.2 4723.5 4774.8 4778.5 4790.4 4664.4 4737.7 4772.5 4796.2 4797.2 4298.8 4378.6 4443.5 4448.2 4482.6 4324.0 4350.4 4437.4 4465.8 4495.5 4235.5 4253.5 4360.7 4374.6 4380.7 4402.5 4414.8 4430.2 4432.8 4445.6 4396.0 4408.5 4417.7 4434.8 4440.9 4448.6 4573.2 4586.6 4711.6 4719.3 5079.5 5119.5 5138.3 5149.6 5189.0 5174.3 5174.8 5189.2 5193.2 5195.1 4576.2 4683.0 4687.2 4753.3 4809.7 5187.8 5256.4 5284.4 5293.7 5343.4 4859.9 5056.3 5078.2 5094.1 5107.6 4962.3 5010.5 5033.6 5038.3 5098.5 5055.6 5057.9 5066.4 5086.4 5089.1 4860.9 4976.2 4982.2 4984.6 5012.8 4121.4 4135.3 4135.8 4177.9 4179.6 3620.7 3666.6 3685.4 3686.1 3695.9 4246.3 4349.8 4355.5 4394.5 4406.0 5124.8 5150.3 5154.0 5169.4 5170.3 4439.3 4449.1 4450.1 4470.6 4476.1 4258.3 4303.5 4402.4 4444.3 4521.2 4815.5 4834.5 4854.7 4867.1 4901.3 5150.9 5171.1 5171.6 5176.7 5233.1 4707.6 4795.6 4861.0 4892.2 4932.9 5041.3 5048.4 5053.9 5076.7 5100.5 4909.3 5149.6 5151.3 5182.1 5184.1 4845.0 4850.1 4891.0 4918.3 4955.9 4895.3 4906.6 4907.1 4909.0 4930.5 4637.8 4816.1 4830.9 4846.6 4907.3 4777.2 4855.5 4860.9 4886.2 4940.7 4917.7 4935.0 4944.7 4946.8 4947.0 04:24:12 INFO - 2015-12-09 04:24:12,866 DEBUG : BROWSER_OUTPUT: 04:24:12 INFO - 2015-12-09 04:24:12,866 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:44953.37 Median:18659.08 stddev:79549.71 (426.3%) stddev-sans-first:79702.83 04:24:12 INFO - 2015-12-09 04:24:12,866 DEBUG : BROWSER_OUTPUT: Values: 493.8 525.9 531.3 533.9 542.1 25589.2 26172.7 33820.8 34187.0 34193.0 40681.6 41282.8 41416.4 41472.4 41492.0 7851.3 8029.8 8058.3 8069.3 8096.7 7875.4 8003.1 8003.7 8011.5 8039.2 7956.9 8010.9 8028.5 8064.5 8071.3 7676.4 7713.0 7766.2 7772.8 7776.5 7846.5 7961.6 7977.4 7977.4 7985.0 37563.6 38005.4 38079.2 38082.0 38112.6 3022.3 3063.1 3075.1 3104.8 3563.9 28726.3 28727.6 28923.0 28945.1 29421.6 3393.7 3548.4 3570.5 3602.0 3610.8 322947.0 329919.0 330198.0 330573.0 334992.0 39131.4 39324.2 39574.8 56643.4 57511.0 36937.0 37125.8 37237.1 37284.3 37445.3 13037.6 13134.8 13147.1 13158.5 13186.6 10176.6 10183.1 10254.5 10255.7 10301.6 13567.7 13660.7 13668.7 13677.3 13765.6 5429.7 5529.2 5588.7 5591.2 5618.0 950.4 968.6 999.4 1008.3 1014.7 25923.0 26042.6 26107.6 26264.0 26301.6 918.3 931.2 976.9 1029.1 1050.1 13263.5 13388.5 13479.3 14837.5 15477.1 316028.0 319624.0 320185.0 322729.0 322807.0 48257.0 48450.0 48885.8 49207.5 49576.3 1213.6 1236.3 1240.4 1262.6 1265.1 27855.0 27988.6 28311.2 28454.3 28577.9 40738.0 41180.8 41303.6 41304.0 41388.0 252423.0 252432.0 252522.0 254195.0 255283.0 30048.0 41897.4 43404.0 43580.8 43617.8 42626.0 43953.8 46547.6 46626.8 47318.0 32922.0 33778.8 33901.2 33922.3 33924.5 33186.3 33684.3 33904.7 33916.7 34021.3 33414.7 33468.5 33477.3 33536.3 33727.8 28733.0 29589.0 30572.5 30601.2 30669.2 23539.4 24090.5 24208.5 24362.6 24502.6 04:24:12 INFO - 2015-12-09 04:24:12,866 DEBUG : BROWSER_OUTPUT: 04:24:12 INFO - 2015-12-09 04:24:12,866 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:22638.01 Median:408.34 stddev:60235.78 (14751.3%) stddev-sans-first:60381.35 04:24:12 INFO - 2015-12-09 04:24:12,866 DEBUG : BROWSER_OUTPUT: Values: 273.4 274.0 274.1 275.2 278.9 12606.8 14642.0 15084.0 15089.8 16064.9 14802.7 14877.3 14913.3 15039.9 15148.8 4519.8 4524.1 4531.3 4537.1 4705.7 4799.6 4808.7 4840.6 4842.4 4846.0 4799.1 4799.2 4867.9 5019.5 5032.7 4961.3 4966.7 4980.4 4993.5 4994.6 5146.4 5154.8 5160.9 5165.7 5178.8 11120.3 11277.7 13389.0 18670.8 18852.4 1786.2 1845.7 1847.6 1847.7 1961.5 8756.8 8959.2 9065.1 10168.6 12841.6 1769.0 1794.2 1835.2 1860.3 1869.3 56218.7 57340.0 57802.4 58338.0 58648.4 9399.7 9514.2 9524.9 9613.4 9653.3 11925.2 11960.5 11986.2 11996.4 12013.1 6785.5 6924.1 6958.3 6960.7 7153.6 6597.6 6720.7 6729.3 6738.5 6774.6 3865.4 3896.8 3935.8 3941.0 4114.3 1602.4 1638.9 1666.4 1694.2 1714.8 775.5 784.8 879.3 892.3 1008.0 9827.9 9851.0 10049.0 10128.8 15433.6 351.3 362.5 362.9 364.7 368.6 5342.9 5368.0 5385.9 5405.2 5465.8 321711.0 324879.0 328267.0 328369.0 489224.0 78372.0 78415.2 78591.0 80040.6 80126.6 403.9 406.7 408.0 408.6 409.6 9523.3 9575.4 9575.7 9749.6 9771.9 34111.8 35232.5 35960.3 36404.0 36606.8 42781.6 43694.8 45851.0 46462.3 47797.8 8247.8 8723.3 8740.6 8773.9 8778.8 15414.2 15584.7 15693.8 16142.9 16221.7 13145.5 13829.2 13910.3 13956.8 13966.1 13032.2 13096.5 13630.2 14030.7 14125.0 13845.2 14072.3 14180.3 14324.6 14423.4 8695.8 8697.3 8917.3 8920.2 8940.2 9401.4 12626.6 13369.3 13539.0 14618.8 04:24:12 INFO - 2015-12-09 04:24:12,866 DEBUG : BROWSER_OUTPUT: 04:24:12 INFO - 2015-12-09 04:24:12,866 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4714.39 Median:5945.70 stddev:2412.12 (40.6%) stddev-sans-first:2405.95 04:24:12 INFO - 2015-12-09 04:24:12,867 DEBUG : BROWSER_OUTPUT: Values: 1391.0 1406.3 1463.3 1646.4 1716.0 5716.9 5855.9 6291.9 6822.4 6919.3 6540.5 6609.6 6649.7 6697.8 6719.0 1989.4 2035.6 2036.0 2037.7 2038.8 2066.9 2080.9 2083.1 2112.8 2119.0 2042.0 2056.4 2080.8 2081.6 2090.1 2030.0 2058.3 2060.2 2061.0 2061.6 1964.5 1977.2 1989.1 1990.9 1994.4 6810.3 6822.8 6839.3 6903.2 6924.9 4068.6 4167.7 4178.9 4196.0 4208.2 2665.7 2745.7 2746.1 2752.0 2761.2 821.4 827.3 847.8 856.4 872.4 9037.0 9061.9 9073.0 9085.7 9143.4 3754.8 3761.9 3832.1 3840.3 3859.5 7882.5 7891.3 7894.4 7903.0 7909.8 4084.1 4100.5 4134.5 4134.9 4177.6 3620.5 3674.4 3699.7 3737.5 3796.2 3020.7 3044.3 3061.3 3094.7 3104.7 907.3 907.8 909.4 911.1 921.5 1176.6 1180.8 1191.6 1199.8 1208.0 6323.3 6393.9 6402.1 6449.7 6502.2 392.1 398.1 398.5 398.9 402.4 5196.0 5212.8 5214.1 5287.4 5294.3 7658.6 7878.8 7949.9 8122.6 8181.4 7692.2 7772.5 7780.0 7818.1 7877.8 6448.1 6472.7 6516.5 6524.1 6541.7 6272.9 6275.8 6292.2 6335.9 6350.1 7035.5 7128.8 7164.8 7295.6 7311.0 7127.7 7208.2 7243.5 7267.6 7428.6 5227.4 5362.2 5951.6 6026.5 6043.6 6317.8 6322.0 6327.7 6332.3 6332.5 5811.8 5939.8 6006.2 6051.0 6092.5 6077.4 6127.6 6131.2 6157.5 6263.6 6027.1 6045.2 6056.8 6086.0 6089.5 5669.0 5694.5 5700.5 5713.7 5728.5 5545.7 5592.3 5655.2 5655.2 5658.8 04:24:12 INFO - 2015-12-09 04:24:12,867 DEBUG : BROWSER_OUTPUT: 04:24:12 INFO - 2015-12-09 04:24:12,867 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4777.48 Median:5503.03 stddev:1472.30 (26.8%) stddev-sans-first:1436.66 04:24:12 INFO - 2015-12-09 04:24:12,867 DEBUG : BROWSER_OUTPUT: Values: 248.9 252.3 252.4 254.2 255.1 5412.9 5498.1 5524.5 5638.3 5643.3 5332.9 5475.8 5491.9 5507.9 5508.2 4126.7 4143.7 4153.0 4155.6 4161.2 4161.9 4164.1 4166.0 4166.2 4171.0 4048.3 4118.0 4130.2 4134.0 4154.5 4158.7 4161.6 4161.7 4164.4 4175.2 3562.7 3565.4 3567.2 3574.4 3576.6 5366.1 5374.0 5376.4 5396.9 5400.6 4443.5 4749.5 4752.4 4753.6 4759.8 5468.8 5600.7 5753.3 5767.3 5772.1 3832.9 3835.7 3838.6 3844.0 3845.1 6107.9 6137.7 6140.4 6140.9 6149.3 3868.1 3923.3 3924.5 3950.5 3953.1 4006.8 4007.5 4009.2 4013.4 4022.1 4695.7 4698.5 4698.7 4708.9 4713.6 5792.5 5821.2 5823.4 5828.9 5851.5 1406.8 1430.5 1434.7 1436.2 1437.6 876.5 882.8 898.0 924.2 953.1 5002.9 5047.3 5060.8 5080.9 5084.6 6015.5 6028.7 6029.2 6030.7 6046.0 5071.2 5075.3 5080.8 5080.9 5085.6 3157.3 3228.2 3257.4 3276.7 3285.7 5747.1 5747.8 5752.0 5756.7 5757.9 6305.8 6306.8 6308.7 6312.1 6330.3 5647.7 5691.9 5704.8 5743.9 5743.9 6078.8 6128.2 6128.7 6141.7 6142.4 5965.1 5969.4 5974.5 5978.3 5979.3 5694.5 5727.9 5728.9 5732.8 5734.9 3745.3 3869.3 3870.8 3880.0 3887.4 6058.5 6085.9 6099.2 6110.0 6112.4 5830.8 5852.5 5864.9 5865.6 5868.5 5669.4 5842.9 5851.4 5855.2 5858.9 5764.6 5766.3 5766.5 5766.7 5781.6 5903.4 5904.7 5922.3 5922.4 5936.2 5619.9 5771.7 5772.7 5777.2 5784.7 04:24:12 INFO - 2015-12-09 04:24:12,867 DEBUG : BROWSER_OUTPUT: 04:24:12 INFO - 2015-12-09 04:24:12,867 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:11943.81 Median:500.41 stddev:13666.74 (2731.1%) stddev-sans-first:13678.73 04:24:12 INFO - 2015-12-09 04:24:12,868 DEBUG : BROWSER_OUTPUT: Values: 642.3 654.5 683.0 716.1 731.3 11349.8 11499.1 11502.2 11503.5 11515.3 11815.2 11903.7 11960.6 11984.7 12015.6 621.7 628.4 785.5 793.5 800.7 788.0 791.1 793.2 795.3 797.6 826.9 829.0 833.6 834.8 835.1 818.5 829.6 829.9 831.0 831.1 615.8 616.2 616.5 617.9 618.0 11318.5 11348.5 11395.8 11405.7 11422.3 10530.2 11407.9 11535.9 11572.0 11649.5 9367.7 9427.9 9447.8 9451.5 9494.8 3306.5 3336.3 3369.8 3390.6 3466.7 39495.8 39775.4 39817.4 40201.0 40234.0 9184.6 9403.5 9485.5 9537.2 9628.6 15993.8 16008.4 16027.2 16040.0 16089.8 6155.1 6217.8 6264.8 6304.1 6307.7 3592.6 3638.4 3648.4 3657.9 3734.3 2547.7 2579.9 2602.7 2602.9 2629.6 486.6 489.5 497.9 500.3 500.5 480.6 501.7 562.3 583.1 614.8 7250.7 7584.5 7643.7 7952.1 8371.8 471.4 479.2 485.3 510.3 602.3 3320.5 3489.0 3561.0 3681.4 3697.4 47068.5 47906.0 48570.0 48617.0 48904.3 44531.8 44614.6 44677.1 44721.3 45417.3 41357.2 41532.6 41719.6 42101.8 42137.6 7117.5 7304.0 7325.8 7419.7 7557.2 30914.8 30930.2 31110.5 31179.8 31592.2 35302.3 36126.8 36511.3 39462.8 39721.0 9291.9 9294.5 9352.0 9411.0 9585.7 10196.5 10217.6 10282.2 10357.8 10506.2 9311.9 9410.2 9470.1 9575.1 10252.1 9539.5 9860.8 9981.6 10023.1 10188.2 9357.1 9442.9 9495.2 9530.3 9657.2 8231.7 8291.3 8401.7 8465.5 8873.5 7772.2 7836.6 7870.4 8021.7 8251.1 04:24:12 INFO - 2015-12-09 04:24:12,869 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:24:12 INFO - 2015-12-09 04:24:12,869 DEBUG : BROWSER_OUTPUT: 04:24:13 INFO - 2015-12-09 04:24:13,582 INFO : Browser exited with error code: 0 04:24:13 INFO - 2015-12-09 04:24:13,601 INFO : Completed test dromaeo_css (00:18:33) 04:24:13 INFO - 2015-12-09 04:24:13,601 INFO : Starting test kraken 04:24:13 INFO - 2015-12-09 04:24:13,601 DEBUG : operating with platform_type : linux_ 04:24:13 INFO - 2015-12-09 04:24:13,601 INFO : Initialising browser for kraken test... 04:24:13 INFO - 2015-12-09 04:24:13,607 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7sd8vo/profile http://localhost:41286/getInfo.html 04:24:18 INFO - 2015-12-09 04:24:18,442 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:24:18 INFO - 2015-12-09 04:24:18,442 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:24:18 INFO - 2015-12-09 04:24:18,443 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:24:18 INFO - 2015-12-09 04:24:18,443 DEBUG : BROWSER_OUTPUT: __metrics 04:24:18 INFO - 2015-12-09 04:24:18,587 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] 04:24:19 INFO - 2015-12-09 04:24:19,582 INFO : Browser initialized. 04:24:19 INFO - 2015-12-09 04:24:19,583 INFO : Running cycle 1/1 for kraken test... 04:24:19 INFO - 2015-12-09 04:24:19,583 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7sd8vo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:24:31 INFO - 2015-12-09 04:24:31,600 DEBUG : BROWSER_OUTPUT: RSS: Main: 142970880 04:24:31 INFO - 2015-12-09 04:24:31,600 DEBUG : BROWSER_OUTPUT: 04:24:34 INFO - 2015-12-09 04:24:34,198 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41286/tests/kraken/driver.html?testName=audio-beat-detection) 04:24:34 INFO - 2015-12-09 04:24:34,819 DEBUG : BROWSER_OUTPUT: RSS: Main: 142548992 04:24:34 INFO - 2015-12-09 04:24:34,820 DEBUG : BROWSER_OUTPUT: 04:24:37 INFO - 2015-12-09 04:24:37,562 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41286/tests/kraken/driver.html?testName=audio-dft) 04:24:38 INFO - 2015-12-09 04:24:38,168 DEBUG : BROWSER_OUTPUT: RSS: Main: 142659584 04:24:38 INFO - 2015-12-09 04:24:38,169 DEBUG : BROWSER_OUTPUT: 04:24:41 INFO - 2015-12-09 04:24:41,101 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41286/tests/kraken/driver.html?testName=audio-fft) 04:24:41 INFO - 2015-12-09 04:24:41,696 DEBUG : BROWSER_OUTPUT: RSS: Main: 138063872 04:24:41 INFO - 2015-12-09 04:24:41,696 DEBUG : BROWSER_OUTPUT: 04:24:43 INFO - 2015-12-09 04:24:43,791 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41286/tests/kraken/driver.html?testName=audio-oscillator) 04:24:44 INFO - 2015-12-09 04:24:44,377 DEBUG : BROWSER_OUTPUT: RSS: Main: 139223040 04:24:44 INFO - 2015-12-09 04:24:44,378 DEBUG : BROWSER_OUTPUT: 04:24:46 INFO - 2015-12-09 04:24:46,619 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41286/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:24:47 INFO - 2015-12-09 04:24:47,205 DEBUG : BROWSER_OUTPUT: RSS: Main: 140328960 04:24:47 INFO - 2015-12-09 04:24:47,205 DEBUG : BROWSER_OUTPUT: 04:24:50 INFO - 2015-12-09 04:24:50,309 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41286/tests/kraken/driver.html?testName=imaging-darkroom) 04:24:50 INFO - 2015-12-09 04:24:50,919 DEBUG : BROWSER_OUTPUT: RSS: Main: 139304960 04:24:50 INFO - 2015-12-09 04:24:50,919 DEBUG : BROWSER_OUTPUT: 04:24:54 INFO - 2015-12-09 04:24:54,433 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41286/tests/kraken/driver.html?testName=imaging-desaturate) 04:24:55 INFO - 2015-12-09 04:24:55,027 DEBUG : BROWSER_OUTPUT: RSS: Main: 137531392 04:24:55 INFO - 2015-12-09 04:24:55,027 DEBUG : BROWSER_OUTPUT: 04:24:57 INFO - 2015-12-09 04:24:57,895 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41286/tests/kraken/driver.html?testName=json-parse-financial) 04:24:58 INFO - 2015-12-09 04:24:58,488 DEBUG : BROWSER_OUTPUT: RSS: Main: 137502720 04:24:58 INFO - 2015-12-09 04:24:58,488 DEBUG : BROWSER_OUTPUT: 04:25:00 INFO - 2015-12-09 04:25:00,262 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41286/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:25:00 INFO - 2015-12-09 04:25:00,851 DEBUG : BROWSER_OUTPUT: RSS: Main: 140525568 04:25:00 INFO - 2015-12-09 04:25:00,852 DEBUG : BROWSER_OUTPUT: 04:25:02 INFO - 2015-12-09 04:25:02,755 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41286/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:25:03 INFO - 2015-12-09 04:25:03,363 DEBUG : BROWSER_OUTPUT: RSS: Main: 140767232 04:25:03 INFO - 2015-12-09 04:25:03,364 DEBUG : BROWSER_OUTPUT: 04:25:05 INFO - 2015-12-09 04:25:05,528 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41286/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:25:06 INFO - 2015-12-09 04:25:06,121 DEBUG : BROWSER_OUTPUT: RSS: Main: 141025280 04:25:06 INFO - 2015-12-09 04:25:06,121 DEBUG : BROWSER_OUTPUT: 04:25:08 INFO - 2015-12-09 04:25:08,722 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41286/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:25:09 INFO - 2015-12-09 04:25:09,325 DEBUG : BROWSER_OUTPUT: RSS: Main: 141451264 04:25:09 INFO - 2015-12-09 04:25:09,325 DEBUG : BROWSER_OUTPUT: 04:25:11 INFO - 2015-12-09 04:25:11,970 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41286/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:25:12 INFO - 2015-12-09 04:25:12,564 DEBUG : BROWSER_OUTPUT: RSS: Main: 140763136 04:25:12 INFO - 2015-12-09 04:25:12,564 DEBUG : BROWSER_OUTPUT: 04:25:14 INFO - 2015-12-09 04:25:14,333 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41286/tests/kraken/driver.html?testName=ai-astar) 04:25:14 INFO - 2015-12-09 04:25:14,579 DEBUG : BROWSER_OUTPUT: RSS: Main: 140390400 04:25:14 INFO - 2015-12-09 04:25:14,580 DEBUG : BROWSER_OUTPUT: 04:25:14 INFO - 2015-12-09 04:25:14,583 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:25:14 INFO - 2015-12-09 04:25:14,583 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:25:14 INFO - 2015-12-09 04:25:14,583 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:25:14 INFO - 2015-12-09 04:25:14,583 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:25:14 INFO - 2015-12-09 04:25:14,583 DEBUG : BROWSER_OUTPUT: |0;ai-astar;115;112;114;114;114;126;114;114;115;117 04:25:14 INFO - 2015-12-09 04:25:14,584 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;154;137;155;168;134;153;152;132;177;139 04:25:14 INFO - 2015-12-09 04:25:14,584 DEBUG : BROWSER_OUTPUT: |2;audio-dft;167;163;164;163;162;168;172;164;166;164 04:25:14 INFO - 2015-12-09 04:25:14,584 DEBUG : BROWSER_OUTPUT: |3;audio-fft;75;73;74;72;150;72;79;75;73;152 04:25:14 INFO - 2015-12-09 04:25:14,584 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;96;132;93;95;95;136;94;95;127 04:25:14 INFO - 2015-12-09 04:25:14,584 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;111;110;110;110;110;111;110;111;110 04:25:14 INFO - 2015-12-09 04:25:14,584 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;148;147;148;150;148;148;148;149;147 04:25:14 INFO - 2015-12-09 04:25:14,584 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;97;96;96;96;96;96;96;96;96 04:25:14 INFO - 2015-12-09 04:25:14,584 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;76;76;74;75;76;73;74;85;74;72 04:25:14 INFO - 2015-12-09 04:25:14,585 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;70;69;69;69;68;68;68;69;69 04:25:14 INFO - 2015-12-09 04:25:14,585 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;85;85;87;84;87;93;87;88;85 04:25:14 INFO - 2015-12-09 04:25:14,585 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;120;127;116;133;128;127;132;123;124;140 04:25:14 INFO - 2015-12-09 04:25:14,585 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;151;155;148;150;152;138;153;151;152;139 04:25:14 INFO - 2015-12-09 04:25:14,585 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;72;70;74;72;71;73;72;73;70;73 04:25:14 INFO - 2015-12-09 04:25:14,585 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:25:14 INFO - 2015-12-09 04:25:14,585 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:25:14 INFO - 2015-12-09 04:25:14,585 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,784 04:25:14 INFO - 2015-12-09 04:25:14,585 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:25:14 INFO - 2015-12-09 04:25:14,586 DEBUG : BROWSER_OUTPUT: __startTimestamp1449663914581__endTimestamp 04:25:14 INFO - 2015-12-09 04:25:14,586 DEBUG : BROWSER_OUTPUT: 04:25:14 INFO - 2015-12-09 04:25:14,593 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:25:14 INFO - 2015-12-09 04:25:14,593 DEBUG : BROWSER_OUTPUT: Number of tests: 14 04:25:14 INFO - 2015-12-09 04:25:14,593 DEBUG : BROWSER_OUTPUT: 04:25:14 INFO - 2015-12-09 04:25:14,594 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:115.50 Median:114.50 stddev:3.89 (3.4%) stddev-sans-first:4.13 04:25:14 INFO - 2015-12-09 04:25:14,594 DEBUG : BROWSER_OUTPUT: Values: 115.0 112.0 114.0 114.0 114.0 126.0 114.0 114.0 115.0 117.0 04:25:14 INFO - 2015-12-09 04:25:14,594 DEBUG : BROWSER_OUTPUT: 04:25:14 INFO - 2015-12-09 04:25:14,594 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:150.10 Median:153.50 stddev:14.82 (9.7%) stddev-sans-first:15.65 04:25:14 INFO - 2015-12-09 04:25:14,594 DEBUG : BROWSER_OUTPUT: Values: 154.0 137.0 155.0 168.0 134.0 153.0 152.0 132.0 177.0 139.0 04:25:14 INFO - 2015-12-09 04:25:14,594 DEBUG : BROWSER_OUTPUT: 04:25:14 INFO - 2015-12-09 04:25:14,594 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:165.30 Median:165.00 stddev:3.02 (1.8%) stddev-sans-first:3.14 04:25:14 INFO - 2015-12-09 04:25:14,594 DEBUG : BROWSER_OUTPUT: Values: 167.0 163.0 164.0 163.0 162.0 168.0 172.0 164.0 166.0 164.0 04:25:14 INFO - 2015-12-09 04:25:14,594 DEBUG : BROWSER_OUTPUT: 04:25:14 INFO - 2015-12-09 04:25:14,594 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:89.50 Median:73.50 stddev:32.48 (44.2%) stddev-sans-first:34.02 04:25:14 INFO - 2015-12-09 04:25:14,594 DEBUG : BROWSER_OUTPUT: Values: 75.0 73.0 74.0 72.0 150.0 72.0 79.0 75.0 73.0 152.0 04:25:14 INFO - 2015-12-09 04:25:14,594 DEBUG : BROWSER_OUTPUT: 04:25:14 INFO - 2015-12-09 04:25:14,594 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.80 Median:95.00 stddev:17.99 (18.9%) stddev-sans-first:18.65 04:25:14 INFO - 2015-12-09 04:25:14,594 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 132.0 93.0 95.0 95.0 136.0 94.0 95.0 127.0 04:25:14 INFO - 2015-12-09 04:25:14,595 DEBUG : BROWSER_OUTPUT: 04:25:14 INFO - 2015-12-09 04:25:14,595 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.30 Median:110.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 04:25:14 INFO - 2015-12-09 04:25:14,595 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 110.0 110.0 110.0 110.0 111.0 110.0 111.0 110.0 04:25:14 INFO - 2015-12-09 04:25:14,595 DEBUG : BROWSER_OUTPUT: 04:25:14 INFO - 2015-12-09 04:25:14,595 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 04:25:14 INFO - 2015-12-09 04:25:14,595 DEBUG : BROWSER_OUTPUT: Values: 148.0 148.0 147.0 148.0 150.0 148.0 148.0 148.0 149.0 147.0 04:25:14 INFO - 2015-12-09 04:25:14,595 DEBUG : BROWSER_OUTPUT: 04:25:14 INFO - 2015-12-09 04:25:14,595 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:25:14 INFO - 2015-12-09 04:25:14,595 DEBUG : BROWSER_OUTPUT: Values: 96.0 97.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 04:25:14 INFO - 2015-12-09 04:25:14,595 DEBUG : BROWSER_OUTPUT: 04:25:14 INFO - 2015-12-09 04:25:14,595 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:75.50 Median:75.50 stddev:3.60 (4.8%) stddev-sans-first:3.81 04:25:14 INFO - 2015-12-09 04:25:14,595 DEBUG : BROWSER_OUTPUT: Values: 76.0 76.0 74.0 75.0 76.0 73.0 74.0 85.0 74.0 72.0 04:25:14 INFO - 2015-12-09 04:25:14,595 DEBUG : BROWSER_OUTPUT: 04:25:14 INFO - 2015-12-09 04:25:14,595 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 04:25:14 INFO - 2015-12-09 04:25:14,595 DEBUG : BROWSER_OUTPUT: Values: 68.0 70.0 69.0 69.0 69.0 68.0 68.0 68.0 69.0 69.0 04:25:14 INFO - 2015-12-09 04:25:14,595 DEBUG : BROWSER_OUTPUT: 04:25:14 INFO - 2015-12-09 04:25:14,596 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:87.00 stddev:2.59 (3.0%) stddev-sans-first:2.68 04:25:14 INFO - 2015-12-09 04:25:14,596 DEBUG : BROWSER_OUTPUT: Values: 85.0 85.0 85.0 87.0 84.0 87.0 93.0 87.0 88.0 85.0 04:25:14 INFO - 2015-12-09 04:25:14,596 DEBUG : BROWSER_OUTPUT: 04:25:14 INFO - 2015-12-09 04:25:14,596 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:127.00 Median:127.50 stddev:6.88 (5.4%) stddev-sans-first:6.82 04:25:14 INFO - 2015-12-09 04:25:14,596 DEBUG : BROWSER_OUTPUT: Values: 120.0 127.0 116.0 133.0 128.0 127.0 132.0 123.0 124.0 140.0 04:25:14 INFO - 2015-12-09 04:25:14,596 DEBUG : BROWSER_OUTPUT: 04:25:14 INFO - 2015-12-09 04:25:14,596 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.90 Median:151.50 stddev:5.78 (3.8%) stddev-sans-first:6.08 04:25:14 INFO - 2015-12-09 04:25:14,596 DEBUG : BROWSER_OUTPUT: Values: 151.0 155.0 148.0 150.0 152.0 138.0 153.0 151.0 152.0 139.0 04:25:14 INFO - 2015-12-09 04:25:14,596 DEBUG : BROWSER_OUTPUT: 04:25:14 INFO - 2015-12-09 04:25:14,596 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.00 Median:72.50 stddev:1.33 (1.8%) stddev-sans-first:1.41 04:25:14 INFO - 2015-12-09 04:25:14,596 DEBUG : BROWSER_OUTPUT: Values: 72.0 70.0 74.0 72.0 71.0 73.0 72.0 73.0 70.0 73.0 04:25:14 INFO - 2015-12-09 04:25:14,596 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:25:14 INFO - 2015-12-09 04:25:14,596 DEBUG : BROWSER_OUTPUT: 04:25:15 INFO - 2015-12-09 04:25:15,566 INFO : Browser exited with error code: 0 04:25:15 INFO - 2015-12-09 04:25:15,623 INFO : Completed test kraken (00:01:02) 04:25:15 INFO - 2015-12-09 04:25:15,623 INFO : Starting test v8_7 04:25:15 INFO - 2015-12-09 04:25:15,624 DEBUG : operating with platform_type : linux_ 04:25:15 INFO - 2015-12-09 04:25:15,624 INFO : Initialising browser for v8_7 test... 04:25:15 INFO - 2015-12-09 04:25:15,630 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpak0GRO/profile http://localhost:41286/getInfo.html 04:25:20 INFO - 2015-12-09 04:25:20,343 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:25:20 INFO - 2015-12-09 04:25:20,343 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:25:20 INFO - 2015-12-09 04:25:20,343 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:25:20 INFO - 2015-12-09 04:25:20,343 DEBUG : BROWSER_OUTPUT: __metrics 04:25:20 INFO - 2015-12-09 04:25:20,480 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] 04:25:21 INFO - 2015-12-09 04:25:21,410 INFO : Browser initialized. 04:25:21 INFO - 2015-12-09 04:25:21,411 INFO : Running cycle 1/1 for v8_7 test... 04:25:21 INFO - 2015-12-09 04:25:21,411 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpak0GRO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:25:33 INFO - 2015-12-09 04:25:33,407 DEBUG : BROWSER_OUTPUT: RSS: Main: 143257600 04:25:33 INFO - 2015-12-09 04:25:33,408 DEBUG : BROWSER_OUTPUT: 04:25:54 INFO - 2015-12-09 04:25:54,802 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41286/tests/v8_7/run.html (next: http://localhost:41286/tests/v8_7/run.html) 04:25:55 INFO - 2015-12-09 04:25:55,052 DEBUG : BROWSER_OUTPUT: RSS: Main: 138690560 04:25:55 INFO - 2015-12-09 04:25:55,052 DEBUG : BROWSER_OUTPUT: 04:25:55 INFO - 2015-12-09 04:25:55,054 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:25:55 INFO - 2015-12-09 04:25:55,054 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:25:55 INFO - 2015-12-09 04:25:55,054 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:25:55 INFO - 2015-12-09 04:25:55,054 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:25:55 INFO - 2015-12-09 04:25:55,054 DEBUG : BROWSER_OUTPUT: |0;Richards;166.00265604249668 04:25:55 INFO - 2015-12-09 04:25:55,054 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;190.83969465648855 04:25:55 INFO - 2015-12-09 04:25:55,054 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.55153203342616 04:25:55 INFO - 2015-12-09 04:25:55,055 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5202.072538860104 04:25:55 INFO - 2015-12-09 04:25:55,055 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1064.9627263045793 04:25:55 INFO - 2015-12-09 04:25:55,055 DEBUG : BROWSER_OUTPUT: |5;Earley;700.2801120448179 04:25:55 INFO - 2015-12-09 04:25:55,055 DEBUG : BROWSER_OUTPUT: |6;Boyer;12207.317073170732 04:25:55 INFO - 2015-12-09 04:25:55,055 DEBUG : BROWSER_OUTPUT: |7;RegExp;26368.42105263158 04:25:55 INFO - 2015-12-09 04:25:55,055 DEBUG : BROWSER_OUTPUT: |8;Splay;697.8367062107467 04:25:55 INFO - 2015-12-09 04:25:55,055 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 04:25:55 INFO - 2015-12-09 04:25:55,056 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:25:55 INFO - 2015-12-09 04:25:55,056 DEBUG : BROWSER_OUTPUT: __startTimestamp1449663955053__endTimestamp 04:25:55 INFO - 2015-12-09 04:25:55,056 DEBUG : BROWSER_OUTPUT: 04:25:55 INFO - 2015-12-09 04:25:55,056 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:25:55 INFO - 2015-12-09 04:25:55,057 DEBUG : BROWSER_OUTPUT: Number of tests: 10 04:25:55 INFO - 2015-12-09 04:25:55,058 DEBUG : BROWSER_OUTPUT: 04:25:55 INFO - 2015-12-09 04:25:55,058 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.00 Median:166.00 stddev:0.00 (0.0%) 04:25:55 INFO - 2015-12-09 04:25:55,058 DEBUG : BROWSER_OUTPUT: Values: 166.0 04:25:55 INFO - 2015-12-09 04:25:55,058 DEBUG : BROWSER_OUTPUT: 04:25:55 INFO - 2015-12-09 04:25:55,058 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:190.84 Median:190.84 stddev:0.00 (0.0%) 04:25:55 INFO - 2015-12-09 04:25:55,058 DEBUG : BROWSER_OUTPUT: Values: 190.8 04:25:55 INFO - 2015-12-09 04:25:55,058 DEBUG : BROWSER_OUTPUT: 04:25:55 INFO - 2015-12-09 04:25:55,059 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.55 Median:278.55 stddev:0.00 (0.0%) 04:25:55 INFO - 2015-12-09 04:25:55,059 DEBUG : BROWSER_OUTPUT: Values: 278.6 04:25:55 INFO - 2015-12-09 04:25:55,059 DEBUG : BROWSER_OUTPUT: 04:25:55 INFO - 2015-12-09 04:25:55,059 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5202.07 Median:5202.07 stddev:0.00 (0.0%) 04:25:55 INFO - 2015-12-09 04:25:55,059 DEBUG : BROWSER_OUTPUT: Values: 5202.1 04:25:55 INFO - 2015-12-09 04:25:55,059 DEBUG : BROWSER_OUTPUT: 04:25:55 INFO - 2015-12-09 04:25:55,059 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1064.96 Median:1064.96 stddev:0.00 (0.0%) 04:25:55 INFO - 2015-12-09 04:25:55,059 DEBUG : BROWSER_OUTPUT: Values: 1065.0 04:25:55 INFO - 2015-12-09 04:25:55,060 DEBUG : BROWSER_OUTPUT: 04:25:55 INFO - 2015-12-09 04:25:55,060 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:700.28 Median:700.28 stddev:0.00 (0.0%) 04:25:55 INFO - 2015-12-09 04:25:55,060 DEBUG : BROWSER_OUTPUT: Values: 700.3 04:25:55 INFO - 2015-12-09 04:25:55,060 DEBUG : BROWSER_OUTPUT: 04:25:55 INFO - 2015-12-09 04:25:55,060 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12207.32 Median:12207.32 stddev:0.00 (0.0%) 04:25:55 INFO - 2015-12-09 04:25:55,060 DEBUG : BROWSER_OUTPUT: Values: 12207.3 04:25:55 INFO - 2015-12-09 04:25:55,060 DEBUG : BROWSER_OUTPUT: 04:25:55 INFO - 2015-12-09 04:25:55,061 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26368.42 Median:26368.42 stddev:0.00 (0.0%) 04:25:55 INFO - 2015-12-09 04:25:55,061 DEBUG : BROWSER_OUTPUT: Values: 26368.4 04:25:55 INFO - 2015-12-09 04:25:55,061 DEBUG : BROWSER_OUTPUT: 04:25:55 INFO - 2015-12-09 04:25:55,061 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:697.84 Median:697.84 stddev:0.00 (0.0%) 04:25:55 INFO - 2015-12-09 04:25:55,061 DEBUG : BROWSER_OUTPUT: Values: 697.8 04:25:55 INFO - 2015-12-09 04:25:55,061 DEBUG : BROWSER_OUTPUT: 04:25:55 INFO - 2015-12-09 04:25:55,061 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 04:25:55 INFO - 2015-12-09 04:25:55,061 DEBUG : BROWSER_OUTPUT: Values: 4840.6 04:25:55 INFO - 2015-12-09 04:25:55,062 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:25:55 INFO - 2015-12-09 04:25:55,062 DEBUG : BROWSER_OUTPUT: 04:25:55 INFO - 2015-12-09 04:25:55,982 INFO : Browser exited with error code: 0 04:25:55 INFO - 2015-12-09 04:25:55,994 INFO : Completed test v8_7 (00:00:40) 04:25:56 INFO - 2015-12-09 04:25:56,079 INFO : Completed test suite (00:20:16) 04:25:56 INFO - 2015-12-09 04:25:56,080 DEBUG : Working with test: dromaeo_css 04:25:56 INFO - 2015-12-09 04:25:56,080 DEBUG : Generating results file: dromaeo_css 04:25:56 INFO - 2015-12-09 04:25:56,081 DEBUG : Working with test: kraken 04:25:56 INFO - 2015-12-09 04:25:56,081 DEBUG : Generating results file: kraken 04:25:56 INFO - 2015-12-09 04:25:56,081 INFO : javascript benchmark 04:25:56 INFO - 2015-12-09 04:25:56,081 DEBUG : Working with test: v8_7 04:25:56 INFO - 2015-12-09 04:25:56,081 DEBUG : Generating results file: v8_7 04:25:56 INFO - 2015-12-09 04:25:56,082 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:25:56 INFO - 2015-12-09 04:25:56,135 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,94,43]] 04:25:56 INFO - 2015-12-09 04:25:56,135 DEBUG : process_Request line: dromaeo_css 6130.78 graph.html#tests=[[72,94,43]] 04:25:56 INFO - 2015-12-09 04:25:56,135 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:25:56 INFO - 2015-12-09 04:25:56,166 DEBUG : process_Request line: kraken 1559.40 graph.html#tests=[[232,94,43]] 04:25:56 INFO - 2015-12-09 04:25:56,167 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:25:56 INFO - 2015-12-09 04:25:56,196 DEBUG : process_Request line: v8_7 21011.06 graph.html#tests=[[230,94,43]] 04:25:56 INFO - 2015-12-09 04:25:56,197 INFO : javascript benchmark 04:25:56 INFO - 2015-12-09 04:25:56,201 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2659.1756555467805, 2693.622892795008, 2728.7953403494744, 2738.657667791526, 2746.1898739871735, 4500.167469018225, 4723.48041715411, 4774.761261936902, 4778.4504781950045, 4790.425148982118, 4664.440267167936, 4737.715737055779, 4772.546608072227, 4796.2086255767845, 4797.208460604005, 4298.763568003758, 4378.62781663559, 4443.466783985927, 4448.177087238855, 4482.551744825524, 4323.978380108111, 4350.390945267665, 4437.445319335083, 4465.754383508905, 4495.483019452756, 4235.470566179228, 4253.468316460442, 4360.738355698668, 4374.606285434326, 4380.7152535085115, 4402.493713222988, 4414.84548040817, 4430.224710675651, 4432.822687092513, 4445.599896009375, 4396.010897548058, 4408.515063343029, 4417.712848664826, 4434.84478043267, 4440.888977775549, 4448.5773851484055, 4573.245414506602, 4586.564276393738, 4711.646626502992, 4719.339292499053, 5079.492050794928, 5119.513646203604, 5138.30632864566, 5149.588032957355, 5189.0, 5174.327337446146, 5174.818881339173, 5189.2475591039465, 5193.195054766479, 5195.116830138885, 4576.244919588264, 4683.0, 4687.179743544866, 4753.31076993834, 4809.663323567387, 5187.844364669066, 5256.448072952285, 5284.392294886137, 5293.708846013429, 5343.385510666245, 4859.902801943941, 5056.342675452168, 5078.238264260391, 5094.108531007059, 5107.6169287302855, 4962.305277261186, 5010.5240002199735, 5033.597312215014, 5038.3198268234055, 5098.490150984871, 5055.646104772704, 5057.949420505822, 5066.442691303953, 5086.3896332440345, 5089.109405853961, 4860.878478038077, 4976.154053810861, 4982.153033984231, 4984.626153038536, 5012.774425150877, 4121.443605113272, 4135.31767258399, 4135.7518548887165, 4177.874663760092, 4179.644730197967, 3620.6741393274733, 3666.5783434904793, 3685.3919103348185, 3686.078480379905, 3695.9445608315896, 4246.278132717445, 4349.826006959749, 4355.499117601453, 4394.516603173648, 4405.977970110193, 5124.8206312778875, 5150.253213284052, 5154.020736060136, 5169.431362550116, 5170.3278573785165, 4439.289713645801, 4449.087936972913, 4450.132224216311, 4470.597646211857, 4476.104779044204, 4258.254805409041, 4303.548127446573, 4402.427684401007, 4444.31113177458, 4521.231390663529, 4815.494373090775, 4834.540718631724, 4854.708717476963, 4867.075255701406, 4901.2893130496595, 5150.871228219175, 5171.095058364846, 5171.612129090408, 5176.663516871391, 5233.110371236975, 4707.62339012885, 4795.592374648194, 4860.975695121501, 4892.241702536113, 4932.8520144395725, 5041.269015992734, 5048.3941926969, 5053.898922021613, 5076.746162691925, 5100.48995100487, 4909.3372394727685, 5149.588032957429, 5151.330327057459, 5182.093133701663, 5184.066867963803, 4844.95155048445, 4850.078485087822, 4890.975545122252, 4918.2868484068895, 4955.851324460272, 4895.290182923527, 4906.55840974314, 4907.092187945099, 4909.0101643156, 4930.482299358516, 4637.76811159434, 4816.060868130681, 4830.903381932412, 4846.5638092571835, 4907.33750943618, 4777.163996300564, 4855.514448555116, 4860.854174374775, 4886.169351210374, 4940.6788558743565, 4917.729524876167, 4935.0, 4944.653874228769, 4946.802127914843, 4946.950530494649], "unit": "ms", "name": "dojo.html", "value": 4659.1864708747735}, {"lowerIsBetter": false, "replicates": [493.84690745868807, 525.9368875734912, 531.2535887078654, 533.8932213557287, 542.0568211312318, 25589.232323030315, 26172.73827261729, 33820.830895845516, 34187.0, 34193.0, 40681.59318406815, 41282.7935860321, 41416.378754318706, 41472.37791433131, 41492.0, 7851.332636725885, 8029.839403211932, 8058.315043221333, 8069.273765361117, 8096.716614918479, 7875.4487185897015, 8003.079645840716, 8003.679852805881, 8011.519308841459, 8039.155888631668, 7956.880646790303, 8010.919890801105, 8028.518288902685, 8064.475809072392, 8071.31393831525, 7676.385889128856, 7713.0, 7766.18455062216, 7772.805679857992, 7776.533407995482, 7846.489978151402, 7961.601919903982, 7977.401694872908, 7977.441579089466, 7985.0, 37563.62436375656, 38005.42991855124, 38079.23841523182, 38082.0, 38112.618873811465, 3022.2746540830044, 3063.127008802503, 3075.0928476099602, 3104.767142464324, 3563.9109022274392, 28726.281842954093, 28727.569086463718, 28923.0, 28945.131646050653, 29421.55867661987, 3393.6606339366112, 3548.449990251515, 3570.4644303354703, 3601.963980360189, 3610.837512311952, 322947.0, 329919.0, 330198.0, 330573.0, 334992.0, 39131.41302880459, 39324.21351572953, 39574.80212598918, 56643.433565663814, 57511.0, 36937.0, 37125.814370927976, 37237.06907327284, 37284.25431491355, 37445.25109497849, 13037.608871733863, 13134.802977955338, 13147.0797044206, 13158.539451119357, 13186.604401867959, 10176.592936282614, 10183.083522483012, 10254.53854576546, 10255.743606409751, 10301.639442619473, 13567.728645427234, 13660.65848353799, 13668.658283542793, 13677.31613419325, 13765.587032389045, 5429.67421954684, 5529.198266251451, 5588.748506317144, 5591.161325801162, 5617.971910140505, 950.3775027357092, 968.6270785747538, 999.3953658036928, 1008.3344992305043, 1014.7057353367588, 25923.0, 26042.609360859602, 26107.608385874228, 26264.0, 26301.605475917877, 918.320442872283, 931.1526510875071, 976.9120779129912, 1029.0738335497963, 1050.0654417568371, 13263.469461221443, 13388.531401400904, 13479.326033698471, 14837.480688176078, 15477.148756818484, 316028.0, 319624.0, 320185.0, 322729.0, 322807.0, 48257.0, 48450.0, 48885.75557122192, 49207.50792492029, 49576.256356154685, 1213.5631172777794, 1236.3076677060874, 1240.4170040081146, 1262.6464589914838, 1265.1270623270098, 27855.0, 27988.60056997101, 28311.15066548007, 28454.288642784097, 28577.857110715144, 40738.0, 41180.79409602933, 41303.58696413117, 41304.0, 41388.0, 252423.0, 252432.0, 252522.0, 254195.0, 255283.0, 30048.0, 41897.37153942754, 43404.0, 43580.782096089315, 43617.78191109024, 42626.0, 43953.78023109864, 46547.6035718929, 46626.76686616681, 47318.0, 32922.01233962985, 33778.8311058453, 33901.15247118743, 33922.3215535683, 33924.49113263254, 33186.33627327488, 33684.326313474085, 33904.66095339015, 33916.660833391354, 34021.31957360789, 33414.665853341154, 33468.49797252994, 33477.33045339128, 33536.329273414885, 33727.83136084304, 28733.0, 29589.0, 30572.541411878396, 30601.23496912506, 30669.23326916845, 23539.41151471227, 24090.450353228265, 24208.488042480727, 24362.63456048196, 24502.63246051275], "unit": "ms", "name": "ext.html", "value": 16895.288909304018}, {"lowerIsBetter": false, "replicates": [273.35760961739913, 274.04085700049825, 274.0572430837917, 275.225240946735, 278.94560560690667, 12606.81089783654, 14642.0, 15084.0, 15089.773653395194, 16064.919675401607, 14802.703945921076, 14877.330520126594, 14913.32890019949, 15039.924800375982, 15148.772768408482, 4519.847438903075, 4524.072565124142, 4531.3203019547, 4537.137943790669, 4705.694129881546, 4799.592034677056, 4808.663393562452, 4840.56434920857, 4842.443119041302, 4845.975770121145, 4799.088173247089, 4799.160146974283, 4867.902641947159, 5019.54824065834, 5032.672876263049, 4961.330220420233, 4966.652334336562, 4980.377452818398, 4993.525615066563, 4994.625403094764, 5146.408163061258, 5154.768035438415, 5160.870978225536, 5165.690058596496, 5178.766955487012, 11120.332780033224, 11277.718057048558, 13389.0, 18670.81329186704, 18852.434426967215, 1786.1872847854195, 1845.6862333403353, 1847.5750577367146, 1847.7228415737613, 1961.5096225943514, 8756.781080472976, 8959.238464730506, 9065.093490650948, 10168.644097456627, 12841.614751557468, 1768.9911550442293, 1794.2105473591578, 1835.2292037344348, 1860.3209828412532, 1869.2896699254331, 56218.71890640521, 57340.0, 57802.421975780126, 58338.0, 58648.41351586515, 9399.671011514563, 9514.238860891111, 9524.90475095254, 9613.423194608346, 9653.276004299705, 11925.22486038422, 11960.521579136912, 11986.220895641756, 11996.400179990966, 12013.099017573717, 6785.457163426914, 6924.099867017357, 6958.269381714948, 6960.686769095403, 7153.60655163971, 6597.637129957802, 6720.697568609425, 6729.3270672933295, 6738.460923126138, 6774.559653622498, 3865.40086286626, 3896.8051597420017, 3935.8032098394965, 3940.9605903941165, 4114.321137012359, 1602.359056377435, 1638.9262483188286, 1666.4084250091285, 1694.1698567701983, 1714.79422469302, 775.46880386934, 784.7645706288091, 879.3273146043282, 892.2727976698969, 1008.0393634381059, 9827.901720982842, 9850.950745246228, 10049.045340692623, 10128.848067278997, 15433.614159645875, 351.3342216499719, 362.5069904929294, 362.896574476273, 364.70641133886943, 368.6129563957833, 5342.9465705342445, 5367.973160134175, 5385.892282154413, 5405.243265942771, 5465.781368745205, 321711.0, 324879.0, 328267.0, 328369.0, 489224.0, 78372.0, 78415.2158478408, 78591.0, 80040.59979700064, 80126.5993670028, 403.93537034074404, 406.69497876592555, 408.0431388394211, 408.6424378668665, 409.57199726286103, 9523.333366664267, 9575.425474471554, 9575.712728618153, 9749.610015599297, 9771.853422198816, 34111.82944085363, 35232.47151292784, 35960.28079438449, 36404.0, 36606.816965915, 42781.57218427776, 43694.78152609216, 45851.0, 46462.30306545472, 47797.76101119472, 8247.835043299221, 8723.258523025614, 8740.60667269987, 8773.868391973998, 8778.824423511622, 15414.152221627917, 15584.688306233586, 15693.843061569238, 16142.919285403497, 16221.67556648884, 13145.474181033034, 13829.170249785046, 13910.304484775923, 13956.79064814049, 13966.092204006707, 13032.218066916015, 13096.541621043218, 13630.182189068688, 14030.719385612434, 14125.011249212457, 13845.16928984264, 14072.296385180905, 14180.29098545048, 14324.641883952985, 14423.423063077778, 8695.782605434662, 8697.304215662605, 8917.28661707049, 8920.24177944882, 8940.15068568485, 9401.435913845191, 12626.558070467858, 13369.331533423096, 13539.0, 14618.780718289447], "unit": "ms", "name": "jquery.html", "value": 7435.622733588737}, {"lowerIsBetter": false, "replicates": [1391.0401822742313, 1406.3109076552487, 1463.253740592299, 1646.390835390905, 1715.9571010724726, 5716.942830571693, 5855.912161317573, 6291.937080629198, 6822.351876571724, 6919.342662447072, 6540.476761859052, 6609.603423794576, 6649.700763465644, 6697.799066028015, 6718.966405167967, 1989.4230673104819, 2035.6234096692085, 2035.9694604580943, 2037.684158955357, 2038.775734669185, 2066.886321252338, 2080.854340196187, 2083.0834432849497, 2112.8204102651216, 2119.0, 2042.0300357330298, 2056.362527616444, 2080.750309962799, 2081.604495145925, 2090.1012564597218, 2029.9898500507477, 2058.259026750369, 2060.1553363121125, 2061.0210150178696, 2061.5876824635134, 1964.5088727818045, 1977.2486455147096, 1989.1347263940231, 1990.8805471671747, 1994.3917105282883, 6810.28492008337, 6822.761203357859, 6839.316068393171, 6903.206131294915, 6924.861502769967, 4068.593140685937, 4167.666586673089, 4178.895527611804, 4196.0, 4208.158368326316, 2665.6801183857865, 2745.7254274572583, 2746.0663374452583, 2752.0, 2761.1716485054603, 821.4414198345123, 827.2678679368742, 847.7753395350237, 856.3662889461816, 872.4242000279785, 9037.0, 9061.909380906172, 9072.999999999935, 9085.682001129928, 9143.405678630868, 3754.81225938702, 3761.9247615047548, 3832.0611450194324, 3840.347140986039, 3859.536855577349, 7882.5270483771155, 7891.329237014918, 7894.447388682737, 7903.012123484565, 7909.802254943557, 4084.1219137885496, 4100.466939297872, 4134.545200027995, 4134.896627584334, 4177.624013838769, 3620.5474315710535, 3674.4488326751202, 3699.741018128759, 3737.5328083989502, 3796.1838204786104, 3020.7432368248947, 3044.2845931206148, 3061.280599059219, 3094.7214750672097, 3104.674009229044, 907.3149771922289, 907.8184363127269, 909.4497828813472, 911.0752586125088, 921.4609453469661, 1176.5764324843049, 1180.816973369129, 1191.5948577483698, 1199.7960346740902, 1208.015467394071, 6323.27282362525, 6393.936060639334, 6402.071699603624, 6449.677516124175, 6502.219733631994, 392.0864385980658, 398.0546202768425, 398.47600405466716, 398.89030516607585, 402.368281797575, 5196.038732834438, 5212.843614691566, 5214.0875346814155, 5287.4448182941005, 5294.258803767476, 7658.578778167254, 7878.842423151505, 7949.920500794918, 8122.55325957078, 8181.3863960203225, 7692.230776922375, 7772.533647981139, 7779.961100194463, 7818.061832580127, 7877.803054923673, 6448.097266382711, 6472.676366181672, 6516.478681705452, 6524.054012168209, 6541.738330466728, 6272.905906411316, 6275.780347687809, 6292.2134733158355, 6335.859545281709, 6350.110984462087, 7035.47233957445, 7128.786136415916, 7164.785056448315, 7295.635218239173, 7311.0, 7127.679254433459, 7208.171060328129, 7243.45674074457, 7267.563946163247, 7428.554286742813, 5227.398849132398, 5362.1688638259575, 5951.613145145551, 6026.548008899438, 6043.576949613484, 6317.842053948688, 6322.0, 6327.715252813542, 6332.335104813928, 6332.493400527855, 5811.767529298781, 5939.792107276225, 6006.249218847644, 6051.0, 6092.543059270483, 6077.392260773887, 6127.632342059491, 6131.233595800525, 6157.53818463626, 6263.561550691408, 6027.065804800263, 6045.214122164267, 6056.788012419544, 6086.02623580225, 6089.48239399656, 5669.036263835075, 5694.544436444991, 5700.515456186271, 5713.714314284352, 5728.484436400578, 5545.667259964415, 5592.287740445961, 5655.179998900218, 5655.236543066633, 5658.773649053992], "unit": "ms", "name": "mootools.html", "value": 3827.6771627794847}, {"lowerIsBetter": false, "replicates": [248.8942199565185, 252.3187394036103, 252.3715947291244, 254.19293742367978, 255.08551841647693, 5412.864678383043, 5498.120300751881, 5524.502794748471, 5638.323401191858, 5643.322801263844, 5332.946670533293, 5475.753591088401, 5491.862703432416, 5507.9449205507935, 5508.228847961279, 4126.669866410687, 4143.6892233082635, 4153.044799696072, 4155.584441555835, 4161.230172418097, 4161.916761664765, 4164.146349998258, 4166.020985068507, 4166.208420400129, 4170.9582904170875, 4048.2915489789316, 4117.97941010296, 4130.194612050651, 4133.979330103361, 4154.459920210384, 4158.7296825706235, 4161.604647558477, 4161.729487583298, 4164.375343698439, 4175.206710724983, 3562.7327950403565, 3565.358235517606, 3567.16171699653, 3574.3566158091535, 3576.5708115026364, 5366.114591092465, 5373.973130134364, 5376.4085950545405, 5396.892062158774, 5400.648957817729, 4443.488998765119, 4749.4775574686755, 4752.42970843501, 4753.643476739224, 4759.833405830814, 5468.788883248222, 5600.691961942091, 5753.338366087912, 5767.33675627304, 5772.076467765138, 3832.885013449601, 3835.67396771272, 3838.560539797576, 3844.038990252437, 3845.1348446599573, 6107.908381374283, 6137.693115344215, 6140.385961403824, 6140.907886381708, 6149.262088549358, 3868.052327179859, 3923.2741942740686, 3924.50943632046, 3950.525936887592, 3953.1105501261986, 4006.7996600169877, 4007.479027726377, 4009.2382447334912, 4013.3779264214095, 4022.0749227677798, 4695.741734204583, 4698.506656801055, 4698.694584851974, 4708.9058218835435, 4713.646476514206, 5792.536597072224, 5821.15593238978, 5823.388544202882, 5828.88342233153, 5851.473367396955, 1406.8311802583755, 1430.5064752660317, 1434.6628542292553, 1436.1670231265841, 1437.583100900748, 876.4784952952983, 882.8101958078915, 897.9506127162938, 924.2282693950492, 953.1421720451649, 5002.89994200114, 5047.268146118866, 5060.772265247999, 5080.949190508121, 5084.593232541388, 6015.518758499309, 6028.668423236764, 6029.246344206974, 6030.698465076729, 6045.969770151121, 5071.18860982241, 5075.314832497639, 5080.77136528857, 5080.923786143211, 5085.593152547861, 3157.3053928135773, 3228.2252259458032, 3257.38109759145, 3276.737860971117, 3285.65500622436, 5747.1379293106365, 5747.770089196469, 5752.0, 5756.683382413924, 5757.913631295534, 6305.779297724649, 6306.779262725782, 6308.675178212535, 6312.084747711556, 6330.272018717815, 5647.745851436695, 5691.943080569225, 5704.828855134353, 5743.8564035899435, 5743.885122297531, 6078.817635470766, 6128.1726966858905, 6128.693565321805, 6141.692915354125, 6142.355052719578, 5965.135055417027, 5969.3732158122775, 5974.522038237017, 5978.3423823378525, 5979.252593425822, 5694.4590263924865, 5727.940331038772, 5728.856778580569, 5732.828015159552, 5734.942650573441, 3745.3258413484746, 3869.303525365461, 3870.7871067091583, 3880.0, 3887.378019516864, 6058.545609079425, 6085.908711369421, 6099.237595300588, 6110.0, 6112.449879510866, 5830.76676932918, 5852.502537284378, 5864.853378665567, 5865.589408741347, 5868.471837534472, 5669.404712505293, 5842.853928651818, 5851.444112809277, 5855.151003104441, 5858.9121163181735, 5764.567657425626, 5766.279215098113, 5766.452187042224, 5766.654000759968, 5781.595288329777, 5903.380145084876, 5904.675242861684, 5922.289325280994, 5922.437368449991, 5936.22829032223, 5619.8595035124445, 5771.7402716877, 5772.682502462405, 5777.22007528995, 5784.652920824764], "unit": "ms", "name": "prototype.html", "value": 4276.205676131019}, {"lowerIsBetter": false, "replicates": [642.3480167629854, 654.5450911616429, 683.0028158888023, 716.0691101567962, 731.2796895058368, 11349.829752553718, 11499.137564682662, 11502.194846360737, 11503.482343294561, 11515.251508651932, 11815.232009919371, 11903.702407439818, 11960.641180764569, 11984.681685014662, 12015.579454719087, 621.7295476467751, 628.4438272129153, 785.5365334452672, 793.5199204481293, 800.6957356204634, 788.0228516639355, 791.133708589094, 793.1513280789558, 795.2564352330589, 797.6011994002998, 826.9090400055989, 828.9709860154895, 833.6415341403194, 834.7579202031403, 835.0939230934443, 818.4720855048461, 829.5810615639094, 829.9004119505638, 830.9750707478785, 831.1065604475183, 615.8214117905846, 616.2174038970483, 616.4605969776445, 617.857892684685, 618.0, 11318.547258109666, 11348.489317980711, 11395.829062564067, 11405.714857128554, 11422.25755325901, 10530.210234232387, 11407.942960285229, 11535.865864815036, 11572.0, 11649.534018639244, 9367.672131475434, 9427.905720942772, 9447.763805904839, 9451.480168590724, 9494.810103797954, 3306.487494438366, 3336.2993771308056, 3369.7809642373168, 3390.643982381839, 3466.6879980801596, 39495.8025209875, 39775.40336894949, 39817.40273895894, 40200.99999999971, 40234.0, 9184.586693598803, 9403.529823508798, 9485.525723713787, 9537.189338906272, 9628.614855405705, 15993.760093598605, 16008.359665613245, 16027.198640068184, 16040.037597744173, 16089.839101609068, 6155.10750941111, 6217.782377616762, 6264.780732674334, 6304.148939893147, 6307.716152773136, 3592.588549368303, 3638.43604241343, 3648.416253399443, 3657.908552286214, 3734.327820992193, 2547.681539807524, 2579.8710064497077, 2602.7137014928344, 2602.8698565071672, 2629.6318515407856, 486.6301610775836, 489.4860396583644, 497.88548633814423, 500.32456184151107, 500.4895007092745, 480.6107053286849, 501.69471279470775, 562.3364429972671, 583.0991118721586, 614.775606903477, 7250.709971601183, 7584.5070070445245, 7643.7324693635455, 7952.085510166289, 8371.832563348698, 471.42015321154486, 479.2212654436541, 485.2938973793122, 510.3314657798296, 602.3284038297267, 3320.518524813937, 3488.965110348864, 3560.964390356115, 3681.392570225936, 3697.352963231424, 47068.5293147071, 47906.0, 48570.0, 48617.0, 48904.26643600349, 44531.77734111374, 44614.55385446169, 44677.10645787066, 44721.32918006233, 45417.31874021892, 41357.172856542704, 41532.58467415287, 41719.58280417157, 42101.78949105235, 42137.57862421337, 7117.537360071578, 7304.0, 7325.816854578678, 7419.666115024945, 7557.206493318123, 30914.76340946427, 30930.226744331572, 31110.533341999435, 31179.84410077935, 31592.210194745316, 35302.29395412026, 36126.819365904055, 36511.269774603825, 39462.80268598753, 39721.0, 9291.86062209081, 9294.535273236446, 9352.0, 9411.0, 9585.664501742405, 10196.516680718849, 10217.642382516577, 10282.228832837716, 10357.792844143225, 10506.21203409732, 9311.860322095039, 9410.24718022543, 9470.100340467645, 9575.138237558653, 10252.128569071712, 9539.52302384864, 9860.753481163028, 9981.550830212513, 10023.148032417326, 10188.235882309005, 9357.111074447917, 9442.858357124785, 9495.24038076966, 9530.285228607743, 9657.179139773198, 8231.711890083818, 8291.33669306442, 8401.663933442594, 8465.534395608054, 8873.467591944245, 7772.183920688246, 7836.583655381282, 7870.409719270963, 8021.719239826578, 8251.092379838094], "unit": "ms", "name": "yui.html", "value": 5541.919299125974}], "name": "dromaeo_css", "value": 6130.776286981549}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [115.0, 112.0, 114.0, 114.0, 114.0, 126.0, 114.0, 114.0, 115.0, 117.0], "unit": "ms", "name": "ai-astar", "value": 115.5}, {"lowerIsBetter": true, "replicates": [154.0, 137.0, 155.0, 168.0, 134.0, 153.0, 152.0, 132.0, 177.0, 139.0], "unit": "ms", "name": "audio-beat-detection", "value": 150.1}, {"lowerIsBetter": true, "replicates": [167.0, 163.0, 164.0, 163.0, 162.0, 168.0, 172.0, 164.0, 166.0, 164.0], "unit": "ms", "name": "audio-dft", "value": 165.3}, {"lowerIsBetter": true, "replicates": [75.0, 73.0, 74.0, 72.0, 150.0, 72.0, 79.0, 75.0, 73.0, 152.0], "unit": "ms", "name": "audio-fft", "value": 89.5}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 132.0, 93.0, 95.0, 95.0, 136.0, 94.0, 95.0, 127.0], "unit": "ms", "name": "audio-oscillator", "value": 105.8}, {"lowerIsBetter": true, "replicates": [110.0, 111.0, 110.0, 110.0, 110.0, 110.0, 111.0, 110.0, 111.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.3}, {"lowerIsBetter": true, "replicates": [148.0, 148.0, 147.0, 148.0, 150.0, 148.0, 148.0, 148.0, 149.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 148.1}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.1}, {"lowerIsBetter": true, "replicates": [76.0, 76.0, 74.0, 75.0, 76.0, 73.0, 74.0, 85.0, 74.0, 72.0], "unit": "ms", "name": "json-parse-financial", "value": 75.5}, {"lowerIsBetter": true, "replicates": [68.0, 70.0, 69.0, 69.0, 69.0, 68.0, 68.0, 68.0, 69.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.7}, {"lowerIsBetter": true, "replicates": [85.0, 85.0, 85.0, 87.0, 84.0, 87.0, 93.0, 87.0, 88.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.6}, {"lowerIsBetter": true, "replicates": [120.0, 127.0, 116.0, 133.0, 128.0, 127.0, 132.0, 123.0, 124.0, 140.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 127.0}, {"lowerIsBetter": true, "replicates": [151.0, 155.0, 148.0, 150.0, 152.0, 138.0, 153.0, 151.0, 152.0, 139.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 148.9}, {"lowerIsBetter": true, "replicates": [72.0, 70.0, 74.0, 72.0, 71.0, 73.0, 72.0, 73.0, 70.0, 73.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 72.0}], "name": "kraken", "value": 1559.4}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.00265604249668], "unit": "score", "name": "Richards", "value": 212.659248}, {"lowerIsBetter": false, "replicates": [190.83969465648855], "unit": "score", "name": "DeltaBlue", "value": 346.45831999999996}, {"lowerIsBetter": false, "replicates": [278.55153203342616], "unit": "score", "name": "Encrypt", "value": 955.5897900000004}, {"lowerIsBetter": false, "replicates": [5202.072538860104], "unit": "score", "name": "Decrypt", "value": 51.16825996015938}, {"lowerIsBetter": false, "replicates": [1064.9627263045793], "unit": "score", "name": "RayTrace", "value": 694.8496709999997}, {"lowerIsBetter": false, "replicates": [700.2801120448179], "unit": "score", "name": "Earley", "value": 951.7091640000002}, {"lowerIsBetter": false, "replicates": [12207.317073170732], "unit": "score", "name": "Boyer", "value": 54.595370629370635}, {"lowerIsBetter": false, "replicates": [26368.42105263158], "unit": "score", "name": "RegExp", "value": 34.54833333333334}, {"lowerIsBetter": false, "replicates": [697.8367062107467], "unit": "score", "name": "Splay", "value": 116.776603}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 21011.058010300967}]} 04:25:56 INFO - RETURN: dromaeo_css: 6130.78 04:25:56 INFO - RETURN: kraken: 1559.40 04:25:56 INFO - RETURN: v8_7: 21011.06 04:25:56 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,43]]", "result": "21011.06"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,43]]", "result": "1559.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,43]]", "result": "6130.78"}}} 04:25:56 INFO - Return code: 0 04:25:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:25:56 INFO - # TBPL SUCCESS # 04:25:56 INFO - Running post-action listener: _resource_record_post_action 04:25:56 INFO - Running post-run listener: _resource_record_post_run 04:25:57 INFO - Total resource usage - Wall time: 1232s; CPU: 13.0%; Read bytes: 12288; Write bytes: 505053184; Read time: 344; Write time: 412360 04:25:57 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 4096; Write bytes: 231936000; Read time: 156; Write time: 298772 04:25:57 INFO - run-tests - Wall time: 1218s; CPU: 13.0%; Read bytes: 8192; Write bytes: 273117184; Read time: 188; Write time: 113588 04:25:57 INFO - Running post-run listener: _upload_blobber_files 04:25:57 INFO - Blob upload gear active. 04:25:57 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 04:25:57 INFO - Copying logs to upload dir... 04:25:57 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1276.645443 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2015-12-09 04:25:55.126021) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-09 04:25:55.129427) ========= 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=3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010008 build_url: 'https://queue.taskcluster.net/v1/task/uKu4M_pWTZykBKZwUUScoQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-09 04:25:55.181168) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-09 04:25:55.181518) ========= 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=3e14eba40af1795cf0357910000001c3-1449661182.727471-52785026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004488 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-09 04:25:55.230845) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-09 04:25:55.231171) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-09 04:25:55.231474) ========= ========= Total master_lag: 0.23 =========