builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-095 starttime: 1445870466.27 results: success (0) buildid: 20151026064125 builduid: 7320c5683eb1436fbea9b03656dcbb36 revision: 28068d907290d1f5138a0b9e59ae2233a1c1b7a3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:06.269257) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:06.269692) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:06.269996) ========= 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=eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.007697 basedir: '/builds/slave/test' ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:06.362942) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:06.363252) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:06.436330) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:06.436724) ========= 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=eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004503 ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:06.487067) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:06.487345) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:06.487708) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:06.488023) ========= 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=eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-10-26 07:41:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 44.0M=0s 2015-10-26 07:41:06 (44.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.185070 ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:06.686140) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:06.686445) ========= 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=eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014475 ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:06.736539) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:06.736917) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 28068d907290d1f5138a0b9e59ae2233a1c1b7a3 --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 28068d907290d1f5138a0b9e59ae2233a1c1b7a3 --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=eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-10-26 07:41:06,762 truncating revision to first 12 chars 2015-10-26 07:41:06,762 Setting DEBUG logging. 2015-10-26 07:41:06,763 attempt 1/10 2015-10-26 07:41:06,763 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/28068d907290?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-26 07:41:07,019 unpacking tar archive at: mozilla-central-28068d907290/testing/mozharness/ program finished with exit code 0 elapsedTime=0.558912 ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:07.310402) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:07.310700) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:07.320714) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:07.321098) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-26 07:41:07.322256) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 38 secs) (at 2015-10-26 07:41:07.322733) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:41:07 INFO - MultiFileLogger online at 20151026 07:41:07 in /builds/slave/test 07:41:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 07:41:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:41:07 INFO - {'append_to_log': False, 07:41:07 INFO - 'base_work_dir': '/builds/slave/test', 07:41:07 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:41:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:41:07 INFO - 'branch': 'Firefox-Non-PGO', 07:41:07 INFO - 'buildbot_json_path': 'buildprops.json', 07:41:07 INFO - 'config_files': ('talos/linux_config.py',), 07:41:07 INFO - 'default_actions': ('clobber', 07:41:07 INFO - 'read-buildbot-config', 07:41:07 INFO - 'download-and-extract', 07:41:07 INFO - 'populate-webroot', 07:41:07 INFO - 'create-virtualenv', 07:41:07 INFO - 'install', 07:41:07 INFO - 'run-tests'), 07:41:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:41:07 INFO - 'download_minidump_stackwalk': True, 07:41:07 INFO - 'download_symbols': 'ondemand', 07:41:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:41:07 INFO - 'tooltool.py': '/tools/tooltool.py', 07:41:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:41:07 INFO - '/tools/misc-python/virtualenv.py')}, 07:41:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:41:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:41:07 INFO - 'installer_path': 'installer.exe', 07:41:07 INFO - 'log_level': 'info', 07:41:07 INFO - 'log_name': 'talos', 07:41:07 INFO - 'log_to_console': True, 07:41:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:41:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:41:07 INFO - 'opt_config_files': (), 07:41:07 INFO - 'pip_index': False, 07:41:07 INFO - 'sps_profile': False, 07:41:07 INFO - 'sps_profile_interval': 0, 07:41:07 INFO - 'suite': 'dromaeojs', 07:41:07 INFO - 'system_bits': '64', 07:41:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:41:07 INFO - 'title': 'talos-linux64-ix-095', 07:41:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:41:07 INFO - 'use_talos_json': True, 07:41:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:41:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:41:07 INFO - 'work_dir': 'build'} 07:41:07 INFO - ##### 07:41:07 INFO - ##### Running clobber step. 07:41:07 INFO - ##### 07:41:07 INFO - Running pre-action listener: _resource_record_pre_action 07:41:07 INFO - Running main action method: clobber 07:41:07 INFO - rmtree: /builds/slave/test/build 07:41:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:41:07 INFO - Running post-action listener: _resource_record_post_action 07:41:07 INFO - ##### 07:41:07 INFO - ##### Running read-buildbot-config step. 07:41:07 INFO - ##### 07:41:07 INFO - Running pre-action listener: _resource_record_pre_action 07:41:07 INFO - Running main action method: read_buildbot_config 07:41:07 INFO - Using buildbot properties: 07:41:07 INFO - { 07:41:07 INFO - "properties": { 07:41:07 INFO - "buildnumber": 318, 07:41:07 INFO - "product": "firefox", 07:41:07 INFO - "script_repo_revision": "production", 07:41:07 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:41:07 INFO - "repository": "", 07:41:07 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 07:41:07 INFO - "buildid": "20151026064125", 07:41:07 INFO - "slavename": "talos-linux64-ix-095", 07:41:07 INFO - "pgo_build": "False", 07:41:07 INFO - "basedir": "/builds/slave/test", 07:41:07 INFO - "project": "", 07:41:07 INFO - "platform": "ubuntu64_hw", 07:41:07 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:41:07 INFO - "slavebuilddir": "test", 07:41:07 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 07:41:07 INFO - "branch": "mozilla-central", 07:41:07 INFO - "repo_path": "mozilla-central", 07:41:07 INFO - "stage_platform": "linux64", 07:41:07 INFO - "builduid": "7320c5683eb1436fbea9b03656dcbb36", 07:41:07 INFO - "revision": "28068d907290d1f5138a0b9e59ae2233a1c1b7a3" 07:41:07 INFO - }, 07:41:07 INFO - "sourcestamp": { 07:41:07 INFO - "repository": "", 07:41:07 INFO - "hasPatch": false, 07:41:07 INFO - "project": "", 07:41:07 INFO - "branch": "mozilla-central-linux64-talos", 07:41:07 INFO - "changes": [ 07:41:07 INFO - { 07:41:07 INFO - "category": null, 07:41:07 INFO - "files": [ 07:41:07 INFO - { 07:41:07 INFO - "url": null, 07:41:07 INFO - "name": "https://queue.taskcluster.net/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2" 07:41:07 INFO - } 07:41:07 INFO - ], 07:41:07 INFO - "repository": "", 07:41:07 INFO - "rev": "28068d907290d1f5138a0b9e59ae2233a1c1b7a3", 07:41:07 INFO - "who": "cbook@mozilla.com", 07:41:07 INFO - "when": 1445870422, 07:41:07 INFO - "number": 6573808, 07:41:07 INFO - "comments": "Backed out changeset 88b02bfcc8c7 (bug 1119442) for test failures in browser_ProcessHangNotifications.js", 07:41:07 INFO - "project": "", 07:41:07 INFO - "at": "Mon 26 Oct 2015 07:40:22", 07:41:07 INFO - "branch": "mozilla-central-linux64-talos", 07:41:07 INFO - "revlink": "", 07:41:07 INFO - "properties": [ 07:41:07 INFO - [ 07:41:07 INFO - "buildid", 07:41:07 INFO - "20151026064125", 07:41:07 INFO - "Change" 07:41:07 INFO - ], 07:41:07 INFO - [ 07:41:07 INFO - "builduid", 07:41:07 INFO - "7320c5683eb1436fbea9b03656dcbb36", 07:41:07 INFO - "Change" 07:41:07 INFO - ], 07:41:07 INFO - [ 07:41:07 INFO - "pgo_build", 07:41:07 INFO - "False", 07:41:07 INFO - "Change" 07:41:07 INFO - ] 07:41:07 INFO - ], 07:41:07 INFO - "revision": "28068d907290d1f5138a0b9e59ae2233a1c1b7a3" 07:41:07 INFO - } 07:41:07 INFO - ], 07:41:07 INFO - "revision": "28068d907290d1f5138a0b9e59ae2233a1c1b7a3" 07:41:07 INFO - } 07:41:07 INFO - } 07:41:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2. 07:41:07 INFO - Running post-action listener: _resource_record_post_action 07:41:07 INFO - ##### 07:41:07 INFO - ##### Running download-and-extract step. 07:41:07 INFO - ##### 07:41:07 INFO - Running pre-action listener: _resource_record_pre_action 07:41:07 INFO - Running main action method: download_and_extract 07:41:07 INFO - mkdir: /builds/slave/test/build/tests 07:41:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:07 INFO - https://queue.taskcluster.net/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 07:41:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/test_packages.json 07:41:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/test_packages.json 07:41:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 07:41:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 07:41:17 INFO - Downloaded 1302 bytes. 07:41:17 INFO - Reading from file /builds/slave/test/build/test_packages.json 07:41:17 INFO - Using the following test package requirements: 07:41:17 INFO - {u'common': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'], 07:41:17 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 07:41:17 INFO - u'firefox-44.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:41:17 INFO - u'jittest': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 07:41:17 INFO - u'jsshell-linux-x86_64.zip'], 07:41:17 INFO - u'mochitest': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 07:41:17 INFO - u'firefox-44.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:41:17 INFO - u'mozbase': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'], 07:41:17 INFO - u'reftest': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 07:41:17 INFO - u'firefox-44.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:41:17 INFO - u'talos': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 07:41:17 INFO - u'firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:41:17 INFO - u'web-platform': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 07:41:17 INFO - u'firefox-44.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:41:17 INFO - u'webapprt': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'], 07:41:17 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 07:41:17 INFO - u'firefox-44.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:41:17 INFO - Downloading packages: [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:41:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:17 INFO - https://queue.taskcluster.net/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:41:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 07:41:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 07:41:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 07:41:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:41:18 INFO - Downloaded 22686892 bytes. 07:41:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 07:41:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 07:41:18 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 07:41:19 INFO - Return code: 0 07:41:19 INFO - Downloading packages: [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:41:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:19 INFO - https://queue.taskcluster.net/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:41:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 07:41:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 07:41:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 07:41:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:41:27 INFO - Downloaded 22686892 bytes. 07:41:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 07:41:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 07:41:27 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 07:41:28 INFO - Return code: 0 07:41:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:28 INFO - https://queue.taskcluster.net/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:41:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip 07:41:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip 07:41:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip 07:41:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:41:31 INFO - Downloaded 11131307 bytes. 07:41:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 07:41:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip 07:41:31 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 07:41:31 INFO - Return code: 0 07:41:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:31 INFO - https://queue.taskcluster.net/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:41:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 07:41:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 07:41:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 07:41:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:41:34 INFO - Downloaded 55669025 bytes. 07:41:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 07:41:34 INFO - mkdir: /builds/slave/test/properties 07:41:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:41:34 INFO - Writing to file /builds/slave/test/properties/build_url 07:41:34 INFO - Contents: 07:41:34 INFO - build_url:https://queue.taskcluster.net/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 07:41:34 INFO - Running post-action listener: _resource_record_post_action 07:41:34 INFO - Running post-action listener: set_extra_try_arguments 07:41:34 INFO - ##### 07:41:34 INFO - ##### Running populate-webroot step. 07:41:34 INFO - ##### 07:41:34 INFO - Running pre-action listener: _resource_record_pre_action 07:41:34 INFO - Running main action method: populate_webroot 07:41:34 INFO - Guessing talos json url... 07:41:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:34 INFO - https://queue.taskcluster.net/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.txt matches https://queue.taskcluster.net 07:41:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.txt 07:41:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.txt 07:41:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.txt to /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.txt 07:41:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.txt', 'file_name': '/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.txt'}, attempt #1 07:41:37 INFO - Downloaded 99 bytes. 07:41:37 INFO - Reading from file /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.txt 07:41:37 INFO - Contents: 07:41:37 INFO - 20151026064125 07:41:37 INFO - https://hg.mozilla.org/mozilla-central/rev/28068d907290d1f5138a0b9e59ae2233a1c1b7a3 07:41:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:37 INFO - trying https://hg.mozilla.org/mozilla-central/raw-file/28068d9072/testing/talos/talos.json 07:41:37 INFO - Downloading https://hg.mozilla.org/mozilla-central/raw-file/28068d9072/testing/talos/talos.json to /builds/slave/test/build/talos.json 07:41:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/mozilla-central/raw-file/28068d9072/testing/talos/talos.json', 'file_name': '/builds/slave/test/build/talos.json'}, attempt #1 07:41:38 INFO - Downloaded 4264 bytes. 07:41:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:41:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:41:38 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 07:41:38 INFO - '../fennec_ids.txt'], 07:41:38 INFO - 'tests': ['tcheck2']}, 07:41:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:41:38 INFO - '--tppagecycles', 07:41:38 INFO - '7'], 07:41:38 INFO - 'tests': ['tsvgm']}}, 07:41:38 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 07:41:38 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 07:41:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:41:38 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:41:38 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 07:41:38 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:41:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:41:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:41:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:41:38 INFO - 'talos_options': ['--e10s'], 07:41:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:41:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:41:38 INFO - 'tests': ['damp', 'tps']}, 07:41:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:41:38 INFO - 'talos_options': ['--e10s'], 07:41:38 INFO - 'tests': ['damp', 'tps']}, 07:41:38 INFO - 'g3': {'tests': ['dromaeo_dom']}, 07:41:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:41:38 INFO - 'other': {'tests': ['a11yr', 07:41:38 INFO - 'ts_paint', 07:41:38 INFO - 'tpaint', 07:41:38 INFO - 'sessionrestore', 07:41:38 INFO - 'sessionrestore_no_auto_restore']}, 07:41:38 INFO - 'other-e10s': {'talos_options': ['--e10s'], 07:41:38 INFO - 'tests': ['a11yr', 07:41:38 INFO - 'ts_paint', 07:41:38 INFO - 'tpaint', 07:41:38 INFO - 'sessionrestore', 07:41:38 INFO - 'sessionrestore_no_auto_restore']}, 07:41:38 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 07:41:38 INFO - 'tests': ['a11yr', 07:41:38 INFO - 'ts_paint', 07:41:38 INFO - 'tpaint', 07:41:38 INFO - 'sessionrestore', 07:41:38 INFO - 'sessionrestore_no_auto_restore']}, 07:41:38 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 07:41:38 INFO - 'tests': ['a11yr', 07:41:38 INFO - 'ts_paint', 07:41:38 INFO - 'tpaint', 07:41:38 INFO - 'sessionrestore', 07:41:38 INFO - 'sessionrestore_no_auto_restore']}, 07:41:38 INFO - 'other_l64': {'tests': ['a11yr', 07:41:38 INFO - 'ts_paint', 07:41:38 INFO - 'tpaint', 07:41:38 INFO - 'sessionrestore', 07:41:38 INFO - 'sessionrestore_no_auto_restore']}, 07:41:38 INFO - 'other_nol64': {'tests': ['a11yr', 07:41:38 INFO - 'ts_paint', 07:41:38 INFO - 'tpaint', 07:41:38 INFO - 'sessionrestore', 07:41:38 INFO - 'sessionrestore_no_auto_restore']}, 07:41:38 INFO - 'svgr': {'tests': ['tsvgx', 07:41:38 INFO - 'tsvgr_opacity', 07:41:38 INFO - 'tart', 07:41:38 INFO - 'tscrollx', 07:41:38 INFO - 'cart']}, 07:41:38 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 07:41:38 INFO - 'tests': ['tsvgx', 07:41:38 INFO - 'tsvgr_opacity', 07:41:38 INFO - 'tart', 07:41:38 INFO - 'tscrollx', 07:41:38 INFO - 'cart']}, 07:41:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:41:38 INFO - 'tests': ['tp5o']}, 07:41:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:41:38 INFO - 'talos_options': ['--e10s'], 07:41:38 INFO - 'tests': ['tp5o']}, 07:41:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:41:38 INFO - 'talos_options': ['--xperf_path', 07:41:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:41:38 INFO - 'tests': ['tp5n']}, 07:41:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:41:38 INFO - 'talos_options': ['--e10s', 07:41:38 INFO - '--xperf_path', 07:41:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 07:41:38 INFO - 'C:/slave/talos-data/talos/xperf.config'], 07:41:38 INFO - 'tests': ['tp5n']}}, 07:41:38 INFO - 'talos.zip': {'path': '', 07:41:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:41:38 INFO - Running post-action listener: _resource_record_post_action 07:41:38 INFO - ##### 07:41:38 INFO - ##### Running create-virtualenv step. 07:41:38 INFO - ##### 07:41:38 INFO - Running pre-action listener: _resource_record_pre_action 07:41:38 INFO - Running main action method: create_virtualenv 07:41:38 INFO - Creating virtualenv /builds/slave/test/build/venv 07:41:38 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:41:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:41:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:41:38 INFO - Using real prefix '/usr' 07:41:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:41:39 INFO - Installing distribute.............................................................................................................................................................................................done. 07:41:41 INFO - Installing pip.................done. 07:41:41 INFO - Return code: 0 07:41:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:41: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')]} 07:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41: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')]} 07:41:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff84d860c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b927b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c64ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cf3250>, '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 0x2c630b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c63540>, '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': 'eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:41:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:41:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:41:41 INFO - Using env: {'DISPLAY': ':0', 07:41:41 INFO - 'HOME': '/home/cltbld', 07:41:41 INFO - 'LANG': 'en_US.UTF-8', 07:41:41 INFO - 'LANGUAGE': 'en_US:en', 07:41:41 INFO - 'LOGNAME': 'cltbld', 07:41:41 INFO - 'MAIL': '/var/mail/cltbld', 07:41:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:41 INFO - 'MOZ_NO_REMOTE': '1', 07:41:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:41 INFO - 'NO_EM_RESTART': '1', 07:41:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:41 INFO - 'PWD': '/builds/slave/test', 07:41:41 INFO - 'SHELL': '/bin/bash', 07:41:41 INFO - 'SHLVL': '1', 07:41:41 INFO - 'TERM': 'linux', 07:41:41 INFO - 'TMOUT': '86400', 07:41:41 INFO - 'USER': 'cltbld', 07:41:41 INFO - 'XDG_SESSION_COOKIE': 'eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937', 07:41:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:41 INFO - '_': '/tools/buildbot/bin/python'} 07:41:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:41 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:41:41 INFO - Cleaning up... 07:41:41 INFO - Return code: 0 07:41:41 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:41: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')]} 07:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41: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')]} 07:41:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ff84d860c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b927b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c64ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cf3250>, '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 0x2c630b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c63540>, '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': 'eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:41:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 07:41:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 07:41:41 INFO - Using env: {'DISPLAY': ':0', 07:41:41 INFO - 'HOME': '/home/cltbld', 07:41:41 INFO - 'LANG': 'en_US.UTF-8', 07:41:41 INFO - 'LANGUAGE': 'en_US:en', 07:41:41 INFO - 'LOGNAME': 'cltbld', 07:41:41 INFO - 'MAIL': '/var/mail/cltbld', 07:41:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:41 INFO - 'MOZ_NO_REMOTE': '1', 07:41:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:41 INFO - 'NO_EM_RESTART': '1', 07:41:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:41 INFO - 'PWD': '/builds/slave/test', 07:41:41 INFO - 'SHELL': '/bin/bash', 07:41:41 INFO - 'SHLVL': '1', 07:41:41 INFO - 'TERM': 'linux', 07:41:41 INFO - 'TMOUT': '86400', 07:41:41 INFO - 'USER': 'cltbld', 07:41:41 INFO - 'XDG_SESSION_COOKIE': 'eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937', 07:41:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:41 INFO - '_': '/tools/buildbot/bin/python'} 07:41:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:41 INFO - Downloading/unpacking psutil>=0.7.1 07:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:43 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 07:41:43 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 07:41:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:41:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:41:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:41:44 INFO - Installing collected packages: psutil 07:41:44 INFO - Running setup.py install for psutil 07:41:44 INFO - building 'psutil._psutil_linux' extension 07:41:44 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 07:41:45 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 07:41:45 INFO - building 'psutil._psutil_posix' extension 07:41:45 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 07:41:45 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 07:41:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:41:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:41:45 INFO - Successfully installed psutil 07:41:45 INFO - Cleaning up... 07:41:45 INFO - Return code: 0 07:41:45 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:41:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:45 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 0x7ff84d860c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b927b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c64ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cf3250>, '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 0x2c630b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c63540>, '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': 'eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:41:45 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 07:41:45 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 07:41:45 INFO - Using env: {'DISPLAY': ':0', 07:41:45 INFO - 'HOME': '/home/cltbld', 07:41:45 INFO - 'LANG': 'en_US.UTF-8', 07:41:45 INFO - 'LANGUAGE': 'en_US:en', 07:41:45 INFO - 'LOGNAME': 'cltbld', 07:41:45 INFO - 'MAIL': '/var/mail/cltbld', 07:41:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:45 INFO - 'MOZ_NO_REMOTE': '1', 07:41:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:45 INFO - 'NO_EM_RESTART': '1', 07:41:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:45 INFO - 'PWD': '/builds/slave/test', 07:41:45 INFO - 'SHELL': '/bin/bash', 07:41:45 INFO - 'SHLVL': '1', 07:41:45 INFO - 'TERM': 'linux', 07:41:45 INFO - 'TMOUT': '86400', 07:41:45 INFO - 'USER': 'cltbld', 07:41:45 INFO - 'XDG_SESSION_COOKIE': 'eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937', 07:41:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:45 INFO - '_': '/tools/buildbot/bin/python'} 07:41:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:45 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:47 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:41:47 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 07:41:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:41:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:41:47 INFO - Installing collected packages: mozsystemmonitor 07:41:47 INFO - Running setup.py install for mozsystemmonitor 07:41:47 INFO - Successfully installed mozsystemmonitor 07:41:47 INFO - Cleaning up... 07:41:47 INFO - Return code: 0 07:41:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:41:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:47 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 0x7ff84d860c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b927b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c64ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cf3250>, '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 0x2c630b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c63540>, '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': 'eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:41:47 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 07:41:47 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 07:41:47 INFO - Using env: {'DISPLAY': ':0', 07:41:47 INFO - 'HOME': '/home/cltbld', 07:41:47 INFO - 'LANG': 'en_US.UTF-8', 07:41:47 INFO - 'LANGUAGE': 'en_US:en', 07:41:47 INFO - 'LOGNAME': 'cltbld', 07:41:47 INFO - 'MAIL': '/var/mail/cltbld', 07:41:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:47 INFO - 'MOZ_NO_REMOTE': '1', 07:41:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:47 INFO - 'NO_EM_RESTART': '1', 07:41:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:47 INFO - 'PWD': '/builds/slave/test', 07:41:47 INFO - 'SHELL': '/bin/bash', 07:41:47 INFO - 'SHLVL': '1', 07:41:47 INFO - 'TERM': 'linux', 07:41:47 INFO - 'TMOUT': '86400', 07:41:47 INFO - 'USER': 'cltbld', 07:41:47 INFO - 'XDG_SESSION_COOKIE': 'eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937', 07:41:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:47 INFO - '_': '/tools/buildbot/bin/python'} 07:41:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:48 INFO - Downloading/unpacking blobuploader==1.2.4 07:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:50 INFO - Downloading blobuploader-1.2.4.tar.gz 07:41:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 07:41:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:41:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:41:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 07:41:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:41:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:41:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:50 INFO - Downloading docopt-0.6.1.tar.gz 07:41:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 07:41:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:41:51 INFO - Installing collected packages: blobuploader, requests, docopt 07:41:51 INFO - Running setup.py install for blobuploader 07:41:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:41:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:41:51 INFO - Running setup.py install for requests 07:41:51 INFO - Running setup.py install for docopt 07:41:51 INFO - Successfully installed blobuploader requests docopt 07:41:51 INFO - Cleaning up... 07:41:51 INFO - Return code: 0 07:41:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:41:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:51 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 0x7ff84d860c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b927b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c64ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cf3250>, '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 0x2c630b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c63540>, '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': 'eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:41:51 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 07:41:51 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 07:41:51 INFO - Using env: {'DISPLAY': ':0', 07:41:51 INFO - 'HOME': '/home/cltbld', 07:41:51 INFO - 'LANG': 'en_US.UTF-8', 07:41:51 INFO - 'LANGUAGE': 'en_US:en', 07:41:51 INFO - 'LOGNAME': 'cltbld', 07:41:51 INFO - 'MAIL': '/var/mail/cltbld', 07:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:51 INFO - 'MOZ_NO_REMOTE': '1', 07:41:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:51 INFO - 'NO_EM_RESTART': '1', 07:41:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:51 INFO - 'PWD': '/builds/slave/test', 07:41:51 INFO - 'SHELL': '/bin/bash', 07:41:51 INFO - 'SHLVL': '1', 07:41:51 INFO - 'TERM': 'linux', 07:41:51 INFO - 'TMOUT': '86400', 07:41:51 INFO - 'USER': 'cltbld', 07:41:51 INFO - 'XDG_SESSION_COOKIE': 'eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937', 07:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:51 INFO - '_': '/tools/buildbot/bin/python'} 07:41:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:41:51 INFO - Running setup.py (path:/tmp/pip-teJdNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:41:51 INFO - Running setup.py (path:/tmp/pip-RklWXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:41:51 INFO - Running setup.py (path:/tmp/pip-KPFOug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:41:52 INFO - Running setup.py (path:/tmp/pip-iTn_Yn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:41:52 INFO - Running setup.py (path:/tmp/pip-wykgGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:41:52 INFO - Running setup.py (path:/tmp/pip-qo4ZQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:41:52 INFO - Running setup.py (path:/tmp/pip-rMyewa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:41:52 INFO - Running setup.py (path:/tmp/pip-dKhLsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:41:52 INFO - Running setup.py (path:/tmp/pip-PzQKuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:41:52 INFO - Running setup.py (path:/tmp/pip-fgCjUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:41:52 INFO - Running setup.py (path:/tmp/pip-x0x4e7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:41:52 INFO - Running setup.py (path:/tmp/pip-exdqtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:41:52 INFO - Running setup.py (path:/tmp/pip-yw7jYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:41:52 INFO - Running setup.py (path:/tmp/pip-XWKrDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:41:52 INFO - Running setup.py (path:/tmp/pip-jpkQRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:41:52 INFO - Running setup.py (path:/tmp/pip-Z8ZpME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:41:52 INFO - Running setup.py (path:/tmp/pip-WlbxfM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:41:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:41:53 INFO - Running setup.py install for manifestparser 07:41:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:41:53 INFO - Running setup.py install for mozcrash 07:41:53 INFO - Running setup.py install for mozdebug 07:41:53 INFO - Running setup.py install for mozdevice 07:41:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:41:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:41:53 INFO - Running setup.py install for mozfile 07:41:53 INFO - Running setup.py install for mozhttpd 07:41:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:41:53 INFO - Running setup.py install for mozinfo 07:41:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:41:53 INFO - Running setup.py install for mozInstall 07:41:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:41:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:41:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:41:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:41:53 INFO - Running setup.py install for mozleak 07:41:53 INFO - Running setup.py install for mozlog 07:41:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:41:54 INFO - Running setup.py install for moznetwork 07:41:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:41:54 INFO - Running setup.py install for mozprocess 07:41:54 INFO - Running setup.py install for mozprofile 07:41:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:41:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:41:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:41:54 INFO - Running setup.py install for mozrunner 07:41:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:41:54 INFO - Running setup.py install for mozscreenshot 07:41:54 INFO - Running setup.py install for moztest 07:41:54 INFO - Running setup.py install for mozversion 07:41:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:41:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:41:54 INFO - Cleaning up... 07:41:54 INFO - Return code: 0 07:41:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:41:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:54 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 0x7ff84d860c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b927b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c64ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cf3250>, '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 0x2c630b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c63540>, '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': 'eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:41:54 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 07:41:54 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 07:41:54 INFO - Using env: {'DISPLAY': ':0', 07:41:54 INFO - 'HOME': '/home/cltbld', 07:41:54 INFO - 'LANG': 'en_US.UTF-8', 07:41:54 INFO - 'LANGUAGE': 'en_US:en', 07:41:54 INFO - 'LOGNAME': 'cltbld', 07:41:54 INFO - 'MAIL': '/var/mail/cltbld', 07:41:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:54 INFO - 'MOZ_NO_REMOTE': '1', 07:41:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:54 INFO - 'NO_EM_RESTART': '1', 07:41:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:54 INFO - 'PWD': '/builds/slave/test', 07:41:54 INFO - 'SHELL': '/bin/bash', 07:41:54 INFO - 'SHLVL': '1', 07:41:54 INFO - 'TERM': 'linux', 07:41:54 INFO - 'TMOUT': '86400', 07:41:54 INFO - 'USER': 'cltbld', 07:41:54 INFO - 'XDG_SESSION_COOKIE': 'eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937', 07:41:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:54 INFO - '_': '/tools/buildbot/bin/python'} 07:41:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:41:55 INFO - Running setup.py (path:/tmp/pip-IwWQx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:41:55 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:41:55 INFO - Running setup.py (path:/tmp/pip-fzaEyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:41:55 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)) 07:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:41:55 INFO - Running setup.py (path:/tmp/pip-AE9jY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:41:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:41:55 INFO - Running setup.py (path:/tmp/pip-FiwxZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:41:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:41:55 INFO - Running setup.py (path:/tmp/pip-G1zieB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:41:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:41:55 INFO - Running setup.py (path:/tmp/pip-Pk0L9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:41:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:41:55 INFO - Running setup.py (path:/tmp/pip-CGis4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:41:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:41:55 INFO - Running setup.py (path:/tmp/pip-TTngWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:41:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:41:55 INFO - Running setup.py (path:/tmp/pip-wvj7ta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:41:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:41:55 INFO - Running setup.py (path:/tmp/pip-aX8MJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:41:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:41:55 INFO - Running setup.py (path:/tmp/pip-RKnjVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:41:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:41:55 INFO - Running setup.py (path:/tmp/pip-1yFmbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:41:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:41:55 INFO - Running setup.py (path:/tmp/pip-tzDnNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:41:55 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)) 07:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:41:55 INFO - Running setup.py (path:/tmp/pip-xgOv6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:41:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:41:56 INFO - Running setup.py (path:/tmp/pip-zxr9LQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:41:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:41:56 INFO - Running setup.py (path:/tmp/pip-NWzQkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:41:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:41:56 INFO - Running setup.py (path:/tmp/pip-6D4rL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:41:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:41:56 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)) 07:41:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:41:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:41:56 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.46->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:41:56 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.46->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:41:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:57 INFO - Downloading blessings-1.5.1.tar.gz 07:41:57 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 07:41:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:41:57 INFO - Installing collected packages: blessings 07:41:57 INFO - Running setup.py install for blessings 07:41:58 INFO - Successfully installed blessings 07:41:58 INFO - Cleaning up... 07:41:58 INFO - Return code: 0 07:41:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:41:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:41:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:41:58 INFO - Reading from file tmpfile_stdout 07:41:58 INFO - Current package versions: 07:41:58 INFO - argparse == 1.2.1 07:41:58 INFO - blessings == 1.5.1 07:41:58 INFO - blobuploader == 1.2.4 07:41:58 INFO - docopt == 0.6.1 07:41:58 INFO - manifestparser == 1.1 07:41:58 INFO - mozInstall == 1.12 07:41:58 INFO - mozcrash == 0.16 07:41:58 INFO - mozdebug == 0.1 07:41:58 INFO - mozdevice == 0.46 07:41:58 INFO - mozfile == 1.2 07:41:58 INFO - mozhttpd == 0.7 07:41:58 INFO - mozinfo == 0.8 07:41:58 INFO - mozleak == 0.1 07:41:58 INFO - mozlog == 3.0 07:41:58 INFO - moznetwork == 0.27 07:41:58 INFO - mozprocess == 0.22 07:41:58 INFO - mozprofile == 0.27 07:41:58 INFO - mozrunner == 6.11 07:41:58 INFO - mozscreenshot == 0.1 07:41:58 INFO - mozsystemmonitor == 0.0 07:41:58 INFO - moztest == 0.7 07:41:58 INFO - mozversion == 1.4 07:41:58 INFO - psutil == 3.1.1 07:41:58 INFO - requests == 1.2.3 07:41:58 INFO - wsgiref == 0.1.2 07:41:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:41:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-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 0x7ff84d860c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b927b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c64ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cf3250>, '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 0x2c630b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c63540>, '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': 'eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:41:58 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 07:41:58 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 07:41:58 INFO - Using env: {'DISPLAY': ':0', 07:41:58 INFO - 'HOME': '/home/cltbld', 07:41:58 INFO - 'LANG': 'en_US.UTF-8', 07:41:58 INFO - 'LANGUAGE': 'en_US:en', 07:41:58 INFO - 'LOGNAME': 'cltbld', 07:41:58 INFO - 'MAIL': '/var/mail/cltbld', 07:41:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:58 INFO - 'MOZ_NO_REMOTE': '1', 07:41:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:58 INFO - 'NO_EM_RESTART': '1', 07:41:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:58 INFO - 'PWD': '/builds/slave/test', 07:41:58 INFO - 'SHELL': '/bin/bash', 07:41:58 INFO - 'SHLVL': '1', 07:41:58 INFO - 'TERM': 'linux', 07:41:58 INFO - 'TMOUT': '86400', 07:41:58 INFO - 'USER': 'cltbld', 07:41:58 INFO - 'XDG_SESSION_COOKIE': 'eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937', 07:41:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:58 INFO - '_': '/tools/buildbot/bin/python'} 07:41:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:58 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)) 07:41:58 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)) 07:41:58 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)) 07:41:58 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)) 07:41:58 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)) 07:41:58 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)) 07:41:58 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)) 07:41:58 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)) 07:41:58 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)) 07:41:58 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)) 07:41:58 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)) 07:41:58 INFO - Cleaning up... 07:41:58 INFO - Return code: 0 07:41:58 INFO - Running post-action listener: _resource_record_post_action 07:41:58 INFO - Running post-action listener: _start_resource_monitoring 07:41:58 INFO - Starting resource monitoring. 07:41:58 INFO - ##### 07:41:58 INFO - ##### Running install step. 07:41:58 INFO - ##### 07:41:58 INFO - Running pre-action listener: _resource_record_pre_action 07:41:58 INFO - Running main action method: install 07:41:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:41:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:41:58 INFO - Reading from file tmpfile_stdout 07:41:58 INFO - Detecting whether we're running mozinstall >=1.0... 07:41:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:41:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:41:58 INFO - Reading from file tmpfile_stdout 07:41:58 INFO - Output received: 07:41:58 INFO - Usage: mozinstall [options] installer 07:41:58 INFO - Options: 07:41:58 INFO - -h, --help show this help message and exit 07:41:58 INFO - -d DEST, --destination=DEST 07:41:58 INFO - Directory to install application into. [default: 07:41:58 INFO - "/builds/slave/test"] 07:41:58 INFO - --app=APP Application being installed. [default: firefox] 07:41:58 INFO - mkdir: /builds/slave/test/build/application 07:41:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:41:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:42:13 INFO - Reading from file tmpfile_stdout 07:42:13 INFO - Output received: 07:42:13 INFO - /builds/slave/test/build/application/firefox/firefox 07:42:13 INFO - Running post-action listener: _resource_record_post_action 07:42:13 INFO - ##### 07:42:13 INFO - ##### Running run-tests step. 07:42:13 INFO - ##### 07:42:13 INFO - Running pre-action listener: _resource_record_pre_action 07:42:13 INFO - Running main action method: run_tests 07:42:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:42:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:42:13 INFO - Python 2.7.3 07:42:13 INFO - Return code: 0 07:42:13 INFO - grabbing minidump binary from tooltool 07:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:42:13 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 0x2cf3250>, '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 0x2c630b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c63540>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:42:13 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:42:13 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 07:42:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:42:13 INFO - Return code: 0 07:42:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:42:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:42:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:42:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:42:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:42:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-095', '--webServer', 'localhost'] in /builds/slave/test/build 07:42:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-095 --webServer localhost 07:42:13 INFO - Using env: {'DISPLAY': ':0', 07:42:13 INFO - 'HOME': '/home/cltbld', 07:42:13 INFO - 'LANG': 'en_US.UTF-8', 07:42:13 INFO - 'LANGUAGE': 'en_US:en', 07:42:13 INFO - 'LOGNAME': 'cltbld', 07:42:13 INFO - 'MAIL': '/var/mail/cltbld', 07:42:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:42:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:42:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:13 INFO - 'MOZ_NO_REMOTE': '1', 07:42:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:42:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:42:13 INFO - 'NO_EM_RESTART': '1', 07:42:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:42:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:13 INFO - 'PWD': '/builds/slave/test', 07:42:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:42:13 INFO - 'SHELL': '/bin/bash', 07:42:13 INFO - 'SHLVL': '1', 07:42:13 INFO - 'TERM': 'linux', 07:42:13 INFO - 'TMOUT': '86400', 07:42:13 INFO - 'USER': 'cltbld', 07:42:13 INFO - 'XDG_SESSION_COOKIE': 'eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937', 07:42:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:13 INFO - '_': '/tools/buildbot/bin/python'} 07:42:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-095', '--webServer', 'localhost'] with output_timeout 3600 07:42:13 INFO - mozversion INFO | application_buildid: 20151026064125 07:42:13 INFO - mozversion INFO | application_changeset: 28068d907290d1f5138a0b9e59ae2233a1c1b7a3 07:42:13 INFO - mozversion INFO | application_display_name: Nightly 07:42:13 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:42:13 INFO - mozversion INFO | application_name: Firefox 07:42:13 INFO - mozversion INFO | application_remotingname: firefox 07:42:13 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 07:42:13 INFO - mozversion INFO | application_vendor: Mozilla 07:42:13 INFO - mozversion INFO | application_version: 44.0a1 07:42:13 INFO - mozversion INFO | platform_buildid: 20151026064125 07:42:13 INFO - mozversion INFO | platform_changeset: 28068d907290d1f5138a0b9e59ae2233a1c1b7a3 07:42:13 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 07:42:13 INFO - mozversion INFO | platform_version: 44.0a1 07:42:13 INFO - 2015-10-26 07:42:13,927 DEBUG : using testdate: 1445870533 07:42:13 INFO - 2015-10-26 07:42:13,927 DEBUG : actual date: 1445870533 07:42:13 INFO - 2015-10-26 07:42:13,937 INFO : starting webserver on 'localhost:41319' 07:42:13 INFO - 2015-10-26 07:42:13,937 INFO : Starting test suite talos-linux64-ix-095 07:42:13 INFO - 2015-10-26 07:42:13,937 INFO : Starting test dromaeo_css 07:42:13 INFO - 2015-10-26 07:42:13,937 DEBUG : operating with platform_type : linux_ 07:42:13 INFO - 2015-10-26 07:42:13,938 INFO : Initialising browser for dromaeo_css test... 07:42:13 INFO - 2015-10-26 07:42:13,970 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZveD9T/profile http://localhost:41319/getInfo.html 07:42:19 INFO - 2015-10-26 07:42:19,905 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:42:19 INFO - 2015-10-26 07:42:19,905 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:42:19 INFO - 2015-10-26 07:42:19,905 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:42:19 INFO - 2015-10-26 07:42:19,905 DEBUG : BROWSER_OUTPUT: __metrics 07:42:20 INFO - 2015-10-26 07:42:20,087 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:42:21 INFO - 2015-10-26 07:42:21,340 INFO : Browser initialized. 07:42:21 INFO - 2015-10-26 07:42:21,341 INFO : Running cycle 1/1 for dromaeo_css test... 07:42:21 INFO - 2015-10-26 07:42:21,341 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZveD9T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:42:33 INFO - 2015-10-26 07:42:33,303 DEBUG : BROWSER_OUTPUT: RSS: Main: 142172160 07:42:33 INFO - 2015-10-26 07:42:33,303 DEBUG : BROWSER_OUTPUT: 07:43:25 INFO - 2015-10-26 07:43:25,024 DEBUG : BROWSER_OUTPUT: 1445870605023 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:45:37 INFO - 2015-10-26 07:45:37,186 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41319/tests/dromaeo/cssquery-ext.html) 07:45:38 INFO - 2015-10-26 07:45:38,360 DEBUG : BROWSER_OUTPUT: RSS: Main: 256016384 07:45:38 INFO - 2015-10-26 07:45:38,360 DEBUG : BROWSER_OUTPUT: 07:48:40 INFO - 2015-10-26 07:48:40,814 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/dromaeo/cssquery-ext.html (next: http://localhost:41319/tests/dromaeo/cssquery-jquery.html) 07:48:41 INFO - 2015-10-26 07:48:41,395 DEBUG : BROWSER_OUTPUT: RSS: Main: 225701888 07:48:41 INFO - 2015-10-26 07:48:41,395 DEBUG : BROWSER_OUTPUT: 07:51:43 INFO - 2015-10-26 07:51:43,346 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41319/tests/dromaeo/cssquery-mootools.html) 07:51:43 INFO - 2015-10-26 07:51:43,927 DEBUG : BROWSER_OUTPUT: RSS: Main: 223580160 07:51:43 INFO - 2015-10-26 07:51:43,927 DEBUG : BROWSER_OUTPUT: 07:54:45 INFO - 2015-10-26 07:54:45,875 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41319/tests/dromaeo/cssquery-prototype.html) 07:54:46 INFO - 2015-10-26 07:54:46,442 DEBUG : BROWSER_OUTPUT: RSS: Main: 205877248 07:54:46 INFO - 2015-10-26 07:54:46,442 DEBUG : BROWSER_OUTPUT: 07:57:49 INFO - 2015-10-26 07:57:49,207 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41319/tests/dromaeo/cssquery-yui.html) 07:57:49 INFO - 2015-10-26 07:57:49,796 DEBUG : BROWSER_OUTPUT: RSS: Main: 204746752 07:57:49 INFO - 2015-10-26 07:57:49,796 DEBUG : BROWSER_OUTPUT: 08:00:52 INFO - 2015-10-26 08:00:52,188 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/dromaeo/cssquery-yui.html (next: http://localhost:41319/tests/dromaeo/cssquery-dojo.html) 08:00:52 INFO - 2015-10-26 08:00:52,447 DEBUG : BROWSER_OUTPUT: RSS: Main: 239616000 08:00:52 INFO - 2015-10-26 08:00:52,447 DEBUG : BROWSER_OUTPUT: 08:00:52 INFO - 2015-10-26 08:00:52,447 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:00:52 INFO - 2015-10-26 08:00:52,447 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:00:52 INFO - 2015-10-26 08:00:52,447 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:00:52 INFO - 2015-10-26 08:00:52,447 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:00:52 INFO - 2015-10-26 08:00:52,448 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2674.2378422149686;2765.543685291927;2785.8607069646514;2799.6220510231115;2810.072676016914;4802.1836287831065;4915.041566894457;4940.135476291648;5008.649394542384;5019.874503137424;4861.975690121553;4877.878053048676;4934.28452874333;4954.331165292684;4964.900701985958;4511.842085527007;4531.252343363358;4538.704984176026;4555.863324100283;4615.3077038444335;4404.427424434819;4567.109413664337;4576.908461830762;4581.221192397301;4619.86140415788;4399.7360158390275;4477.6417886569;4526.34368016639;4543.0459603483305;4556.863294101182;4505.098980203972;4508.143452743968;4513.7743112844555;4529.094181163748;4577.176108300505;4369.519352871182;4475.485319188302;4479.7984090716;4492.505824359318;4531.161735079021;4721.315409265637;4753.405824271966;4805.231163013901;4825.276208568707;4867.8783030424165;5177.042247184246;5206.921896171561;5250.369955605313;5251.238570407308;5255.3168088148295;5069.467705890866;5219.556337711299;5235.188545775411;5237.319148510707;5238.8690282742855;4729.858104256878;4799.448063472675;4810.42274927011;4813.807447702123;4857.489963553847;5326.573874090063;5329.120695085345;5333.759980800874;5364.678119312854;5377.435369286247;4924.630652701062;5031.974840125849;5120.02719483297;5121.718305493234;5127.820526281562;5160.870978225499;5164.302819119371;5319.680819150786;5320.76056577455;5324.041672498987;5190.143626301618;5192.8961420771375;5194.558462530727;5207.453217412193;5223.791048358099;5161.81933632321;5176.922346164811;5190.299309593157;5196.610254230948;5229.686218826882;4109.383592461095;4133.42132101506;4231.0268638212765;4234.576542345802;4239.385289133058;3712.3503386907723;3723.553173619183;3742.55089389275;3776.5656949450618;3786.8863934082024;4496.348029535764;4499.10017996402;4499.730016199039;4527.049319642846;4534.682572219913;5000.1249781288125;5110.3356563646485;5115.795368185305;5133.974330128325;5267.420583735786;4443.977780111079;4454.933176002362;4474.082813023322;4500.234960056799;4503.504614492404;4578.45058592971;4582.816687332469;4584.083183363341;4594.6783725139585;4620.699654522446;4736.573708366263;4841.419029716457;4854.902901942012;4859.586935110555;4940.555350018443;5266.97366513165;5280.128778751463;5283.128283833124;5286.709230992333;5299.920501192408;4990.72551009698;4993.650444468853;4993.900121997613;4995.15082435994;5048.242763585492;4949.604031677385;5063.012712521023;5093.083245015932;5100.48995100487;5205.297284866646;5233.973830130825;5234.947650523446;5240.371155461369;5259.079661059376;5291.629585928948;5075.137226671549;5076.416212135652;5079.974600126976;5091.261767043832;5126.4617215191865;4824.855254342376;4947.876303092451;4959.280904268933;4971.229459433794;5073.568746656576;4819.228923372244;4925.482824303396;4937.975310123426;4953.331300274561;4953.405591328919;5123.6157288202785;5135.3324067870935;5136.460671629425;5138.743062846917;5144.382674079135;5026.949730502648;5028.974855125701;5036.874078148076;5042.344495215599;5082.771275292695 08:00:52 INFO - 2015-10-26 08:00:52,448 DEBUG : BROWSER_OUTPUT: |1;ext.html;531.9787208511659;535.9062164121278;543.4809756682369;547.687817943772;549.0446622876196;27834.30414239645;35177.47233791492;35386.46920296198;35448;35467.645323546756;38758;39029.60970390302;39036;39094.60905390945;39158;6756.966215168942;6855.862882742367;8055.838883222332;8227.958860205721;8229.094799572042;10404.479776011169;10596.470176491182;10700.78598428031;10713.035826775627;10729.141668666489;10266.38401695897;10336.793264134712;10347.896521034807;10415.479226038706;10437.530311136017;7116.893246601305;7273.672684729147;10095.545700443461;10325.638602648947;10637.840432393521;9860.556274967572;10054.849177262347;10056.89943100567;10057.547410366478;10057.849132263022;37697.62302376996;38000.619993799985;38068.80965595182;38179;38188.80905595482;2887.364779748452;2890.898818541362;2899.3331533747146;3040.2703351195773;3362.7141692955884;28001.9746388554;28077.85961070202;28141.57787633187;28186.718132818616;28939;2312.4681323295736;2330.4057465346395;2359;2380.190735150057;2392.4497365605835;235219;235870;236913.81543092345;237500;238200;45259.32111018337;46354.76822615865;53514.789112653365;54032;54148;61910.69044654838;62937.68531157315;63158.36841631617;63199.6840015797;63316;12478.625641230596;12660.303683297507;12680.682982925317;12697;12707.745845083047;8596.398252122417;8689.304855611535;8710.782230444289;8712.912870871336;8741.431806932529;17760.289588416577;17926.91036544835;17931.73102403465;17976.91011544934;18034.294855771477;7764.805879853049;8046.155153708776;8051.798705032421;8075.273225409624;8111.716089936942;814.2102160903913;898.7034278688018;905.9501727404925;909.3907082254773;948.7248697877538;25835.870820645778;25935.22194334173;27199.592006119925;27277.318067048483;27312.590311145348;884.8628462588309;912.9406588571721;960.1694534227843;978.2858513285341;988.5403287471361;12976.416061277265;13186.670333241555;13252.933735331262;13432.395542200624;13453.327333633279;276065;283261;283343;283449;283556;44409.555904440545;44636.776816115715;44652;44705.77647111744;44713;1150.4822829726488;1169.4620474581618;1170.0639488409408;1170.0697945133638;1211.236920739928;20999.790002099784;23685;24545.263642090766;25740.61389079128;26043.739562604133;37489.25021499609;37742.62257377501;37745.43381849329;37746.81126594349;37784.62215377921;172675;173329;173550;173905.1304743468;234727;22452.550948981254;27964.580531291638;28431.857840710665;28644.856775715987;28681.856590716914;32357.191070222492;35087.47368789521;35945;36062.639373605925;36262.36819343083;31702;31756;31776.36447271088;32534.34931301408;32672.183195420304;31295.21761955878;31379.21551961128;31562.842185789843;31713;31754.206144846565;31355.84322078375;32008.67991320057;32015.83992080025;32039.839800800848;32062;26475.205743827715;28327.57508637331;28591.42817143687;28710.712892870804;28807.423851523272;22526.436839079153;22605.547889042457;22782.31653050411;22812.65781013253;22848.543029138993 08:00:52 INFO - 2015-10-26 08:00:52,448 DEBUG : BROWSER_OUTPUT: |2;jquery.html;347.7166109620659;351.60620105481865;351.6606474751864;357.4905759293007;366.0774847384591;15448.536543903687;16191.919040404797;17245.482635520922;17662.205200765966;17700.203490842912;15206.771898421532;15391.76912346313;15482;15597;15598.922005389986;5074.72089035102;5180.2488639147305;5184.429712731597;5213.296205012312;5216;4495.033567782926;4631.907361852761;4633.1660301145785;4636.721796692209;4638;3077.461444247248;3141.9788568715257;4576.931346029813;4584.358189853423;4698.201305778026;4797;4823.9035219295765;4827.300041493982;4855.417349917997;4862.14912390332;4076.388541718736;4161.417401563784;4164.0630858057;4167.541570427251;4190.497140343178;9360.15758581731;9713.660021899199;11960.64118076459;12282.938585307105;12405.193662411913;1689.374931275436;1752.8597712182996;1773.326136068286;1784.2060283173994;1855.443366989911;9447.90552094477;9513.143817056429;9632.855507167398;9664.03359664035;9729.659461918869;1598.4005997750844;1619.295606411215;1619.3117924881878;1758.9736153957701;1841.5304097455064;53234.20148697773;54031.189532156655;54202.18696719552;54671.619331390546;55487.72256138694;6369.808905732835;6592.472602191813;6721.529492935545;6751.831204219836;6825.35159159879;8016.358691304682;8056.637451314705;8059.314958228616;8062.193780621891;8070.192980701883;4911.484294149134;4931.975340123348;4950.504949505093;4952.356193694796;4989.925151122736;4870.853874383774;4892.535209155125;4930.630202734737;4964.652474326835;4970.90058198834;3122.328699329655;3149.653538110806;3157.463231250647;3158.0683698309053;3177.3327601203546;1127.813910704741;1133.9943300283446;1139.2424038014628;1145.404389717359;1183.2841131115724;309.464626196681;398.55760106282;406.8250652219574;439.8218721417767;452.47738861614886;10713.892861071445;11160.274582152135;11161.776764464665;11320.490577924013;11336.659900203007;429.810883211386;435.1623125483495;437.1628331744737;448.39018934249395;460.42676867300423;6035.30593981689;6137.079438084323;6216.222972128484;6257.499400047986;6344.936550634435;523282;531887;535791;548658;552177;56760;57664.2335766425;70384.6480767603;71302;72258;484.8303093917101;493.045956074996;493.2084005171719;493.9654224204343;494.21173228700167;10534.683959481228;10782.08352290064;10807.837882431928;10945.616903408343;11020.228583999364;31884.362312754078;32287.51568726424;33666.663333366356;34003.14992125217;34380;43779;44392;45667.54332456633;45896.770516147204;46495;6369.172007638977;6463.062855885964;6467.3209313023335;6468.611883287018;6470.967645161744;11499.655010349703;11541.884581154081;11799;11809.173357865211;11835.408229588658;10394.272400931863;10402.531886064993;10425.582976680847;10559.155267578422;10639.14886809069;10161.745956351151;10216.693499195037;10218.489075546342;10258.692239232834;10436.478176091317;10046.447445390573;10047.648332308334;10138.239632027775;10164.237682173718;10308.536115874953;6322.747090116344;6323.304436511888;6350.650714210763;6351.079093531504;6359;10156.695299141038;10389;10406.323588966692;10457.058864701909;10582.682519524427 08:00:52 INFO - 2015-10-26 08:00:52,449 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1326.0518729108685;1457.103881113115;1677.5302915183747;1708.6411853510763;1880.5204672808431;7140.785776426709;7941;8131.878021829677;8146;8152.510849349043;8423.073461919184;8525.104863989269;8547.383973405469;8551.572421378938;8651.091635378289;2142.228797632855;2160.459885028743;2164.3398763377163;2174.5433458973635;2179.8474106812528;2149.7312835895514;2166.599179151854;2255.560165767676;2259.0850705464313;2263.4680850000236;2132.722746043012;2146.967795483069;2162.8810415427224;2179.4769255378756;2181.3237896252213;2103.0220947259445;2108.609907167164;2120.5016821047043;2124.915003399862;2125.0968338456173;2047.0890453748088;2078.4284321811624;2083.374987503743;2087.4363921741046;2088.530080731823;7091.787246382617;7299.4890357674985;8128.878066829002;8167.264946154815;8190.221928916743;4529.84145554904;4643.698159619614;4650.023495066041;4655.162070827249;4659.301104834268;2968.079895232485;2988.7907846450757;2993.5509673548922;3012.774041946841;3079.13784140441;667.9699413526355;837.1001173738256;847.161310302802;851.2764150472111;890.6125835261618;12005.45975431099;12026.639200823989;12056.095792815486;12077.27536347822;12080;4487.461504619467;4488.259437192892;4545.113702827983;4603.6777425579885;4624.375709279271;10531.52608132636;10642.680719578426;10679.412632305297;10683.358998460117;10685.62600309001;4765.309030190603;4765.404324459442;4765.642576806684;4774.236122220428;4790.952090479051;4270.658347332206;4277.657787377065;4278.828846846153;4289.227938971015;4294.055307832272;3832.693384529287;3860.517435320585;3866.168773713665;3873.283442563135;3916.276838191187;1226.4113225651738;1232.0944106082022;1232.2606436138246;1237.220551052849;1249.86876377979;1193.3257709394165;1207.2575366149754;1210.9878901211052;1212.15755050241;1229.3853073463267;7819.100803407568;7843.450958432855;7859.371250299962;7868.567228802471;7902.392210570613;464.43339126265903;468.76795985986473;473.5335694341071;475.52685078347264;482.80687724909615;5951.9702401488585;5999.310079340845;6006.309274433496;6008.939910600925;6017.939820601738;9814.852777208347;10441.112505436971;10929.234953553334;10951.890481095246;10955.397453140155;9866.112049915399;10562.788744225227;10868.565257389773;10884.836727449094;10914.563417463212;8006.519608823489;8013.821384422074;8020.598970051591;8098.797530061678;8120.796980075427;7876.84246315082;7880.093789214085;7897.644605992629;7900.209979002054;7903.644336005009;9211.493367864563;9221.815563688822;9495.477748723886;9497.097775711296;9509.66716164931;9535.23718102564;9624.903750962681;9658.468784216935;9666.95166524163;9687.564059617192;7669.578173200527;7721.189275126031;7731.188225236494;7751.689932402641;7755.7673269802;7719.382449404146;8220.917790822015;8229.670813167406;8304.335653147613;8351.95824020876;7550.7734767957045;7596.354309883722;7774.533527988338;7803.648835802516;7855.2930236276725;7647.235276472308;7649.694012239448;7673.34776544;7711.994881330782;7839.176886427072;7469.215732348025;7490.775276741707;7532.058492688414;7614.961925190339;7676.577788221478;7020.824479388056;7036.226015138228;7036.648167591702;7039.296070392919;7041.5775053496955;6856.41720453767;6905.413039891666;6920.480963927826;6921.861562768817;6958.234594194736 08:00:52 INFO - 2015-10-26 08:00:52,453 DEBUG : BROWSER_OUTPUT: |4;prototype.html;300.87363307410885;307.9260977365433;312.7232399326595;313.41704429760654;314.66173863097174;5013.974930125349;5073.315102461167;5130.692158470494;5131.230315452679;5132.717700526469;4989.0271397077495;5051.898962020758;5054.418741844689;5054.747262636872;5055.115354812911;3672.045268230266;3741;3909.1790723948006;3951.5455722591983;3969.2656858481273;3843.730938834283;3891.805409729516;3917.84328626856;3918.196769662227;3922.1567363016948;3867.9613203868025;3868.70984676149;3872.9031774205587;3872.961270387288;3873.5932727063673;3686.373316536182;3750.099976005739;3815.1988082502708;3870.980645096771;3880.262750077476;3381.0195043437375;3386.4243078676686;3387.627360990289;3394.0496660934978;3398.558187435642;4839.685420447695;4841.564259216688;4841.757912104381;4841.8063277468855;4843.733594652293;4345.087531618332;4346.087321662455;4352.89117772055;4353.390525326457;4356.4336636237085;5060.98780243949;5161.535461808456;5169.353830771153;5171.276021357013;5174.896502069938;3526.1713497328105;3527.2240107176635;3527.523784289114;3528.8588456461966;3531.7174626030114;5345.946540534623;5421.620486565942;5422.9728851355885;5423.186522021729;5424.783008679688;3736.290104880064;3738.532683414573;3743.0268130285776;3744.344739670547;3745.9250814983548;3791.7724936503896;3793.7723736575895;3796.8101594920145;3815.73289869712;3830.80845957701;4280.400743895857;4369.344598310216;4374.321980093091;4374.956250437455;4375.256206444912;5178.326817513699;5183.092958732283;5184.611154163453;5184.7926082956255;5186.5850731941355;1358.660334916271;1372.2521225931891;1372.6019454358125;1374.9518766843112;1375.3742047368423;917.9724608261763;927.2535608834953;951.7573018880141;953.7663272498282;954.746992047108;4636.884077898012;4638.5593368629925;4639.350490931272;4639.814407423665;4643.048175124091;5393.029254734107;5393.7303134843105;5395.217693434509;5395.757190926418;5396.946030539723;4625.606823420047;4638.165130276582;4639;4639.0721855628335;4643.094596553708;2741.5272669751366;2836.9858150709115;2922.108756829145;2950.070727720761;2951.5572664100127;5046.142155833517;5049.646524743306;5051.823186188466;5052.747362631927;5055.443901170868;5434.157705555566;5529.30883639545;5530.364008139035;5530.778768849282;5535.142052981713;5085.440601533828;5106.617003724809;5111.897762044664;5120.257562653394;5133.307003554547;5276.34045744282;5341.065313570188;5342.866428339323;5409.053415652166;5427.6200665953;5327.387350454747;5328.040952628564;5332.706701131475;5335.1730481775385;5337.653052551572;5170.689758614495;5176.4047134578495;5178.71517066565;5180.507851754077;5183.81856635016;3889.124946886928;3895.3183192940555;3896.844126235032;3900.5124359455067;3903.3754599265117;5248.921266181087;5403.459654034565;5419.539339156058;5424.5660347172925;5426.701531415652;5239.685618862881;5244.213367994831;5245.554127899171;5245.790168393374;5248.790048398021;5223.660462069954;5242.501962313574;5249.186376111708;5249.89500209986;5250.291210686509;5086.593072554113;5130.102232109441;5133.4096578893905;5135.974320128525;5140.768665409991;5129.563987061141;5260.894782104413;5274.736263186748;5275.657082289639;5280.683159010472;5086.898262034812;5092.2361645753435;5095.363079615048;5118.669146021986;5144.819931302386 08:00:52 INFO - 2015-10-26 08:00:52,454 DEBUG : BROWSER_OUTPUT: |5;yui.html;703.852191039882;725.6625669063884;766.7891329884282;790.6046976511744;798.8881556582076;10914.781704365909;10958.890411095887;11309.886901130987;11311.773764524705;11320.60377886774;10386.532606032728;10461.424621645807;10478.371297722142;10479.947600262007;10480.213983951196;625.0312016374623;635.9936400636004;805.2792750488295;811.9472234304781;822.6627082896014;817.6320655704957;819.7581713394533;826.557791581504;827.3670641958906;827.9047909490396;854.7692123126752;863.6027427383411;863.7451951674271;864.2308345572445;864.6195673903462;643.4627086382852;654.8919428294349;843.2705709561246;856.567433446112;857.6097875466647;637.1780403279763;637.4995628431659;637.8692368064536;638.3488841381765;638.409471239102;10396.532156052996;10812.405317707522;10848.240623156382;10930.672079837617;10943.726406839813;9749.31754777166;10191.337563058452;10229.590816367336;10454.647704266556;10519.053285204369;8480.363972702073;8509;8559.358048146414;8575.656973721043;8577.742667719978;2952.3209661777696;2991.1325715542466;3017.698230176965;3018.4868572342752;3035.8026728262594;31780.523292150636;31787.364252714815;31857.681423185935;31906.042818715476;32016.359672806648;8000.599970001476;8033.357331413473;8212.260896519341;8240.258376746122;8397;10413.739656508647;10554.577816887391;14846.554603361916;14856;14861.92569037148;5125.026245013435;5171.948280517222;5204.609654275944;5238.685678859204;5290.994711004897;3376.03782921871;3407.216340241758;3419.2990436960363;3421.2131209821882;3433.7939723616664;2392.2344849648293;2419.0565679385054;2421.6609674645565;2422.515496900627;2425.1754403502896;393.1311800919999;406.0376906731007;408.691437964341;408.8773367989591;416.4877201042734;412.1221797571214;418.84293389978757;419.73976134796624;546.1889094694376;565.4882331489925;6196.690165491707;6392.607581813999;6499.772507962293;6508.772192973223;6572.572782769104;370.5201763715994;428.6378010581054;490.49969031588296;534.5349545895097;549.7773401772289;3114.2525793809323;3140.874365025419;3186.330870517171;3194.8402579870913;3224.322892192619;40764.59235407608;40786.38820417696;41020.79489602533;41072.38391424131;41156.1768764629;34715.13212169716;35318.82340588281;35443.4683479748;35650.64349356525;35712.642873570934;30039.699603004126;30065.549016764766;30328.696713033027;30339.089827305215;30383.088507344815;5951.642901425928;6155.107509411021;6242.5942313751275;6260.843478913063;6287.2140982377205;21296.25463108784;21442.678359824302;21444;21457.678134827678;23233.303000909324;29687.25781855471;29705.10884673463;29774.404511909208;29914.551281731226;29924.102276931728;6333.429991300805;6370.74517019314;6436;6466.676666166767;6526.24948130971;8950.373473856767;9155.084491550791;9205.401648892801;9214.36944902553;9580.90419095828;8428.199321064489;8530.274926631306;8548.102449242988;8548.487090774574;8563.785905352415;8069.435139540175;8069.475484093515;8085.5957202140835;8233.752987410388;8479.788005299917;7977.122516523294;8235.629396677044;8292.212239837447;8523.531205783493;8553.529555874487;7412.518186317872;7465.06686664167;7575.469717119749;7578.734297791219;7638.9956888641145;7193.172785129777;7426.405887529093;7457.627118643937;7463.216362282099;7497.587632680255 08:00:52 INFO - 2015-10-26 08:00:52,454 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:00:52 INFO - 2015-10-26 08:00:52,454 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:00:52 INFO - 2015-10-26 08:00:52,454 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,979 08:00:52 INFO - 2015-10-26 08:00:52,454 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:00:52 INFO - 2015-10-26 08:00:52,454 DEBUG : BROWSER_OUTPUT: __startTimestamp1445871652440__endTimestamp 08:00:52 INFO - 2015-10-26 08:00:52,454 DEBUG : BROWSER_OUTPUT: 08:00:52 INFO - 2015-10-26 08:00:52,456 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:00:52 INFO - 2015-10-26 08:00:52,456 DEBUG : BROWSER_OUTPUT: Number of tests: 6 08:00:52 INFO - 2015-10-26 08:00:52,456 DEBUG : BROWSER_OUTPUT: 08:00:52 INFO - 2015-10-26 08:00:52,456 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4804.89 Median:4949.60 stddev:510.63 (10.3%) stddev-sans-first:484.07 08:00:52 INFO - 2015-10-26 08:00:52,456 DEBUG : BROWSER_OUTPUT: Values: 2674.2 2765.5 2785.9 2799.6 2810.1 4802.2 4915.0 4940.1 5008.6 5019.9 4862.0 4877.9 4934.3 4954.3 4964.9 4511.8 4531.3 4538.7 4555.9 4615.3 4404.4 4567.1 4576.9 4581.2 4619.9 4399.7 4477.6 4526.3 4543.0 4556.9 4505.1 4508.1 4513.8 4529.1 4577.2 4369.5 4475.5 4479.8 4492.5 4531.2 4721.3 4753.4 4805.2 4825.3 4867.9 5177.0 5206.9 5250.4 5251.2 5255.3 5069.5 5219.6 5235.2 5237.3 5238.9 4729.9 4799.4 4810.4 4813.8 4857.5 5326.6 5329.1 5333.8 5364.7 5377.4 4924.6 5032.0 5120.0 5121.7 5127.8 5160.9 5164.3 5319.7 5320.8 5324.0 5190.1 5192.9 5194.6 5207.5 5223.8 5161.8 5176.9 5190.3 5196.6 5229.7 4109.4 4133.4 4231.0 4234.6 4239.4 3712.4 3723.6 3742.6 3776.6 3786.9 4496.3 4499.1 4499.7 4527.0 4534.7 5000.1 5110.3 5115.8 5134.0 5267.4 4444.0 4454.9 4474.1 4500.2 4503.5 4578.5 4582.8 4584.1 4594.7 4620.7 4736.6 4841.4 4854.9 4859.6 4940.6 5267.0 5280.1 5283.1 5286.7 5299.9 4990.7 4993.7 4993.9 4995.2 5048.2 4949.6 5063.0 5093.1 5100.5 5205.3 5234.0 5234.9 5240.4 5259.1 5291.6 5075.1 5076.4 5080.0 5091.3 5126.5 4824.9 4947.9 4959.3 4971.2 5073.6 4819.2 4925.5 4938.0 4953.3 4953.4 5123.6 5135.3 5136.5 5138.7 5144.4 5026.9 5029.0 5036.9 5042.3 5082.8 08:00:52 INFO - 2015-10-26 08:00:52,456 DEBUG : BROWSER_OUTPUT: 08:00:52 INFO - 2015-10-26 08:00:52,456 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:39340.98 Median:2969.80 stddev:62240.54 (2095.8%) stddev-sans-first:62346.93 08:00:52 INFO - 2015-10-26 08:00:52,457 DEBUG : BROWSER_OUTPUT: Values: 532.0 535.9 543.5 547.7 549.0 27834.3 35177.5 35386.5 35448.0 35467.6 38758.0 39029.6 39036.0 39094.6 39158.0 6757.0 6855.9 8055.8 8228.0 8229.1 10404.5 10596.5 10700.8 10713.0 10729.1 10266.4 10336.8 10347.9 10415.5 10437.5 7116.9 7273.7 10095.5 10325.6 10637.8 9860.6 10054.8 10056.9 10057.5 10057.8 37697.6 38000.6 38068.8 38179.0 38188.8 2887.4 2890.9 2899.3 3040.3 3362.7 28002.0 28077.9 28141.6 28186.7 28939.0 2312.5 2330.4 2359.0 2380.2 2392.4 235219.0 235870.0 236913.8 237500.0 238200.0 45259.3 46354.8 53514.8 54032.0 54148.0 61910.7 62937.7 63158.4 63199.7 63316.0 12478.6 12660.3 12680.7 12697.0 12707.7 8596.4 8689.3 8710.8 8712.9 8741.4 17760.3 17926.9 17931.7 17976.9 18034.3 7764.8 8046.2 8051.8 8075.3 8111.7 814.2 898.7 906.0 909.4 948.7 25835.9 25935.2 27199.6 27277.3 27312.6 884.9 912.9 960.2 978.3 988.5 12976.4 13186.7 13252.9 13432.4 13453.3 276065.0 283261.0 283343.0 283449.0 283556.0 44409.6 44636.8 44652.0 44705.8 44713.0 1150.5 1169.5 1170.1 1170.1 1211.2 20999.8 23685.0 24545.3 25740.6 26043.7 37489.3 37742.6 37745.4 37746.8 37784.6 172675.0 173329.0 173550.0 173905.1 234727.0 22452.6 27964.6 28431.9 28644.9 28681.9 32357.2 35087.5 35945.0 36062.6 36262.4 31702.0 31756.0 31776.4 32534.3 32672.2 31295.2 31379.2 31562.8 31713.0 31754.2 31355.8 32008.7 32015.8 32039.8 32062.0 26475.2 28327.6 28591.4 28710.7 28807.4 22526.4 22605.5 22782.3 22812.7 22848.5 08:00:52 INFO - 2015-10-26 08:00:52,457 DEBUG : BROWSER_OUTPUT: 08:00:52 INFO - 2015-10-26 08:00:52,457 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26128.51 Median:22108.08 stddev:88086.80 (398.4%) stddev-sans-first:88312.63 08:00:52 INFO - 2015-10-26 08:00:52,457 DEBUG : BROWSER_OUTPUT: Values: 347.7 351.6 351.7 357.5 366.1 15448.5 16191.9 17245.5 17662.2 17700.2 15206.8 15391.8 15482.0 15597.0 15598.9 5074.7 5180.2 5184.4 5213.3 5216.0 4495.0 4631.9 4633.2 4636.7 4638.0 3077.5 3142.0 4576.9 4584.4 4698.2 4797.0 4823.9 4827.3 4855.4 4862.1 4076.4 4161.4 4164.1 4167.5 4190.5 9360.2 9713.7 11960.6 12282.9 12405.2 1689.4 1752.9 1773.3 1784.2 1855.4 9447.9 9513.1 9632.9 9664.0 9729.7 1598.4 1619.3 1619.3 1759.0 1841.5 53234.2 54031.2 54202.2 54671.6 55487.7 6369.8 6592.5 6721.5 6751.8 6825.4 8016.4 8056.6 8059.3 8062.2 8070.2 4911.5 4932.0 4950.5 4952.4 4989.9 4870.9 4892.5 4930.6 4964.7 4970.9 3122.3 3149.7 3157.5 3158.1 3177.3 1127.8 1134.0 1139.2 1145.4 1183.3 309.5 398.6 406.8 439.8 452.5 10713.9 11160.3 11161.8 11320.5 11336.7 429.8 435.2 437.2 448.4 460.4 6035.3 6137.1 6216.2 6257.5 6344.9 523282.0 531887.0 535791.0 548658.0 552177.0 56760.0 57664.2 70384.6 71302.0 72258.0 484.8 493.0 493.2 494.0 494.2 10534.7 10782.1 10807.8 10945.6 11020.2 31884.4 32287.5 33666.7 34003.1 34380.0 43779.0 44392.0 45667.5 45896.8 46495.0 6369.2 6463.1 6467.3 6468.6 6471.0 11499.7 11541.9 11799.0 11809.2 11835.4 10394.3 10402.5 10425.6 10559.2 10639.1 10161.7 10216.7 10218.5 10258.7 10436.5 10046.4 10047.6 10138.2 10164.2 10308.5 6322.7 6323.3 6350.7 6351.1 6359.0 10156.7 10389.0 10406.3 10457.1 10582.7 08:00:52 INFO - 2015-10-26 08:00:52,458 DEBUG : BROWSER_OUTPUT: 08:00:52 INFO - 2015-10-26 08:00:52,458 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5860.58 Median:4782.59 stddev:3285.70 (68.7%) stddev-sans-first:3277.24 08:00:52 INFO - 2015-10-26 08:00:52,458 DEBUG : BROWSER_OUTPUT: Values: 1326.1 1457.1 1677.5 1708.6 1880.5 7140.8 7941.0 8131.9 8146.0 8152.5 8423.1 8525.1 8547.4 8551.6 8651.1 2142.2 2160.5 2164.3 2174.5 2179.8 2149.7 2166.6 2255.6 2259.1 2263.5 2132.7 2147.0 2162.9 2179.5 2181.3 2103.0 2108.6 2120.5 2124.9 2125.1 2047.1 2078.4 2083.4 2087.4 2088.5 7091.8 7299.5 8128.9 8167.3 8190.2 4529.8 4643.7 4650.0 4655.2 4659.3 2968.1 2988.8 2993.6 3012.8 3079.1 668.0 837.1 847.2 851.3 890.6 12005.5 12026.6 12056.1 12077.3 12080.0 4487.5 4488.3 4545.1 4603.7 4624.4 10531.5 10642.7 10679.4 10683.4 10685.6 4765.3 4765.4 4765.6 4774.2 4791.0 4270.7 4277.7 4278.8 4289.2 4294.1 3832.7 3860.5 3866.2 3873.3 3916.3 1226.4 1232.1 1232.3 1237.2 1249.9 1193.3 1207.3 1211.0 1212.2 1229.4 7819.1 7843.5 7859.4 7868.6 7902.4 464.4 468.8 473.5 475.5 482.8 5952.0 5999.3 6006.3 6008.9 6017.9 9814.9 10441.1 10929.2 10951.9 10955.4 9866.1 10562.8 10868.6 10884.8 10914.6 8006.5 8013.8 8020.6 8098.8 8120.8 7876.8 7880.1 7897.6 7900.2 7903.6 9211.5 9221.8 9495.5 9497.1 9509.7 9535.2 9624.9 9658.5 9667.0 9687.6 7669.6 7721.2 7731.2 7751.7 7755.8 7719.4 8220.9 8229.7 8304.3 8352.0 7550.8 7596.4 7774.5 7803.6 7855.3 7647.2 7649.7 7673.3 7712.0 7839.2 7469.2 7490.8 7532.1 7615.0 7676.6 7020.8 7036.2 7036.6 7039.3 7041.6 6856.4 6905.4 6920.5 6921.9 6958.2 08:00:52 INFO - 2015-10-26 08:00:52,458 DEBUG : BROWSER_OUTPUT: 08:00:52 INFO - 2015-10-26 08:00:52,458 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4340.91 Median:5052.32 stddev:1264.00 (25.0%) stddev-sans-first:1230.64 08:00:52 INFO - 2015-10-26 08:00:52,459 DEBUG : BROWSER_OUTPUT: Values: 300.9 307.9 312.7 313.4 314.7 5014.0 5073.3 5130.7 5131.2 5132.7 4989.0 5051.9 5054.4 5054.7 5055.1 3672.0 3741.0 3909.2 3951.5 3969.3 3843.7 3891.8 3917.8 3918.2 3922.2 3868.0 3868.7 3872.9 3873.0 3873.6 3686.4 3750.1 3815.2 3871.0 3880.3 3381.0 3386.4 3387.6 3394.0 3398.6 4839.7 4841.6 4841.8 4841.8 4843.7 4345.1 4346.1 4352.9 4353.4 4356.4 5061.0 5161.5 5169.4 5171.3 5174.9 3526.2 3527.2 3527.5 3528.9 3531.7 5345.9 5421.6 5423.0 5423.2 5424.8 3736.3 3738.5 3743.0 3744.3 3745.9 3791.8 3793.8 3796.8 3815.7 3830.8 4280.4 4369.3 4374.3 4375.0 4375.3 5178.3 5183.1 5184.6 5184.8 5186.6 1358.7 1372.3 1372.6 1375.0 1375.4 918.0 927.3 951.8 953.8 954.7 4636.9 4638.6 4639.4 4639.8 4643.0 5393.0 5393.7 5395.2 5395.8 5396.9 4625.6 4638.2 4639.0 4639.1 4643.1 2741.5 2837.0 2922.1 2950.1 2951.6 5046.1 5049.6 5051.8 5052.7 5055.4 5434.2 5529.3 5530.4 5530.8 5535.1 5085.4 5106.6 5111.9 5120.3 5133.3 5276.3 5341.1 5342.9 5409.1 5427.6 5327.4 5328.0 5332.7 5335.2 5337.7 5170.7 5176.4 5178.7 5180.5 5183.8 3889.1 3895.3 3896.8 3900.5 3903.4 5248.9 5403.5 5419.5 5424.6 5426.7 5239.7 5244.2 5245.6 5245.8 5248.8 5223.7 5242.5 5249.2 5249.9 5250.3 5086.6 5130.1 5133.4 5136.0 5140.8 5129.6 5260.9 5274.7 5275.7 5280.7 5086.9 5092.2 5095.4 5118.7 5144.8 08:00:52 INFO - 2015-10-26 08:00:52,459 DEBUG : BROWSER_OUTPUT: 08:00:52 INFO - 2015-10-26 08:00:52,459 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:9829.18 Median:540.36 stddev:10713.19 (1982.6%) stddev-sans-first:10721.33 08:00:52 INFO - 2015-10-26 08:00:52,459 DEBUG : BROWSER_OUTPUT: Values: 703.9 725.7 766.8 790.6 798.9 10914.8 10958.9 11309.9 11311.8 11320.6 10386.5 10461.4 10478.4 10479.9 10480.2 625.0 636.0 805.3 811.9 822.7 817.6 819.8 826.6 827.4 827.9 854.8 863.6 863.7 864.2 864.6 643.5 654.9 843.3 856.6 857.6 637.2 637.5 637.9 638.3 638.4 10396.5 10812.4 10848.2 10930.7 10943.7 9749.3 10191.3 10229.6 10454.6 10519.1 8480.4 8509.0 8559.4 8575.7 8577.7 2952.3 2991.1 3017.7 3018.5 3035.8 31780.5 31787.4 31857.7 31906.0 32016.4 8000.6 8033.4 8212.3 8240.3 8397.0 10413.7 10554.6 14846.6 14856.0 14861.9 5125.0 5171.9 5204.6 5238.7 5291.0 3376.0 3407.2 3419.3 3421.2 3433.8 2392.2 2419.1 2421.7 2422.5 2425.2 393.1 406.0 408.7 408.9 416.5 412.1 418.8 419.7 546.2 565.5 6196.7 6392.6 6499.8 6508.8 6572.6 370.5 428.6 490.5 534.5 549.8 3114.3 3140.9 3186.3 3194.8 3224.3 40764.6 40786.4 41020.8 41072.4 41156.2 34715.1 35318.8 35443.5 35650.6 35712.6 30039.7 30065.5 30328.7 30339.1 30383.1 5951.6 6155.1 6242.6 6260.8 6287.2 21296.3 21442.7 21444.0 21457.7 23233.3 29687.3 29705.1 29774.4 29914.6 29924.1 6333.4 6370.7 6436.0 6466.7 6526.2 8950.4 9155.1 9205.4 9214.4 9580.9 8428.2 8530.3 8548.1 8548.5 8563.8 8069.4 8069.5 8085.6 8233.8 8479.8 7977.1 8235.6 8292.2 8523.5 8553.5 7412.5 7465.1 7575.5 7578.7 7639.0 7193.2 7426.4 7457.6 7463.2 7497.6 08:00:52 INFO - 2015-10-26 08:00:52,459 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:00:52 INFO - 2015-10-26 08:00:52,459 DEBUG : BROWSER_OUTPUT: 08:00:53 INFO - 2015-10-26 08:00:53,189 INFO : Browser exited with error code: 0 08:00:53 INFO - 2015-10-26 08:00:53,206 INFO : Completed test dromaeo_css (00:18:39) 08:00:53 INFO - 2015-10-26 08:00:53,206 INFO : Starting test kraken 08:00:53 INFO - 2015-10-26 08:00:53,206 DEBUG : operating with platform_type : linux_ 08:00:53 INFO - 2015-10-26 08:00:53,206 INFO : Initialising browser for kraken test... 08:00:53 INFO - 2015-10-26 08:00:53,212 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmSkqk5/profile http://localhost:41319/getInfo.html 08:00:57 INFO - 2015-10-26 08:00:57,346 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:00:57 INFO - 2015-10-26 08:00:57,347 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:00:57 INFO - 2015-10-26 08:00:57,347 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:00:57 INFO - 2015-10-26 08:00:57,347 DEBUG : BROWSER_OUTPUT: __metrics 08:00:57 INFO - 2015-10-26 08:00:57,484 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:00:58 INFO - 2015-10-26 08:00:58,631 INFO : Browser initialized. 08:00:58 INFO - 2015-10-26 08:00:58,632 INFO : Running cycle 1/1 for kraken test... 08:00:58 INFO - 2015-10-26 08:00:58,632 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmSkqk5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:01:10 INFO - 2015-10-26 08:01:10,619 DEBUG : BROWSER_OUTPUT: RSS: Main: 141516800 08:01:10 INFO - 2015-10-26 08:01:10,619 DEBUG : BROWSER_OUTPUT: 08:01:13 INFO - 2015-10-26 08:01:13,260 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41319/tests/kraken/driver.html?testName=audio-beat-detection) 08:01:13 INFO - 2015-10-26 08:01:13,842 DEBUG : BROWSER_OUTPUT: RSS: Main: 176852992 08:01:13 INFO - 2015-10-26 08:01:13,842 DEBUG : BROWSER_OUTPUT: 08:01:16 INFO - 2015-10-26 08:01:16,438 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41319/tests/kraken/driver.html?testName=audio-dft) 08:01:17 INFO - 2015-10-26 08:01:17,007 DEBUG : BROWSER_OUTPUT: RSS: Main: 169484288 08:01:17 INFO - 2015-10-26 08:01:17,007 DEBUG : BROWSER_OUTPUT: 08:01:20 INFO - 2015-10-26 08:01:20,029 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41319/tests/kraken/driver.html?testName=audio-fft) 08:01:20 INFO - 2015-10-26 08:01:20,595 DEBUG : BROWSER_OUTPUT: RSS: Main: 153825280 08:01:20 INFO - 2015-10-26 08:01:20,595 DEBUG : BROWSER_OUTPUT: 08:01:22 INFO - 2015-10-26 08:01:22,528 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41319/tests/kraken/driver.html?testName=audio-oscillator) 08:01:23 INFO - 2015-10-26 08:01:23,095 DEBUG : BROWSER_OUTPUT: RSS: Main: 155852800 08:01:23 INFO - 2015-10-26 08:01:23,095 DEBUG : BROWSER_OUTPUT: 08:01:25 INFO - 2015-10-26 08:01:25,329 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41319/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:01:25 INFO - 2015-10-26 08:01:25,894 DEBUG : BROWSER_OUTPUT: RSS: Main: 158527488 08:01:25 INFO - 2015-10-26 08:01:25,895 DEBUG : BROWSER_OUTPUT: 08:01:28 INFO - 2015-10-26 08:01:28,855 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41319/tests/kraken/driver.html?testName=imaging-darkroom) 08:01:29 INFO - 2015-10-26 08:01:29,420 DEBUG : BROWSER_OUTPUT: RSS: Main: 154427392 08:01:29 INFO - 2015-10-26 08:01:29,421 DEBUG : BROWSER_OUTPUT: 08:01:32 INFO - 2015-10-26 08:01:32,844 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41319/tests/kraken/driver.html?testName=imaging-desaturate) 08:01:33 INFO - 2015-10-26 08:01:33,410 DEBUG : BROWSER_OUTPUT: RSS: Main: 156102656 08:01:33 INFO - 2015-10-26 08:01:33,410 DEBUG : BROWSER_OUTPUT: 08:01:36 INFO - 2015-10-26 08:01:36,254 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41319/tests/kraken/driver.html?testName=json-parse-financial) 08:01:36 INFO - 2015-10-26 08:01:36,828 DEBUG : BROWSER_OUTPUT: RSS: Main: 155635712 08:01:36 INFO - 2015-10-26 08:01:36,829 DEBUG : BROWSER_OUTPUT: 08:01:38 INFO - 2015-10-26 08:01:38,563 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41319/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:01:39 INFO - 2015-10-26 08:01:39,124 DEBUG : BROWSER_OUTPUT: RSS: Main: 163364864 08:01:39 INFO - 2015-10-26 08:01:39,124 DEBUG : BROWSER_OUTPUT: 08:01:41 INFO - 2015-10-26 08:01:41,057 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41319/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:01:41 INFO - 2015-10-26 08:01:41,638 DEBUG : BROWSER_OUTPUT: RSS: Main: 168185856 08:01:41 INFO - 2015-10-26 08:01:41,638 DEBUG : BROWSER_OUTPUT: 08:01:43 INFO - 2015-10-26 08:01:43,771 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41319/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:01:44 INFO - 2015-10-26 08:01:44,340 DEBUG : BROWSER_OUTPUT: RSS: Main: 176001024 08:01:44 INFO - 2015-10-26 08:01:44,340 DEBUG : BROWSER_OUTPUT: 08:01:46 INFO - 2015-10-26 08:01:46,861 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41319/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:01:47 INFO - 2015-10-26 08:01:47,447 DEBUG : BROWSER_OUTPUT: RSS: Main: 181506048 08:01:47 INFO - 2015-10-26 08:01:47,447 DEBUG : BROWSER_OUTPUT: 08:01:49 INFO - 2015-10-26 08:01:49,975 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41319/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:01:50 INFO - 2015-10-26 08:01:50,557 DEBUG : BROWSER_OUTPUT: RSS: Main: 181174272 08:01:50 INFO - 2015-10-26 08:01:50,557 DEBUG : BROWSER_OUTPUT: 08:01:52 INFO - 2015-10-26 08:01:52,347 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41319/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41319/tests/kraken/driver.html?testName=ai-astar) 08:01:52 INFO - 2015-10-26 08:01:52,593 DEBUG : BROWSER_OUTPUT: RSS: Main: 233672704 08:01:52 INFO - 2015-10-26 08:01:52,593 DEBUG : BROWSER_OUTPUT: 08:01:52 INFO - 2015-10-26 08:01:52,597 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:01:52 INFO - 2015-10-26 08:01:52,597 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:01:52 INFO - 2015-10-26 08:01:52,597 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:01:52 INFO - 2015-10-26 08:01:52,597 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:01:52 INFO - 2015-10-26 08:01:52,597 DEBUG : BROWSER_OUTPUT: |0;ai-astar;112;112;106;113;111;114;104;112;107;113 08:01:52 INFO - 2015-10-26 08:01:52,598 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;131;131;139;147;130;131;131;129;147;129 08:01:52 INFO - 2015-10-26 08:01:52,598 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;158;160;161;161;160;159;160;159;159 08:01:52 INFO - 2015-10-26 08:01:52,598 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;71;73;80;71;71;71;73;82;72 08:01:52 INFO - 2015-10-26 08:01:52,598 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;94;139;93;94;94;127;93;94;129 08:01:52 INFO - 2015-10-26 08:01:52,599 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;110;110;110;109;110;110;110 08:01:52 INFO - 2015-10-26 08:01:52,599 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;148;148;147;148;148;148;148;147;148 08:01:52 INFO - 2015-10-26 08:01:52,599 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;96;96;96;96;96;96;96 08:01:52 INFO - 2015-10-26 08:01:52,599 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;68;69;68;68;70;68;68;77;65;67 08:01:52 INFO - 2015-10-26 08:01:52,599 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;71;70;71;70;72;70;69;69;70;87 08:01:52 INFO - 2015-10-26 08:01:52,599 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;81;85;81;82;84;83;84;82;83;84 08:01:52 INFO - 2015-10-26 08:01:52,599 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;125;126;120;123;139;119;118;118;124;118 08:01:52 INFO - 2015-10-26 08:01:52,600 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;143;149;137;137;130;142;138;140;143 08:01:52 INFO - 2015-10-26 08:01:52,600 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;71;72;72;71;72;72;72;69;71;70 08:01:52 INFO - 2015-10-26 08:01:52,600 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:01:52 INFO - 2015-10-26 08:01:52,600 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:01:52 INFO - 2015-10-26 08:01:52,600 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,911 08:01:52 INFO - 2015-10-26 08:01:52,600 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:01:52 INFO - 2015-10-26 08:01:52,600 DEBUG : BROWSER_OUTPUT: __startTimestamp1445871712595__endTimestamp 08:01:52 INFO - 2015-10-26 08:01:52,601 DEBUG : BROWSER_OUTPUT: 08:01:52 INFO - 2015-10-26 08:01:52,608 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:01:52 INFO - 2015-10-26 08:01:52,608 DEBUG : BROWSER_OUTPUT: Number of tests: 14 08:01:52 INFO - 2015-10-26 08:01:52,608 DEBUG : BROWSER_OUTPUT: 08:01:52 INFO - 2015-10-26 08:01:52,609 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:110.40 Median:112.00 stddev:3.44 (3.1%) stddev-sans-first:3.60 08:01:52 INFO - 2015-10-26 08:01:52,609 DEBUG : BROWSER_OUTPUT: Values: 112.0 112.0 106.0 113.0 111.0 114.0 104.0 112.0 107.0 113.0 08:01:52 INFO - 2015-10-26 08:01:52,609 DEBUG : BROWSER_OUTPUT: 08:01:52 INFO - 2015-10-26 08:01:52,609 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:134.50 Median:131.00 stddev:7.17 (5.5%) stddev-sans-first:7.49 08:01:52 INFO - 2015-10-26 08:01:52,609 DEBUG : BROWSER_OUTPUT: Values: 131.0 131.0 139.0 147.0 130.0 131.0 131.0 129.0 147.0 129.0 08:01:52 INFO - 2015-10-26 08:01:52,609 DEBUG : BROWSER_OUTPUT: 08:01:52 INFO - 2015-10-26 08:01:52,609 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.70 Median:160.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 08:01:52 INFO - 2015-10-26 08:01:52,609 DEBUG : BROWSER_OUTPUT: Values: 160.0 158.0 160.0 161.0 161.0 160.0 159.0 160.0 159.0 159.0 08:01:52 INFO - 2015-10-26 08:01:52,609 DEBUG : BROWSER_OUTPUT: 08:01:52 INFO - 2015-10-26 08:01:52,609 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:73.70 Median:73.00 stddev:3.97 (5.4%) stddev-sans-first:4.21 08:01:52 INFO - 2015-10-26 08:01:52,609 DEBUG : BROWSER_OUTPUT: Values: 73.0 71.0 73.0 80.0 71.0 71.0 71.0 73.0 82.0 72.0 08:01:52 INFO - 2015-10-26 08:01:52,610 DEBUG : BROWSER_OUTPUT: 08:01:52 INFO - 2015-10-26 08:01:52,610 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.20 Median:94.00 stddev:18.52 (19.7%) stddev-sans-first:19.27 08:01:52 INFO - 2015-10-26 08:01:52,610 DEBUG : BROWSER_OUTPUT: Values: 95.0 94.0 139.0 93.0 94.0 94.0 127.0 93.0 94.0 129.0 08:01:52 INFO - 2015-10-26 08:01:52,610 DEBUG : BROWSER_OUTPUT: 08:01:52 INFO - 2015-10-26 08:01:52,610 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.90 Median:110.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 08:01:52 INFO - 2015-10-26 08:01:52,610 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 110.0 110.0 110.0 109.0 110.0 110.0 110.0 08:01:52 INFO - 2015-10-26 08:01:52,610 DEBUG : BROWSER_OUTPUT: 08:01:52 INFO - 2015-10-26 08:01:52,610 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 08:01:52 INFO - 2015-10-26 08:01:52,610 DEBUG : BROWSER_OUTPUT: Values: 148.0 148.0 148.0 147.0 148.0 148.0 148.0 148.0 147.0 148.0 08:01:52 INFO - 2015-10-26 08:01:52,610 DEBUG : BROWSER_OUTPUT: 08:01:52 INFO - 2015-10-26 08:01:52,610 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 08:01:52 INFO - 2015-10-26 08:01:52,610 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 08:01:52 INFO - 2015-10-26 08:01:52,610 DEBUG : BROWSER_OUTPUT: 08:01:52 INFO - 2015-10-26 08:01:52,610 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:68.80 Median:68.00 stddev:3.16 (4.6%) stddev-sans-first:3.33 08:01:52 INFO - 2015-10-26 08:01:52,610 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 68.0 68.0 70.0 68.0 68.0 77.0 65.0 67.0 08:01:52 INFO - 2015-10-26 08:01:52,610 DEBUG : BROWSER_OUTPUT: 08:01:52 INFO - 2015-10-26 08:01:52,611 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:70.50 stddev:5.38 (7.6%) stddev-sans-first:5.70 08:01:52 INFO - 2015-10-26 08:01:52,611 DEBUG : BROWSER_OUTPUT: Values: 71.0 70.0 71.0 70.0 72.0 70.0 69.0 69.0 70.0 87.0 08:01:52 INFO - 2015-10-26 08:01:52,611 DEBUG : BROWSER_OUTPUT: 08:01:52 INFO - 2015-10-26 08:01:52,611 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:83.50 stddev:1.37 (1.6%) stddev-sans-first:1.27 08:01:52 INFO - 2015-10-26 08:01:52,611 DEBUG : BROWSER_OUTPUT: Values: 81.0 85.0 81.0 82.0 84.0 83.0 84.0 82.0 83.0 84.0 08:01:52 INFO - 2015-10-26 08:01:52,611 DEBUG : BROWSER_OUTPUT: 08:01:52 INFO - 2015-10-26 08:01:52,611 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:123.00 Median:123.50 stddev:6.41 (5.2%) stddev-sans-first:6.76 08:01:52 INFO - 2015-10-26 08:01:52,611 DEBUG : BROWSER_OUTPUT: Values: 125.0 126.0 120.0 123.0 139.0 119.0 118.0 118.0 124.0 118.0 08:01:52 INFO - 2015-10-26 08:01:52,611 DEBUG : BROWSER_OUTPUT: 08:01:52 INFO - 2015-10-26 08:01:52,611 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:139.90 Median:141.00 stddev:5.00 (3.5%) stddev-sans-first:5.30 08:01:52 INFO - 2015-10-26 08:01:52,611 DEBUG : BROWSER_OUTPUT: Values: 140.0 143.0 149.0 137.0 137.0 130.0 142.0 138.0 140.0 143.0 08:01:52 INFO - 2015-10-26 08:01:52,612 DEBUG : BROWSER_OUTPUT: 08:01:52 INFO - 2015-10-26 08:01:52,612 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.20 Median:72.00 stddev:1.03 (1.4%) stddev-sans-first:1.09 08:01:52 INFO - 2015-10-26 08:01:52,612 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 72.0 71.0 72.0 72.0 72.0 69.0 71.0 70.0 08:01:52 INFO - 2015-10-26 08:01:52,612 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:01:52 INFO - 2015-10-26 08:01:52,612 DEBUG : BROWSER_OUTPUT: 08:01:53 INFO - 2015-10-26 08:01:53,465 INFO : Browser exited with error code: 0 08:01:53 INFO - 2015-10-26 08:01:53,519 INFO : Completed test kraken (00:01:00) 08:01:53 INFO - 2015-10-26 08:01:53,519 INFO : Starting test v8_7 08:01:53 INFO - 2015-10-26 08:01:53,519 DEBUG : operating with platform_type : linux_ 08:01:53 INFO - 2015-10-26 08:01:53,519 INFO : Initialising browser for v8_7 test... 08:01:53 INFO - 2015-10-26 08:01:53,525 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAaia1I/profile http://localhost:41319/getInfo.html 08:01:57 INFO - 2015-10-26 08:01:57,624 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:01:57 INFO - 2015-10-26 08:01:57,624 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:01:57 INFO - 2015-10-26 08:01:57,624 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:01:57 INFO - 2015-10-26 08:01:57,624 DEBUG : BROWSER_OUTPUT: __metrics 08:01:57 INFO - 2015-10-26 08:01:57,766 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:01:58 INFO - 2015-10-26 08:01:58,314 DEBUG : BROWSER_OUTPUT: console.error: 08:01:58 INFO - 2015-10-26 08:01:58,318 DEBUG : BROWSER_OUTPUT: Message: [Exception... "Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [mozIStorageAsyncStatement.executeAsync]" nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)" location: "JS frame :: resource://gre/modules/Sqlite.jsm :: ConnectionData.prototype<._executeStatement :: line 758" data: no] 08:01:58 INFO - 2015-10-26 08:01:58,318 DEBUG : BROWSER_OUTPUT: Stack: 08:01:58 INFO - 2015-10-26 08:01:58,318 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 08:01:58 INFO - 2015-10-26 08:01:58,318 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 08:01:58 INFO - 2015-10-26 08:01:58,318 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 08:01:58 INFO - 2015-10-26 08:01:58,318 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 08:01:58 INFO - 2015-10-26 08:01:58,318 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 08:01:58 INFO - 2015-10-26 08:01:58,319 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 08:01:58 INFO - 2015-10-26 08:01:58,319 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 08:01:58 INFO - 2015-10-26 08:01:58,319 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:791:13 08:01:58 INFO - 2015-10-26 08:01:58,319 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 08:01:58 INFO - 2015-10-26 08:01:58,319 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 08:01:58 INFO - 2015-10-26 08:01:58,319 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 08:01:58 INFO - 2015-10-26 08:01:58,319 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 08:01:58 INFO - 2015-10-26 08:01:58,319 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 5979.86 08:02:33 INFO - RETURN: kraken: 1494.90 08:02:33 INFO - RETURN: v8_7: 19921.94 08:02:33 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,35]]", "result": "19921.94"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,35]]", "result": "1494.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,35]]", "result": "5979.86"}}} 08:02:33 INFO - Return code: 0 08:02:33 INFO - # TBPL SUCCESS # 08:02:33 INFO - Running post-action listener: _resource_record_post_action 08:02:33 INFO - Running post-run listener: _resource_record_post_run 08:02:34 INFO - Total resource usage - Wall time: 1235s; CPU: 13.0%; Read bytes: 8105984; Write bytes: 462893056; Read time: 2556; Write time: 425420 08:02:34 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 56406016; Read time: 0; Write time: 32892 08:02:34 INFO - run-tests - Wall time: 1221s; CPU: 13.0%; Read bytes: 6021120; Write bytes: 358903808; Read time: 2192; Write time: 357804 08:02:34 INFO - Running post-run listener: _upload_blobber_files 08:02:34 INFO - Blob upload gear active. 08:02:34 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 08:02:34 INFO - Copying logs to upload dir... 08:02:34 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1287.568680 ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 38 secs) (at 2015-10-26 08:02:45.434096) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-26 08:02:45.438132) ========= 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=eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009853 build_url: 'https://queue.taskcluster.net/v1/task/KIa7vfl5ReCXBdSAvG3mMQ/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2' ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-26 08:02:45.604619) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 08:02:45.605078) ========= 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=eadb8861e84b224bff01021b000001d6-1445869416.949159-93258937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004738 ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 08:02:45.635932) ========= ========= Started reboot slave lost (results: 0, elapsed: 4 secs) (at 2015-10-26 08:02:45.636291) ========= ========= Finished reboot slave lost (results: 0, elapsed: 4 secs) (at 2015-10-26 08:02:50.204819) =========