builder: b2g-inbound_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-078 starttime: 1445639062.57 results: success (0) buildid: 20151023144132 builduid: 29395a078bd44b32afd153b9a2871bd5 revision: dc2b138240dd6a23b5cedd55f41e508af1137836 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.568452) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.568905) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.569207) ========= 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=0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.004518 basedir: '/builds/slave/test' ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.652063) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.652371) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.673997) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.674298) ========= 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=0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004623 ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.723121) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.723392) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.723730) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.724040) ========= 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=0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-10-23 15:24:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 47.3M=0s 2015-10-23 15:24:23 (47.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.229273 ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.964756) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.965038) ========= 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=0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014282 ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:23.014392) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:23.014701) ========= bash -c 'python archiver_client.py mozharness --repo integration/b2g-inbound --rev dc2b138240dd6a23b5cedd55f41e508af1137836 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/b2g-inbound --rev dc2b138240dd6a23b5cedd55f41e508af1137836 --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=0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-10-23 15:24:23,170 truncating revision to first 12 chars 2015-10-23 15:24:23,170 Setting DEBUG logging. 2015-10-23 15:24:23,170 attempt 1/10 2015-10-23 15:24:23,170 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/dc2b138240dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-23 15:24:23,430 unpacking tar archive at: b2g-inbound-dc2b138240dd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.548573 ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:23.577829) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:23.578326) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:23.596812) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:23.597376) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:23.598071) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2015-10-23 15:24:23.598558) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound-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', 'B2G-Inbound-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'B2G-Inbound-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=0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:24:23 INFO - MultiFileLogger online at 20151023 15:24:23 in /builds/slave/test 15:24:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound-Non-PGO 15:24:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:24:23 INFO - {'append_to_log': False, 15:24:23 INFO - 'base_work_dir': '/builds/slave/test', 15:24:23 INFO - 'blob_upload_branch': 'B2G-Inbound-Non-PGO', 15:24:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:24:23 INFO - 'branch': 'B2G-Inbound-Non-PGO', 15:24:23 INFO - 'buildbot_json_path': 'buildprops.json', 15:24:23 INFO - 'config_files': ('talos/linux_config.py',), 15:24:23 INFO - 'default_actions': ('clobber', 15:24:23 INFO - 'read-buildbot-config', 15:24:23 INFO - 'download-and-extract', 15:24:23 INFO - 'populate-webroot', 15:24:23 INFO - 'create-virtualenv', 15:24:23 INFO - 'install', 15:24:23 INFO - 'run-tests'), 15:24:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:24:23 INFO - 'download_minidump_stackwalk': True, 15:24:23 INFO - 'download_symbols': 'ondemand', 15:24:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:24:23 INFO - 'tooltool.py': '/tools/tooltool.py', 15:24:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:24:23 INFO - '/tools/misc-python/virtualenv.py')}, 15:24:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:24:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:24:23 INFO - 'installer_path': 'installer.exe', 15:24:23 INFO - 'log_level': 'info', 15:24:23 INFO - 'log_name': 'talos', 15:24:23 INFO - 'log_to_console': True, 15:24:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:24:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:24:23 INFO - 'opt_config_files': (), 15:24:23 INFO - 'pip_index': False, 15:24:23 INFO - 'sps_profile': False, 15:24:23 INFO - 'sps_profile_interval': 0, 15:24:23 INFO - 'suite': 'dromaeojs', 15:24:23 INFO - 'system_bits': '64', 15:24:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:24:23 INFO - 'title': 'talos-linux64-ix-078', 15:24:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:24:23 INFO - 'use_talos_json': True, 15:24:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:24:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:24:23 INFO - 'work_dir': 'build'} 15:24:23 INFO - ##### 15:24:23 INFO - ##### Running clobber step. 15:24:23 INFO - ##### 15:24:23 INFO - Running pre-action listener: _resource_record_pre_action 15:24:23 INFO - Running main action method: clobber 15:24:23 INFO - rmtree: /builds/slave/test/build 15:24:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:24:24 INFO - Running post-action listener: _resource_record_post_action 15:24:24 INFO - ##### 15:24:24 INFO - ##### Running read-buildbot-config step. 15:24:24 INFO - ##### 15:24:24 INFO - Running pre-action listener: _resource_record_pre_action 15:24:24 INFO - Running main action method: read_buildbot_config 15:24:24 INFO - Using buildbot properties: 15:24:24 INFO - { 15:24:24 INFO - "properties": { 15:24:24 INFO - "buildnumber": 251, 15:24:24 INFO - "product": "firefox", 15:24:24 INFO - "script_repo_revision": "production", 15:24:24 INFO - "builddir": "b2g-inbound_ubuntu64_hw_test-dromaeojs", 15:24:24 INFO - "repository": "", 15:24:24 INFO - "buildername": "Ubuntu HW 12.04 x64 b2g-inbound talos dromaeojs", 15:24:24 INFO - "buildid": "20151023144132", 15:24:24 INFO - "slavename": "talos-linux64-ix-078", 15:24:24 INFO - "pgo_build": "False", 15:24:24 INFO - "basedir": "/builds/slave/test", 15:24:24 INFO - "project": "", 15:24:24 INFO - "platform": "ubuntu64_hw", 15:24:24 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:24:24 INFO - "slavebuilddir": "test", 15:24:24 INFO - "scheduler": "tests-b2g-inbound-linux64-talos", 15:24:24 INFO - "branch": "b2g-inbound", 15:24:24 INFO - "repo_path": "integration/b2g-inbound", 15:24:24 INFO - "stage_platform": "linux64", 15:24:24 INFO - "builduid": "29395a078bd44b32afd153b9a2871bd5", 15:24:24 INFO - "revision": "dc2b138240dd6a23b5cedd55f41e508af1137836" 15:24:24 INFO - }, 15:24:24 INFO - "sourcestamp": { 15:24:24 INFO - "repository": "", 15:24:24 INFO - "hasPatch": false, 15:24:24 INFO - "project": "", 15:24:24 INFO - "branch": "b2g-inbound-linux64-talos", 15:24:24 INFO - "changes": [ 15:24:24 INFO - { 15:24:24 INFO - "category": null, 15:24:24 INFO - "files": [ 15:24:24 INFO - { 15:24:24 INFO - "url": null, 15:24:24 INFO - "name": "https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2" 15:24:24 INFO - } 15:24:24 INFO - ], 15:24:24 INFO - "repository": "", 15:24:24 INFO - "rev": "dc2b138240dd6a23b5cedd55f41e508af1137836", 15:24:24 INFO - "who": "fdesre@mozilla.com", 15:24:24 INFO - "when": 1445639022, 15:24:24 INFO - "number": 6568383, 15:24:24 INFO - "comments": "Bug 1201685 - Limit the number of indexedDB open() calls in IndexedDBHelper r=gwagner", 15:24:24 INFO - "project": "", 15:24:24 INFO - "at": "Fri 23 Oct 2015 15:23:42", 15:24:24 INFO - "branch": "b2g-inbound-linux64-talos", 15:24:24 INFO - "revlink": "", 15:24:24 INFO - "properties": [ 15:24:24 INFO - [ 15:24:24 INFO - "buildid", 15:24:24 INFO - "20151023144132", 15:24:24 INFO - "Change" 15:24:24 INFO - ], 15:24:24 INFO - [ 15:24:24 INFO - "builduid", 15:24:24 INFO - "29395a078bd44b32afd153b9a2871bd5", 15:24:24 INFO - "Change" 15:24:24 INFO - ], 15:24:24 INFO - [ 15:24:24 INFO - "pgo_build", 15:24:24 INFO - "False", 15:24:24 INFO - "Change" 15:24:24 INFO - ] 15:24:24 INFO - ], 15:24:24 INFO - "revision": "dc2b138240dd6a23b5cedd55f41e508af1137836" 15:24:24 INFO - } 15:24:24 INFO - ], 15:24:24 INFO - "revision": "dc2b138240dd6a23b5cedd55f41e508af1137836" 15:24:24 INFO - } 15:24:24 INFO - } 15:24:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2. 15:24:24 INFO - Running post-action listener: _resource_record_post_action 15:24:24 INFO - ##### 15:24:24 INFO - ##### Running download-and-extract step. 15:24:24 INFO - ##### 15:24:24 INFO - Running pre-action listener: _resource_record_pre_action 15:24:24 INFO - Running main action method: download_and_extract 15:24:24 INFO - mkdir: /builds/slave/test/build/tests 15:24:24 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')]} 15:24:24 INFO - https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 15:24:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/test_packages.json 15:24:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/test_packages.json 15:24:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 15:24:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 15:24:25 INFO - Downloaded 1302 bytes. 15:24:25 INFO - Reading from file /builds/slave/test/build/test_packages.json 15:24:25 INFO - Using the following test package requirements: 15:24:25 INFO - {u'common': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'], 15:24:25 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 15:24:25 INFO - u'firefox-44.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:24:25 INFO - u'jittest': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 15:24:25 INFO - u'jsshell-linux-x86_64.zip'], 15:24:25 INFO - u'mochitest': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 15:24:25 INFO - u'firefox-44.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:24:25 INFO - u'mozbase': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'], 15:24:25 INFO - u'reftest': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 15:24:25 INFO - u'firefox-44.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:24:25 INFO - u'talos': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 15:24:25 INFO - u'firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:24:25 INFO - u'web-platform': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 15:24:25 INFO - u'firefox-44.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:24:25 INFO - u'webapprt': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'], 15:24:25 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 15:24:25 INFO - u'firefox-44.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:24:25 INFO - Downloading packages: [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:24:25 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')]} 15:24:25 INFO - https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:24:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 15:24:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 15:24:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/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 15:24:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/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 15:24:29 INFO - Downloaded 22678713 bytes. 15:24:29 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 15:24:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 15:24:29 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 15:24:31 INFO - Return code: 0 15:24:31 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 15:24: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')]} 15:24:31 INFO - https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:24:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 15:24:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 15:24:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/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 15:24:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/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 15:24:33 INFO - Downloaded 22678713 bytes. 15:24:33 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 15:24:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 15:24:33 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 15:24:35 INFO - Return code: 0 15:24:35 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')]} 15:24:35 INFO - https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:24:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip 15:24:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip 15:24:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/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 15:24:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/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 15:24:37 INFO - Downloaded 11131307 bytes. 15:24:37 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 15:24:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip 15:24:37 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 15:24:37 INFO - Return code: 0 15:24: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')]} 15:24:37 INFO - https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:24:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 15:24:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 15:24:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/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 15:24:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/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 15:24:38 INFO - Downloaded 55661359 bytes. 15:24:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 15:24:38 INFO - mkdir: /builds/slave/test/properties 15:24:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:24:38 INFO - Writing to file /builds/slave/test/properties/build_url 15:24:38 INFO - Contents: 15:24:38 INFO - build_url:https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 15:24:38 INFO - Running post-action listener: _resource_record_post_action 15:24:38 INFO - Running post-action listener: set_extra_try_arguments 15:24:38 INFO - ##### 15:24:38 INFO - ##### Running populate-webroot step. 15:24:38 INFO - ##### 15:24:38 INFO - Running pre-action listener: _resource_record_pre_action 15:24:38 INFO - Running main action method: populate_webroot 15:24:38 INFO - Guessing talos json url... 15:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:24:38 INFO - https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.txt matches https://queue.taskcluster.net 15:24:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.txt 15:24:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.txt 15:24:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/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 15:24:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/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 15:24:39 INFO - Downloaded 107 bytes. 15:24:39 INFO - Reading from file /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.txt 15:24:39 INFO - Contents: 15:24:39 INFO - 20151023144132 15:24:39 INFO - https://hg.mozilla.org/integration/b2g-inbound/rev/dc2b138240dd6a23b5cedd55f41e508af1137836 15:24:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:24:39 INFO - trying https://hg.mozilla.org/integration/b2g-inbound/raw-file/dc2b138240/testing/talos/talos.json 15:24:39 INFO - Downloading https://hg.mozilla.org/integration/b2g-inbound/raw-file/dc2b138240/testing/talos/talos.json to /builds/slave/test/build/talos.json 15:24:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/b2g-inbound/raw-file/dc2b138240/testing/talos/talos.json', 'file_name': '/builds/slave/test/build/talos.json'}, attempt #1 15:24:40 INFO - Downloaded 4264 bytes. 15:24:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:24:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:24:40 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 15:24:40 INFO - '../fennec_ids.txt'], 15:24:40 INFO - 'tests': ['tcheck2']}, 15:24:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:24:40 INFO - '--tppagecycles', 15:24:40 INFO - '7'], 15:24:40 INFO - 'tests': ['tsvgm']}}, 15:24:40 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 15:24:40 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 15:24:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:24:40 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:24:40 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 15:24:40 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:24:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:24:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:24:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:24:40 INFO - 'talos_options': ['--e10s'], 15:24:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:24:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:24:40 INFO - 'tests': ['damp', 'tps']}, 15:24:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:24:40 INFO - 'talos_options': ['--e10s'], 15:24:40 INFO - 'tests': ['damp', 'tps']}, 15:24:40 INFO - 'g3': {'tests': ['dromaeo_dom']}, 15:24:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:24:40 INFO - 'other': {'tests': ['a11yr', 15:24:40 INFO - 'ts_paint', 15:24:40 INFO - 'tpaint', 15:24:40 INFO - 'sessionrestore', 15:24:40 INFO - 'sessionrestore_no_auto_restore']}, 15:24:40 INFO - 'other-e10s': {'talos_options': ['--e10s'], 15:24:40 INFO - 'tests': ['a11yr', 15:24:40 INFO - 'ts_paint', 15:24:40 INFO - 'tpaint', 15:24:40 INFO - 'sessionrestore', 15:24:40 INFO - 'sessionrestore_no_auto_restore']}, 15:24:40 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 15:24:40 INFO - 'tests': ['a11yr', 15:24:40 INFO - 'ts_paint', 15:24:40 INFO - 'tpaint', 15:24:40 INFO - 'sessionrestore', 15:24:40 INFO - 'sessionrestore_no_auto_restore']}, 15:24:40 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 15:24:40 INFO - 'tests': ['a11yr', 15:24:40 INFO - 'ts_paint', 15:24:40 INFO - 'tpaint', 15:24:40 INFO - 'sessionrestore', 15:24:40 INFO - 'sessionrestore_no_auto_restore']}, 15:24:40 INFO - 'other_l64': {'tests': ['a11yr', 15:24:40 INFO - 'ts_paint', 15:24:40 INFO - 'tpaint', 15:24:40 INFO - 'sessionrestore', 15:24:40 INFO - 'sessionrestore_no_auto_restore']}, 15:24:40 INFO - 'other_nol64': {'tests': ['a11yr', 15:24:40 INFO - 'ts_paint', 15:24:40 INFO - 'tpaint', 15:24:40 INFO - 'sessionrestore', 15:24:40 INFO - 'sessionrestore_no_auto_restore']}, 15:24:40 INFO - 'svgr': {'tests': ['tsvgx', 15:24:40 INFO - 'tsvgr_opacity', 15:24:40 INFO - 'tart', 15:24:40 INFO - 'tscrollx', 15:24:40 INFO - 'cart']}, 15:24:40 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 15:24:40 INFO - 'tests': ['tsvgx', 15:24:40 INFO - 'tsvgr_opacity', 15:24:40 INFO - 'tart', 15:24:40 INFO - 'tscrollx', 15:24:40 INFO - 'cart']}, 15:24:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:24:40 INFO - 'tests': ['tp5o']}, 15:24:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:24:40 INFO - 'talos_options': ['--e10s'], 15:24:40 INFO - 'tests': ['tp5o']}, 15:24:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:24:40 INFO - 'talos_options': ['--xperf_path', 15:24:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:24:40 INFO - 'tests': ['tp5n']}, 15:24:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:24:40 INFO - 'talos_options': ['--e10s', 15:24:40 INFO - '--xperf_path', 15:24:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 15:24:40 INFO - 'C:/slave/talos-data/talos/xperf.config'], 15:24:40 INFO - 'tests': ['tp5n']}}, 15:24:40 INFO - 'talos.zip': {'path': '', 15:24:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:24:40 INFO - Running post-action listener: _resource_record_post_action 15:24:40 INFO - ##### 15:24:40 INFO - ##### Running create-virtualenv step. 15:24:40 INFO - ##### 15:24:40 INFO - Running pre-action listener: _resource_record_pre_action 15:24:40 INFO - Running main action method: create_virtualenv 15:24:40 INFO - Creating virtualenv /builds/slave/test/build/venv 15:24:40 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 15:24:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:24:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:24:40 INFO - Using real prefix '/usr' 15:24:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:24:41 INFO - Installing distribute.............................................................................................................................................................................................done. 15:24:43 INFO - Installing pip.................done. 15:24:43 INFO - Return code: 0 15:24:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:24:43 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')]} 15:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:43 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')]} 15:24:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:43 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 0x7f962a143c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23947b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2466ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fba30>, '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 0x24650b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2465540>, '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': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', '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 15:24:43 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 15:24:43 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 15:24:43 INFO - Using env: {'DISPLAY': ':0', 15:24:43 INFO - 'HOME': '/home/cltbld', 15:24:43 INFO - 'LANG': 'en_US.UTF-8', 15:24:43 INFO - 'LANGUAGE': 'en_US:en', 15:24:43 INFO - 'LOGNAME': 'cltbld', 15:24:43 INFO - 'MAIL': '/var/mail/cltbld', 15:24:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:43 INFO - 'MOZ_NO_REMOTE': '1', 15:24:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:24:43 INFO - 'NO_EM_RESTART': '1', 15:24:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:24:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:43 INFO - 'PWD': '/builds/slave/test', 15:24:43 INFO - 'SHELL': '/bin/bash', 15:24:43 INFO - 'SHLVL': '1', 15:24:43 INFO - 'TERM': 'linux', 15:24:43 INFO - 'TMOUT': '86400', 15:24:43 INFO - 'USER': 'cltbld', 15:24:43 INFO - 'XDG_SESSION_COOKIE': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', 15:24:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:43 INFO - '_': '/tools/buildbot/bin/python'} 15:24:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:24:44 INFO - Cleaning up... 15:24:44 INFO - Return code: 0 15:24:44 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:24:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f962a143c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23947b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2466ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fba30>, '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 0x24650b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2465540>, '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': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', '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 15:24:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 15:24:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 15:24:44 INFO - Using env: {'DISPLAY': ':0', 15:24:44 INFO - 'HOME': '/home/cltbld', 15:24:44 INFO - 'LANG': 'en_US.UTF-8', 15:24:44 INFO - 'LANGUAGE': 'en_US:en', 15:24:44 INFO - 'LOGNAME': 'cltbld', 15:24:44 INFO - 'MAIL': '/var/mail/cltbld', 15:24:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:44 INFO - 'MOZ_NO_REMOTE': '1', 15:24:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:24:44 INFO - 'NO_EM_RESTART': '1', 15:24:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:24:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:44 INFO - 'PWD': '/builds/slave/test', 15:24:44 INFO - 'SHELL': '/bin/bash', 15:24:44 INFO - 'SHLVL': '1', 15:24:44 INFO - 'TERM': 'linux', 15:24:44 INFO - 'TMOUT': '86400', 15:24:44 INFO - 'USER': 'cltbld', 15:24:44 INFO - 'XDG_SESSION_COOKIE': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', 15:24:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:44 INFO - '_': '/tools/buildbot/bin/python'} 15:24:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:44 INFO - Downloading/unpacking psutil>=0.7.1 15:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:46 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 15:24:46 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 15:24:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:24:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:24:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:24:46 INFO - Installing collected packages: psutil 15:24:46 INFO - Running setup.py install for psutil 15:24:46 INFO - building 'psutil._psutil_linux' extension 15:24:46 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:24:47 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:24:48 INFO - building 'psutil._psutil_posix' extension 15:24:48 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 15:24:48 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 15:24:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:24:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:24:48 INFO - Successfully installed psutil 15:24:48 INFO - Cleaning up... 15:24:48 INFO - Return code: 0 15:24:48 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:24:48 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')]} 15:24:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:48 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')]} 15:24:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:48 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 0x7f962a143c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23947b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2466ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fba30>, '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 0x24650b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2465540>, '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': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', '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 15:24:48 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 15:24:48 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 15:24:48 INFO - Using env: {'DISPLAY': ':0', 15:24:48 INFO - 'HOME': '/home/cltbld', 15:24:48 INFO - 'LANG': 'en_US.UTF-8', 15:24:48 INFO - 'LANGUAGE': 'en_US:en', 15:24:48 INFO - 'LOGNAME': 'cltbld', 15:24:48 INFO - 'MAIL': '/var/mail/cltbld', 15:24:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:48 INFO - 'MOZ_NO_REMOTE': '1', 15:24:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:24:48 INFO - 'NO_EM_RESTART': '1', 15:24:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:24:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:48 INFO - 'PWD': '/builds/slave/test', 15:24:48 INFO - 'SHELL': '/bin/bash', 15:24:48 INFO - 'SHLVL': '1', 15:24:48 INFO - 'TERM': 'linux', 15:24:48 INFO - 'TMOUT': '86400', 15:24:48 INFO - 'USER': 'cltbld', 15:24:48 INFO - 'XDG_SESSION_COOKIE': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', 15:24:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:48 INFO - '_': '/tools/buildbot/bin/python'} 15:24:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:48 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:24: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 15:24: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 15:24: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 15:24: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 15:24: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 15:24: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 15:24:50 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:24:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 15:24:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:24:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:24:50 INFO - Installing collected packages: mozsystemmonitor 15:24:50 INFO - Running setup.py install for mozsystemmonitor 15:24:50 INFO - Successfully installed mozsystemmonitor 15:24:50 INFO - Cleaning up... 15:24:50 INFO - Return code: 0 15:24:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:24:50 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')]} 15:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:50 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')]} 15:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:50 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 0x7f962a143c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23947b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2466ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fba30>, '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 0x24650b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2465540>, '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': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', '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 15:24:50 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 15:24:50 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 15:24:50 INFO - Using env: {'DISPLAY': ':0', 15:24:50 INFO - 'HOME': '/home/cltbld', 15:24:50 INFO - 'LANG': 'en_US.UTF-8', 15:24:50 INFO - 'LANGUAGE': 'en_US:en', 15:24:50 INFO - 'LOGNAME': 'cltbld', 15:24:50 INFO - 'MAIL': '/var/mail/cltbld', 15:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:50 INFO - 'MOZ_NO_REMOTE': '1', 15:24:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:24:50 INFO - 'NO_EM_RESTART': '1', 15:24:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:50 INFO - 'PWD': '/builds/slave/test', 15:24:50 INFO - 'SHELL': '/bin/bash', 15:24:50 INFO - 'SHLVL': '1', 15:24:50 INFO - 'TERM': 'linux', 15:24:50 INFO - 'TMOUT': '86400', 15:24:50 INFO - 'USER': 'cltbld', 15:24:50 INFO - 'XDG_SESSION_COOKIE': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', 15:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:50 INFO - '_': '/tools/buildbot/bin/python'} 15:24:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:50 INFO - Downloading/unpacking blobuploader==1.2.4 15:24: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 15:24: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 15:24: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 15:24: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 15:24: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 15:24: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 15:24:52 INFO - Downloading blobuploader-1.2.4.tar.gz 15:24:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 15:24:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:24:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 15:24:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:24:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:24:53 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 15:24:53 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 15:24:53 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 15:24:53 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 15:24:53 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 15:24:53 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 15:24:53 INFO - Downloading docopt-0.6.1.tar.gz 15:24:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 15:24:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:24:53 INFO - Installing collected packages: blobuploader, requests, docopt 15:24:53 INFO - Running setup.py install for blobuploader 15:24:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:24:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:24:53 INFO - Running setup.py install for requests 15:24:53 INFO - Running setup.py install for docopt 15:24:53 INFO - Successfully installed blobuploader requests docopt 15:24:53 INFO - Cleaning up... 15:24:53 INFO - Return code: 0 15:24:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:24:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:53 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 0x7f962a143c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23947b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2466ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fba30>, '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 0x24650b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2465540>, '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': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', '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 15:24:53 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 15:24:53 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 15:24:53 INFO - Using env: {'DISPLAY': ':0', 15:24:53 INFO - 'HOME': '/home/cltbld', 15:24:53 INFO - 'LANG': 'en_US.UTF-8', 15:24:53 INFO - 'LANGUAGE': 'en_US:en', 15:24:53 INFO - 'LOGNAME': 'cltbld', 15:24:53 INFO - 'MAIL': '/var/mail/cltbld', 15:24:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:53 INFO - 'MOZ_NO_REMOTE': '1', 15:24:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:24:53 INFO - 'NO_EM_RESTART': '1', 15:24:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:24:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:53 INFO - 'PWD': '/builds/slave/test', 15:24:53 INFO - 'SHELL': '/bin/bash', 15:24:53 INFO - 'SHLVL': '1', 15:24:53 INFO - 'TERM': 'linux', 15:24:53 INFO - 'TMOUT': '86400', 15:24:53 INFO - 'USER': 'cltbld', 15:24:53 INFO - 'XDG_SESSION_COOKIE': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', 15:24:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:53 INFO - '_': '/tools/buildbot/bin/python'} 15:24:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:24:54 INFO - Running setup.py (path:/tmp/pip-uDpksA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:24:54 INFO - Running setup.py (path:/tmp/pip-ECoLtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:24:54 INFO - Running setup.py (path:/tmp/pip-0Qvmt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:24:54 INFO - Running setup.py (path:/tmp/pip-xXjdDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:24:54 INFO - Running setup.py (path:/tmp/pip-pSy2Gz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:24:54 INFO - Running setup.py (path:/tmp/pip-NYxM9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:24:54 INFO - Running setup.py (path:/tmp/pip-Jf8P2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:24:54 INFO - Running setup.py (path:/tmp/pip-KpWUmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:24:54 INFO - Running setup.py (path:/tmp/pip-A99iFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:24:54 INFO - Running setup.py (path:/tmp/pip-wycHXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:24:54 INFO - Running setup.py (path:/tmp/pip-Qb7im9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:24:54 INFO - Running setup.py (path:/tmp/pip-i7N_ZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:24:55 INFO - Running setup.py (path:/tmp/pip-yeSzVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:24:55 INFO - Running setup.py (path:/tmp/pip-gifgOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:55 INFO - Running setup.py (path:/tmp/pip-acwWSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:24:55 INFO - Running setup.py (path:/tmp/pip-fIcZBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:24:55 INFO - Running setup.py (path:/tmp/pip-CB3Pvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:24:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:24:55 INFO - Running setup.py install for manifestparser 15:24:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:24:55 INFO - Running setup.py install for mozcrash 15:24:55 INFO - Running setup.py install for mozdebug 15:24:55 INFO - Running setup.py install for mozdevice 15:24:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:24:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:24:55 INFO - Running setup.py install for mozfile 15:24:55 INFO - Running setup.py install for mozhttpd 15:24:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:24:56 INFO - Running setup.py install for mozinfo 15:24:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:24:56 INFO - Running setup.py install for mozInstall 15:24:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:24:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:24:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:24:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:24:56 INFO - Running setup.py install for mozleak 15:24:56 INFO - Running setup.py install for mozlog 15:24:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:24:56 INFO - Running setup.py install for moznetwork 15:24:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:24:56 INFO - Running setup.py install for mozprocess 15:24:56 INFO - Running setup.py install for mozprofile 15:24:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:24:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:24:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:24:56 INFO - Running setup.py install for mozrunner 15:24:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:24:56 INFO - Running setup.py install for mozscreenshot 15:24:56 INFO - Running setup.py install for moztest 15:24:57 INFO - Running setup.py install for mozversion 15:24:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:24:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:24:57 INFO - Cleaning up... 15:24:57 INFO - Return code: 0 15:24:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:24:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:24:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:57 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 0x7f962a143c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23947b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2466ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fba30>, '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 0x24650b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2465540>, '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': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', '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 15:24:57 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 15:24:57 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 15:24:57 INFO - Using env: {'DISPLAY': ':0', 15:24:57 INFO - 'HOME': '/home/cltbld', 15:24:57 INFO - 'LANG': 'en_US.UTF-8', 15:24:57 INFO - 'LANGUAGE': 'en_US:en', 15:24:57 INFO - 'LOGNAME': 'cltbld', 15:24:57 INFO - 'MAIL': '/var/mail/cltbld', 15:24:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:57 INFO - 'MOZ_NO_REMOTE': '1', 15:24:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:24:57 INFO - 'NO_EM_RESTART': '1', 15:24:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:24:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:57 INFO - 'PWD': '/builds/slave/test', 15:24:57 INFO - 'SHELL': '/bin/bash', 15:24:57 INFO - 'SHLVL': '1', 15:24:57 INFO - 'TERM': 'linux', 15:24:57 INFO - 'TMOUT': '86400', 15:24:57 INFO - 'USER': 'cltbld', 15:24:57 INFO - 'XDG_SESSION_COOKIE': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', 15:24:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:57 INFO - '_': '/tools/buildbot/bin/python'} 15:24:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:24:57 INFO - Running setup.py (path:/tmp/pip-1nnJIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:24:57 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)) 15:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:24:57 INFO - Running setup.py (path:/tmp/pip-NO2VbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:24:57 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)) 15:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:24:57 INFO - Running setup.py (path:/tmp/pip-ytmq2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:24:57 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)) 15:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:24:57 INFO - Running setup.py (path:/tmp/pip-8u4yR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:24:57 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)) 15:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:24:57 INFO - Running setup.py (path:/tmp/pip-XE_LAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:24:57 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)) 15:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:24:57 INFO - Running setup.py (path:/tmp/pip-Ynmwls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:24:57 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)) 15:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:24:57 INFO - Running setup.py (path:/tmp/pip-_7u5ai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:24:57 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)) 15:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:24:57 INFO - Running setup.py (path:/tmp/pip-r33UPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:24:58 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)) 15:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:24:58 INFO - Running setup.py (path:/tmp/pip-SEGP_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:24:58 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)) 15:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:24:58 INFO - Running setup.py (path:/tmp/pip-rrqpE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:24:58 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)) 15:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:24:58 INFO - Running setup.py (path:/tmp/pip-oSXhwC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:24:58 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)) 15:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:24:58 INFO - Running setup.py (path:/tmp/pip-YMnW_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:24:58 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)) 15:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:24:58 INFO - Running setup.py (path:/tmp/pip-r1TEJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:24:58 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)) 15:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:24:58 INFO - Running setup.py (path:/tmp/pip-JPGQUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.10 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)) 15:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:58 INFO - Running setup.py (path:/tmp/pip-PaZUiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:58 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)) 15:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:24:58 INFO - Running setup.py (path:/tmp/pip-Ejisy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:24:58 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)) 15:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:24:58 INFO - Running setup.py (path:/tmp/pip-Keuej5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:24:58 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)) 15:24:58 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)) 15:24: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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:24:58 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)) 15:24:58 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)) 15:24:58 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)) 15:24:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:00 INFO - Downloading blessings-1.5.1.tar.gz 15:25:00 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 15:25:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:25:00 INFO - Installing collected packages: blessings 15:25:00 INFO - Running setup.py install for blessings 15:25:00 INFO - Successfully installed blessings 15:25:00 INFO - Cleaning up... 15:25:00 INFO - Return code: 0 15:25:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:25:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:25:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:25:00 INFO - Reading from file tmpfile_stdout 15:25:00 INFO - Current package versions: 15:25:00 INFO - argparse == 1.2.1 15:25:00 INFO - blessings == 1.5.1 15:25:00 INFO - blobuploader == 1.2.4 15:25:00 INFO - docopt == 0.6.1 15:25:00 INFO - manifestparser == 1.1 15:25:00 INFO - mozInstall == 1.12 15:25:00 INFO - mozcrash == 0.16 15:25:00 INFO - mozdebug == 0.1 15:25:00 INFO - mozdevice == 0.46 15:25:00 INFO - mozfile == 1.2 15:25:00 INFO - mozhttpd == 0.7 15:25:00 INFO - mozinfo == 0.8 15:25:00 INFO - mozleak == 0.1 15:25:00 INFO - mozlog == 3.0 15:25:00 INFO - moznetwork == 0.27 15:25:00 INFO - mozprocess == 0.22 15:25:00 INFO - mozprofile == 0.27 15:25:00 INFO - mozrunner == 6.10 15:25:00 INFO - mozscreenshot == 0.1 15:25:00 INFO - mozsystemmonitor == 0.0 15:25:00 INFO - moztest == 0.7 15:25:00 INFO - mozversion == 1.4 15:25:00 INFO - psutil == 3.1.1 15:25:00 INFO - requests == 1.2.3 15:25:00 INFO - wsgiref == 0.1.2 15:25:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:25:00 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')]} 15:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:00 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')]} 15:25:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:00 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 0x7f962a143c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23947b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2466ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fba30>, '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 0x24650b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2465540>, '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': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', '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 15:25:00 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 15:25:00 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 15:25:00 INFO - Using env: {'DISPLAY': ':0', 15:25:00 INFO - 'HOME': '/home/cltbld', 15:25:00 INFO - 'LANG': 'en_US.UTF-8', 15:25:00 INFO - 'LANGUAGE': 'en_US:en', 15:25:00 INFO - 'LOGNAME': 'cltbld', 15:25:00 INFO - 'MAIL': '/var/mail/cltbld', 15:25:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:00 INFO - 'MOZ_NO_REMOTE': '1', 15:25:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:25:00 INFO - 'NO_EM_RESTART': '1', 15:25:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:25:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:00 INFO - 'PWD': '/builds/slave/test', 15:25:00 INFO - 'SHELL': '/bin/bash', 15:25:00 INFO - 'SHLVL': '1', 15:25:00 INFO - 'TERM': 'linux', 15:25:00 INFO - 'TMOUT': '86400', 15:25:00 INFO - 'USER': 'cltbld', 15:25:00 INFO - 'XDG_SESSION_COOKIE': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', 15:25:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:00 INFO - '_': '/tools/buildbot/bin/python'} 15:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:01 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)) 15:25:01 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)) 15:25:01 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)) 15:25:01 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)) 15:25:01 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)) 15:25:01 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)) 15:25:01 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)) 15:25:01 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)) 15:25:01 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)) 15:25:01 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)) 15:25:01 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)) 15:25:01 INFO - Cleaning up... 15:25:01 INFO - Return code: 0 15:25:01 INFO - Running post-action listener: _resource_record_post_action 15:25:01 INFO - Running post-action listener: _start_resource_monitoring 15:25:01 INFO - Starting resource monitoring. 15:25:01 INFO - ##### 15:25:01 INFO - ##### Running install step. 15:25:01 INFO - ##### 15:25:01 INFO - Running pre-action listener: _resource_record_pre_action 15:25:01 INFO - Running main action method: install 15:25:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:25:01 INFO - Reading from file tmpfile_stdout 15:25:01 INFO - Detecting whether we're running mozinstall >=1.0... 15:25:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:25:01 INFO - Reading from file tmpfile_stdout 15:25:01 INFO - Output received: 15:25:01 INFO - Usage: mozinstall [options] installer 15:25:01 INFO - Options: 15:25:01 INFO - -h, --help show this help message and exit 15:25:01 INFO - -d DEST, --destination=DEST 15:25:01 INFO - Directory to install application into. [default: 15:25:01 INFO - "/builds/slave/test"] 15:25:01 INFO - --app=APP Application being installed. [default: firefox] 15:25:01 INFO - mkdir: /builds/slave/test/build/application 15:25:01 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'] 15:25:01 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 15:25:15 INFO - Reading from file tmpfile_stdout 15:25:15 INFO - Output received: 15:25:15 INFO - /builds/slave/test/build/application/firefox/firefox 15:25:15 INFO - Running post-action listener: _resource_record_post_action 15:25:15 INFO - ##### 15:25:15 INFO - ##### Running run-tests step. 15:25:15 INFO - ##### 15:25:15 INFO - Running pre-action listener: _resource_record_pre_action 15:25:15 INFO - Running main action method: run_tests 15:25:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:25:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:25:15 INFO - Python 2.7.3 15:25:15 INFO - Return code: 0 15:25:15 INFO - grabbing minidump binary from tooltool 15:25:15 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')]} 15:25:15 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 0x24fba30>, '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 0x24650b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2465540>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:25:15 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 15:25:15 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 15:25:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:25:15 INFO - Return code: 0 15:25:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:25:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:25:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:25:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:25:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:25:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'B2G-Inbound-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-078', '--webServer', 'localhost'] in /builds/slave/test/build 15:25:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName B2G-Inbound-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-078 --webServer localhost 15:25:15 INFO - Using env: {'DISPLAY': ':0', 15:25:15 INFO - 'HOME': '/home/cltbld', 15:25:15 INFO - 'LANG': 'en_US.UTF-8', 15:25:15 INFO - 'LANGUAGE': 'en_US:en', 15:25:15 INFO - 'LOGNAME': 'cltbld', 15:25:15 INFO - 'MAIL': '/var/mail/cltbld', 15:25:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:25:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:25:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:15 INFO - 'MOZ_NO_REMOTE': '1', 15:25:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:25:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:25:15 INFO - 'NO_EM_RESTART': '1', 15:25:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:25:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:15 INFO - 'PWD': '/builds/slave/test', 15:25:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:25:15 INFO - 'SHELL': '/bin/bash', 15:25:15 INFO - 'SHLVL': '1', 15:25:15 INFO - 'TERM': 'linux', 15:25:15 INFO - 'TMOUT': '86400', 15:25:15 INFO - 'USER': 'cltbld', 15:25:15 INFO - 'XDG_SESSION_COOKIE': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', 15:25:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:15 INFO - '_': '/tools/buildbot/bin/python'} 15:25:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'B2G-Inbound-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-078', '--webServer', 'localhost'] with output_timeout 3600 15:25:15 INFO - mozversion INFO | application_buildid: 20151023144132 15:25:15 INFO - mozversion INFO | application_changeset: dc2b138240dd6a23b5cedd55f41e508af1137836 15:25:15 INFO - mozversion INFO | application_display_name: Nightly 15:25:15 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:25:15 INFO - mozversion INFO | application_name: Firefox 15:25:15 INFO - mozversion INFO | application_remotingname: firefox 15:25:15 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/b2g-inbound 15:25:15 INFO - mozversion INFO | application_vendor: Mozilla 15:25:15 INFO - mozversion INFO | application_version: 44.0a1 15:25:15 INFO - mozversion INFO | platform_buildid: 20151023144132 15:25:15 INFO - mozversion INFO | platform_changeset: dc2b138240dd6a23b5cedd55f41e508af1137836 15:25:15 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/b2g-inbound 15:25:15 INFO - mozversion INFO | platform_version: 44.0a1 15:25:15 INFO - 2015-10-23 15:25:15,839 DEBUG : using testdate: 1445639115 15:25:15 INFO - 2015-10-23 15:25:15,839 DEBUG : actual date: 1445639115 15:25:15 INFO - 2015-10-23 15:25:15,920 INFO : starting webserver on 'localhost:56946' 15:25:15 INFO - 2015-10-23 15:25:15,921 INFO : Starting test suite talos-linux64-ix-078 15:25:15 INFO - 2015-10-23 15:25:15,921 INFO : Starting test dromaeo_css 15:25:15 INFO - 2015-10-23 15:25:15,922 DEBUG : operating with platform_type : linux_ 15:25:15 INFO - 2015-10-23 15:25:15,922 INFO : Initialising browser for dromaeo_css test... 15:25:15 INFO - 2015-10-23 15:25:15,951 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa3QdOr/profile http://localhost:56946/getInfo.html 15:25:23 INFO - 2015-10-23 15:25:23,268 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:25:23 INFO - 2015-10-23 15:25:23,268 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:25:23 INFO - 2015-10-23 15:25:23,268 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:25:23 INFO - 2015-10-23 15:25:23,268 DEBUG : BROWSER_OUTPUT: __metrics 15:25:23 INFO - 2015-10-23 15:25:23,409 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:25:24 INFO - 2015-10-23 15:25:24,773 INFO : Browser initialized. 15:25:24 INFO - 2015-10-23 15:25:24,773 INFO : Running cycle 1/1 for dromaeo_css test... 15:25:24 INFO - 2015-10-23 15:25:24,773 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa3QdOr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:25:36 INFO - 2015-10-23 15:25:36,705 DEBUG : BROWSER_OUTPUT: RSS: Main: 141819904 15:25:36 INFO - 2015-10-23 15:25:36,706 DEBUG : BROWSER_OUTPUT: 15:26:28 INFO - 2015-10-23 15:26:28,492 DEBUG : BROWSER_OUTPUT: 1445639188491 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] 15:28:40 INFO - 2015-10-23 15:28:40,800 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56946/tests/dromaeo/cssquery-ext.html) 15:28:41 INFO - 2015-10-23 15:28:41,971 DEBUG : BROWSER_OUTPUT: RSS: Main: 240898048 15:28:41 INFO - 2015-10-23 15:28:41,971 DEBUG : BROWSER_OUTPUT: 15:31:44 INFO - 2015-10-23 15:31:44,350 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/dromaeo/cssquery-ext.html (next: http://localhost:56946/tests/dromaeo/cssquery-jquery.html) 15:31:44 INFO - 2015-10-23 15:31:44,931 DEBUG : BROWSER_OUTPUT: RSS: Main: 215420928 15:31:44 INFO - 2015-10-23 15:31:44,931 DEBUG : BROWSER_OUTPUT: 15:34:47 INFO - 2015-10-23 15:34:47,052 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56946/tests/dromaeo/cssquery-mootools.html) 15:34:47 INFO - 2015-10-23 15:34:47,618 DEBUG : BROWSER_OUTPUT: RSS: Main: 213344256 15:34:47 INFO - 2015-10-23 15:34:47,618 DEBUG : BROWSER_OUTPUT: 15:37:49 INFO - 2015-10-23 15:37:49,568 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56946/tests/dromaeo/cssquery-prototype.html) 15:37:50 INFO - 2015-10-23 15:37:50,154 DEBUG : BROWSER_OUTPUT: RSS: Main: 199266304 15:37:50 INFO - 2015-10-23 15:37:50,155 DEBUG : BROWSER_OUTPUT: 15:40:53 INFO - 2015-10-23 15:40:53,040 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56946/tests/dromaeo/cssquery-yui.html) 15:40:53 INFO - 2015-10-23 15:40:53,613 DEBUG : BROWSER_OUTPUT: RSS: Main: 201940992 15:40:53 INFO - 2015-10-23 15:40:53,614 DEBUG : BROWSER_OUTPUT: 15:43:56 INFO - 2015-10-23 15:43:56,008 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/dromaeo/cssquery-yui.html (next: http://localhost:56946/tests/dromaeo/cssquery-dojo.html) 15:43:56 INFO - 2015-10-23 15:43:56,259 DEBUG : BROWSER_OUTPUT: RSS: Main: 246972416 15:43:56 INFO - 2015-10-23 15:43:56,259 DEBUG : BROWSER_OUTPUT: 15:43:56 INFO - 2015-10-23 15:43:56,261 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:43:56 INFO - 2015-10-23 15:43:56,261 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:43:56 INFO - 2015-10-23 15:43:56,261 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:43:56 INFO - 2015-10-23 15:43:56,261 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:43:56 INFO - 2015-10-23 15:43:56,262 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2648.8808003639833;2732.6857411397696;2759.6964333923265;2764.8341099534027;2765.7649099826513;4749.7387643679585;4845.394325709286;4956.727379994103;4988.975055124728;5008.549230569252;4849.975750121253;4862.708237505753;4948;4958.801647934079;4988.825391111312;4477.171723231197;4486.439195100605;4518.277075667894;4581.4960354361165;4627.629789616823;4349.347597860315;4559.589636932676;4567.931481027787;4589.977050114745;4612.677112602119;4436.977815110904;4449.221386257392;4478.686491945565;4572.611328037121;4599.149157405891;4532.161550113239;4532.297493888453;4537.931931021004;4539.6368290536675;4577.33628623851;4375.2124617568825;4520.434945631796;4531.274996000624;4539.160255352737;4577;4733.148033353995;4804;4841.709497430165;4851.126797176506;4859.684120532154;5172.922406163873;5175.068487672255;5177.119889618773;5178.71517066565;5179.378474583036;5034.672746271481;5192.55863251624;5195.974020129913;5196.58427325813;5250.7637156328055;4698.647601429906;4787.976060119747;4803.351547541072;4806.8798280043275;4825.662203645781;5364.651297665718;5372.704501252469;5383.623146379716;5386.757595908193;5391.083515802323;4788;5133.691978481228;5146.716930568779;5148.871278218;5197.506236907488;5149.716765577854;5163.535281824654;5168.9224661630105;5261.421243663194;5320.946790532123;5178.197379406198;5192.221166825007;5194.3247377840635;5204.895902082013;5258.447862974409;4867.1969125093965;5172.870678233075;5176.715280659525;5183.81856635016;5187.584993200535;4251.659867210616;4286.721363111388;4314.741115533078;4315.6763242756915;4351.108022855307;3751.6060813614176;3755.2301778135416;3755.455458958436;3782.224643947984;3804.353259945816;4181.435506206684;4185.707000509996;4195.16096780639;4197.538270790211;4234.513031001413;4911;5013.49865013503;5039.84880453587;5086.949130508721;5195.19474481456;4139.668826493873;4148.149629326042;4173.1862286853775;4182.435371224907;4197.496300443905;4622.375979242826;4628.861134165981;4628.97685511577;4641.419822522184;4669.626429885601;4691.7654117294;4796.2805579163405;4796.5203479651755;4802.615790736802;4872.074305881871;5219.269302297757;5251.947480525146;5259.8422047338645;5260.605454590845;5263.5525980292105;4936.654434189572;4953.702777833341;4970.155073637418;4970.8757281068265;4997.175466048061;4940.6788558743565;5095.566876815364;5097.719625420637;5111.156659151347;5121.667091639031;5091.414487334004;5147.099257629975;5156.2523434102595;5200.947990520046;5214.895702085862;4906.533879281463;4959.156943319572;4984.675996060245;4984.75076246179;5026.170681837602;5012.248162775612;5028.145215313479;5033.974830125972;5036.194208926554;5036.647434679538;4614.815407383667;4737.360456338351;4741.478437371955;4756.476787553297;4883;5055.545000949932;5057.519535644108;5068.49315068505;5071.898562028812;5079.238114282739;4892.241702536113;4894.314795928644;4905.607551395951;4909.828156014522;4935.753212339297 15:43:56 INFO - 2015-10-23 15:43:56,265 DEBUG : BROWSER_OUTPUT: |1;ext.html;502.7008929686836;506.53398873036826;509.0709455244178;537.938137114232;546.8113500842211;28764.424711505784;37176.25647487052;37650.43524347134;37664.81167594165;37716.43425348622;40749.388759168636;41369;41385.37921931173;41407.378889316686;41458.79270603643;7139.1433028036445;7246.71013159473;7951.443398962089;8125.187481251886;8126.349892008625;10181.694549163536;10455.634052808153;10457.895421045769;10459.738506537322;10468.214883883702;10044.497775111251;10140.340877842917;10156.644517441891;10168.796624067514;10174.898251017468;7329.670164842568;7530.28462296081;10216.795664086676;10221.080102790747;10528.789424211549;10110.797784044351;10271.33236339643;10279.640212592523;10291.125254353388;10292.536835842406;38631;39217.60782392168;39270.21459570821;39271.21457570861;39272.21455570901;2733.72662733727;2752.862356882148;2769.3353595137023;2789.2050765531726;2794.930126746827;28599.14202573926;28790.856045719847;28811.42377152466;28816.718554016912;29454.98409913378;2607.895684172631;2621.6722909636296;2630.184642760731;2671.17193669961;2759.9586006209925;235516;237022;237239;239819;240078;53126.468735312155;54216.45783542193;57024.14463783047;57521.71239143861;62884;58764.412355875895;58787;59159.40840591539;59205.70397147987;59217.40782592119;14299.85700143006;14487.275636218146;14499.347529361205;14554.636134096732;14588.854111458963;10839.620613278497;10970.396628185526;10990.945045274722;10991;11025.724356890982;19132.23471061142;19171.424857254304;19172.424827255203;19176.424707258804;19304.71042934357;8138.104808471064;8363.954505686788;8414.11651776567;8446.059055452506;8446.535440550828;756.6141267953384;839.7186942374251;854.2098558833054;877.1842186766369;939.3143005605946;26344.209673709818;26364.340891477485;26454.074107406534;26493.602595961074;26583.867080664473;876.5573385440312;894.217559635319;910.1035480052143;915.4781774388667;1071.8124328242527;13783.175888439167;13885.444582216598;14086;14108.15351078918;14124.646883827987;285938;291920;292001;292418;292939;47012.29481557779;47533.76233118882;47656;47659;47670.761646192244;1085.1156307609274;1094.7755710079414;1096.703889949725;1105.1766434006556;1134.6993046842592;25213;25706.485870282115;26183.869080655237;26221.475570488867;26264.21207363782;36561.26877462489;37634.8118259407;41256.79371603123;41686.791566041975;41698.374524382765;348224;353920;353923;356532;356782;29549;29957.550636740903;29986.550201746555;30042.24894377658;30047.399052019275;39305;39764;40576.59423405728;40625.79687101545;40809.79595102005;34168.31663366669;34774.47838282377;34794.478082829286;35145;38621.227575448895;34478.80699341765;34684.47973280352;35099.649003509636;35229;35365.646343537264;34717.82641086778;34887.476687849194;38505;38508;38655.22689546137;29939.850300748356;31294.37411251808;31905.8404707975;31929.68070319267;32012.359752805278;24803.87598061998;25033.499330013663;25065.87467062653;26660.333491662863;27145.86427067852 15:43:56 INFO - 2015-10-23 15:43:56,267 DEBUG : BROWSER_OUTPUT: |2;jquery.html;353.4450912068053;353.9115221194701;354.63118356908814;354.8420952676059;358.603742864135;12184.329861857594;12581.24512529249;15415.306311215994;15443.305051272691;15953.760693589606;14949.850501494982;15521.379144834222;15522.146281954516;15525.611859703513;15643.92178039111;4901.534354236351;4928.704277743329;5080.110980578403;5080.872978175538;5087.338645976018;3549.077239917627;3685.244524872408;5004.874878128039;5613.775448982035;5860.824175274749;5734.713264336788;5763.020286551276;5774.422557744234;5809.331926828406;5818.447247511501;5648.491635752782;5806.854828629318;5823.534117270629;5825.766969321222;5826.300843898759;5103.2855400244;5111.05445492585;5135.204043373283;5153.871153221199;5158.819441319536;18987.145578448864;19323.710144347846;19581.804181958283;19793.10931008108;19831.504212394662;1641.2122181352897;1651.5045486354165;1718.510224585987;1744.9563760906078;2491.314888405685;11970.700732481671;12402.25586464815;12607.117501774788;12670.366481675972;13180.53868114621;1822.1162736072995;1864.9440516784518;1905.557544655283;1932.9710054349196;1954.4332143678316;58306.12540811849;60955.69522152405;61102.388976110116;61185.082223767124;61592;14709.117452952643;15275.694486110215;15285.159316237714;15292.235388230543;15345.386184552493;22500.437489062904;22630.981605827776;22700;22740.772592274196;22748.545029099325;8087.069986951458;8089.878651820228;8144.511329320259;8163.510189388656;8246.422750407533;6966.512344135862;7022.754203602849;7025.367716905503;7040.823979400556;7075.186353569303;7359.411247100219;7450.590217538087;7453.180150183475;7467.7012919483695;7475.5888426136;1051.784384201237;1056.8837427882927;1071.59815069349;2057.5494276535005;3157.936841263208;387.58915549517604;388.97860617665737;394.15847166300154;408.4812288393783;446.06994416641663;9379.030403615181;9427.198688111588;10211.285210035374;10380.896191038144;10454.790904181873;413.30358346186773;419.38141241668905;420.7685772824934;422.1261987685523;444.50659767657874;5554.694491802989;5647.350554686182;5820.854478638068;5832.24180856486;5896.675682837399;440636;444413;445337;447658;472061.8381448562;66289.6685516579;67002.3299766996;67069.32930670728;67519.3248067513;68028.31971680319;472.8085125524168;480.74760750605515;480.9182438985381;481.8000529780172;482.01909114950905;9680.322377433513;9755.782977731178;9835.459049752333;10208.642697505551;10230;32136.999999999065;33200.66799332073;33229.50155747617;34015.829920850236;34017.31965360729;39513.802430987664;39889.2022159561;41048.79475602722;41731.7913410431;42950.14099717926;6047.848803779941;6088.391160883876;6149.508039356751;6165.599236049642;6172.105044768394;9165.17513423795;11232.213745038096;11351.829722554334;11456.427178640868;11479.942600286946;8337.95831020841;9331.906680933103;9453.149216570542;9589.616415343307;9651.469169195761;9308.162265396148;9494.335396522178;9575.329726919328;9624.470654113811;9741.123298903132;9598.376105553116;9622.422654640744;9777;9851.950740246253;10033.046860548237;5879.970600146972;5949.167116603592;5967.8209653710455;5969.283685957713;5992.340842507234;9500.144986951207;9616.855747163938;9761.902380976384;9795.118439340493;10024.949875250577 15:43:56 INFO - 2015-10-23 15:43:56,268 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1295.9352032398378;1446.8625480579349;1610.903345799252;1655.7516372544117;1825.9087045647723;7275.090613673291;7893.171217022209;8052.91947080529;8060.83878322434;8072.757817265491;7793.337566306856;7911.287984081431;7966.522008679483;7977.960110199441;7993.200679932019;2025.8480613953975;2050.4361300642367;2056.475598722324;2058.5471196336857;2058.886761228132;2039.5614942787297;2050.3541384463847;2061.5052387426986;2100.390886642872;2103.032605001701;2028.8072633099832;2077.83377329814;2084.801943815343;2091.6130515854616;2092.2677063027895;2043.5810658814908;2050.477128332273;2059.1763294682096;2060.4951786812176;2060.8763474191446;2162.362103179566;2178.237616834095;2206.5366273082673;2210.303754317385;2210.701555290031;6774.424173945221;7864.016997875266;7912.960435197845;7931.286184243446;8096.554689492075;3993.5407428145845;4448.5773851484055;4530.207213737583;4535.342375355555;4537.047220083787;2753.9586906196423;2813.4232482341276;2855.8857645694147;2855.9718501339503;2936.6182396288345;828.1015098618007;838.5849004742691;847.1316900177306;848.9702860399918;853.4964371021127;11970.22193557416;12046.09654275924;12057.879421205764;12079.758404831855;12088.093392995472;4305.935410968838;4353.172897149531;4356.716813407118;4364.62900653448;4451.597746709776;10164.898351016544;10214.540345684314;10223.897761022443;10249.38503689781;10261.897381026094;4588.242939914943;4589.426321709786;4591.380163677927;4599;4600.631949444037;4032.4556184915245;4037.232925744158;4043.3126368516832;4050.1292222172374;4050.7569545827346;3514.648535146465;3575.2670702505925;3589.892303230907;3611.0791748104066;3618.7696183297803;1157.7279339355246;1185.7035741064733;1192.2905871006737;1194.4386138514883;1194.4863708605383;1164.1443538998828;1178.8762179971152;1191.1780871199019;1205.614203454886;1208.6615747590688;7963.8407231855035;8098.028236611644;8117.228863258099;8127.674893004214;8133.7153199638915;1134.9432528373547;1136.1195073817692;1143.790849673194;1166.5683697032152;1168.351564881499;5571.4428557143965;6031.336552979169;6104.481119104926;6112.847178820476;6125.173101631222;9790.21678265737;10366.688999330032;10366.792664146826;10423;10547.789044219073;10007.199424046059;10250;10277.841653252752;10279.383237005804;10287.022732840369;7570.356519695777;7643.159252482223;7664.731734389381;7665.61671916402;7669.041369828771;7735;7867.095284042409;7921.881171782543;7926.978620451748;7944;8856.778580535538;9036.548172591212;9056.230220431074;9062.365634405529;9133.771655708395;9036.593353298986;9084.500352480676;9105.317101217302;9226.40028398152;9234.122758337948;7039;7080.362767350757;7169.4981351307;7181.82045448847;7185.568865868065;8137.511749295061;8210.794730131794;8213.425060245725;8216.507009579444;8221.917780822116;7587.658555365132;7631.3513351365755;7642.1593624702355;7704.499207551491;7764.34003109742;7472.178060413459;7489.812754681177;7644.694212231449;7667.233276672511;7712;7516.774496765106;7558.093028836574;7565.356944659546;7568.735094271674;7577.128630207597;6780.186377634518;7021.332973367522;7030.3321184487395;7041.155061392666;7043.471739619446;7133.17968433616;7208.098987626547;7223.1693355264815;7226.74706385274;7228.746993855189 15:43:56 INFO - 2015-10-23 15:43:56,269 DEBUG : BROWSER_OUTPUT: |4;prototype.html;305.91128572713905;314.6680252333788;318.79915653138517;322.591921219657;329.60282859154705;5199.272101905736;5218.165093585022;5225.372955245366;5227.947720522794;5229.60777941654;5115.641905066647;5129.204973229155;5129.9227162295965;5130.538251557359;5135.024345374386;3900.7854567998747;3966.246413181501;4006.298897692907;4085.5301640311445;4087.9182416351655;4048.554658987524;4075.7554546727292;4079.4084857695616;4084.8570300039505;4086.6730661547003;4036.0717035081802;4036.091879327157;4037.8586749463775;4042.757434553936;4043.130726893717;3849.3840985442334;3916.725829191958;3972.6226008529143;4042.6765858731524;4047.413125096874;3600.225951420431;3605.278944211142;3605.891823245307;3608.5128507651657;3615.981920090409;4936.48166942473;4936.48166942473;4937.456879743261;4939.728314942677;4946;4518.774061296955;4544.681872268942;4545.977270113662;4548.044910568785;4551.613112885409;5131.076406246912;5258.50044245796;5326.280952071459;5344.331958505187;5348.866278343034;3904.472896159039;3907.257621051991;3907.687385009221;3907.8632247871474;3907.902302442433;5627.915581266243;5633.887322253573;5635.8591035224035;5636.2954630671165;5637.8590535236945;4027.718059735849;4032.072623296658;4033.1328764315813;4033.4756481657196;4035.1727895781296;3997.200559888034;4088.100617864065;4100.507939047334;4109.815058322382;4117.711760176759;4585.633149348044;4587.449506059294;4590.036092420629;4590.288505281686;4591.79336929839;5326.227696983993;5342.1452567589;5343.091674415358;5343.545798607084;5345.679259244458;1465.8534146585257;1487.2415068315238;1488.1963739580722;1488.3600051977537;1488.5385530485585;1020.78563501664;1034.8551202831609;1049.6693541534391;1050.8791488978713;1052.752603138262;4431.40176076232;4433.7561434121435;4434.11317736454;4434.889127771831;4435.112977404532;5537.141743029837;5538.695371754511;5540.085885828793;5545;5545.251391062155;4412.051408947028;4422.248217802981;4426.601605855489;4430.047539778963;4434.711743736647;2851.8431486268455;2902.1873875314523;2972.212363723615;2991.536311871663;3026.8789248430257;5121.027004869063;5204.34945631796;5205.817796377184;5214.504622060898;5216.37403511581;5642.181883626851;5648.8870222595315;5655.943440565542;5657.490825825613;5659.830205093854;5193.818216362561;5200.6879587224885;5201.115810312181;5208.765605547759;5209.661372010807;5330.600204984564;5469.425710300361;5473.28847249855;5546.057170280981;5568.415316391721;5383.353997520323;5393.9730301348245;5401.4868587484125;5404.108322126805;5404.459554044564;5242.606804489602;5257.053730328577;5258.2375555544995;5275.155975043975;5283.2075188722;4011.9598804012753;4032.3145065338376;4033.0320723025675;4034.4351790748733;4038.818253178673;5437.864053398538;5561.138023606348;5573.6098473106495;5581.051221292471;5582.134769110794;5261.763220655156;5265;5271.367435907562;5273.551748101454;5274.498922602346;5290.444503327095;5290.550303224269;5293.841184764463;5294.100002999577;5296.179092240709;4976.651634385558;5066.36670416198;5115.5396014358885;5123.180291153398;5124.256982737556;5305.151175811851;5311.282976798083;5312.681239125664;5312.920306195488;5319.946800531945;4911.386076740408;5014.34813474246;5018.397792264952;5098.082345177755;5100.183970564692 15:43:56 INFO - 2015-10-23 15:43:56,271 DEBUG : BROWSER_OUTPUT: |5;yui.html;601.6239850093691;693.9867793022187;750.2197714377048;763.1805150474755;770.060526158087;10600.257981941268;10775.191860610446;10818.459077046156;10829.945850270757;10832.350058996446;9256.953715231415;10045.447500387476;10127.13919316859;10131.392116473016;10148;620.5190976992833;794.400227827989;816.9387295952828;819.4100247821559;819.7991492084448;802.3621221129216;803.1606970715616;803.3813963248296;808.3331251717327;809.8947136872227;656.6815094679076;666.6333516565894;829.5396055189367;838.8448137094626;839.3117643532306;830.7756905635475;844.1642773654099;847.9703210387669;848.7835601921502;848.859938110211;629.5907660020977;631.8104568629393;632.2097378277153;632.4371309534517;632.5824955529374;10923.508442120044;11030.338179709242;11055.11559075272;11068.557257709763;11078.501467433904;9584.616615335379;9985.550650220757;10237;10390.584376624925;10678.145748340192;8278.089410164877;8299.709510167175;8329.50022998622;8414.705485307984;8473;2656.0836511403536;2702.90539831105;2726.8091233613654;2752.119321817019;2782.6382570265937;31811.363772724417;32214.194645133826;32233.51649725256;32672.6732732672;32755.50866737001;8377.748667539983;8378.622961966727;8631.654733810577;8726.12738726135;8753.47479151253;14721.55835324942;14725.705485890436;14731.263436828116;14732;14764.26178691061;5088.389393272831;5122.948770512247;5148.382194136652;5281.973590132025;5288.9735551322;2933.897313594014;2947.64628244612;2948.7051294870344;3013.74383177428;3071.2014876132366;2420.152946468722;2475.5296493666146;2478.1450399612104;2514.396544829228;2558.1046633678066;441.90057237122033;445.5054889073126;448.53576548272383;458.16385097197747;459.15515451568604;375.63000444562084;407.52319785850625;506.7162389062137;524.0828550037435;537.3766430940091;7065.258147894499;7349.375303099297;7558.319751222306;7802.453828232083;7939.126696063428;430.9030468144705;456.2608574109963;523.9580833533308;526.0058489454933;559.3371854352524;3265.2000259935994;3301.108700650811;3304.553885225464;3400.1159698478077;3649.835757390924;41250.79374603108;41390.1721965565;41441;41473;41483.7925810375;34699.13252168716;35172;35429.82285088558;35435.468467973;35497.46753798695;29580;30183.245418864703;30228.24429389239;30466.695333046828;31376.058718238488;7139.286071392819;7277.963610181915;7308.123025237006;7330.6701198445135;7384;22858.085676573417;23131.653025204298;23505.29484115479;23553.41116472102;23579.882100589388;30820.691793081784;31208.68791312149;31219.37561248808;31254.531182032744;31293.530597040608;6373.458256048102;6593.670316484061;6617.272100069084;6628.900566491603;6648.202215734143;9937.354071985297;9992.60029598837;10145.442000689738;10235.078842904175;10243.846342304721;9495.525223738647;9751.512424378894;9782.461964592016;9806.75483112928;9808.65669701557;9006.864897026418;9077.319201059814;9275.62897484093;9297;9480.099390557885;8850.601722922354;9118.635254589743;9174.220191283815;9202;9262.756147029284;8560.186782255676;8570.302325697416;8702.608382622924;8704.477731336141;8734.388592798443;8205.548694821842;8251.174882511701;8320.417570769989;8400.747977560683;8464.873026904725 15:43:56 INFO - 2015-10-23 15:43:56,271 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:43:56 INFO - 2015-10-23 15:43:56,271 DEBUG : BROWSER_OUTPUT: __start_cc_report 15:43:56 INFO - 2015-10-23 15:43:56,271 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,899 15:43:56 INFO - 2015-10-23 15:43:56,271 DEBUG : BROWSER_OUTPUT: __end_cc_report 15:43:56 INFO - 2015-10-23 15:43:56,271 DEBUG : BROWSER_OUTPUT: __startTimestamp1445640236259__endTimestamp 15:43:56 INFO - 2015-10-23 15:43:56,271 DEBUG : BROWSER_OUTPUT: 15:43:56 INFO - 2015-10-23 15:43:56,271 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:43:56 INFO - 2015-10-23 15:43:56,272 DEBUG : BROWSER_OUTPUT: Number of tests: 6 15:43:56 INFO - 2015-10-23 15:43:56,272 DEBUG : BROWSER_OUTPUT: 15:43:56 INFO - 2015-10-23 15:43:56,272 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4765.05 Median:4894.31 stddev:513.33 (10.5%) stddev-sans-first:487.33 15:43:56 INFO - 2015-10-23 15:43:56,272 DEBUG : BROWSER_OUTPUT: Values: 2648.9 2732.7 2759.7 2764.8 2765.8 4749.7 4845.4 4956.7 4989.0 5008.5 4850.0 4862.7 4948.0 4958.8 4988.8 4477.2 4486.4 4518.3 4581.5 4627.6 4349.3 4559.6 4567.9 4590.0 4612.7 4437.0 4449.2 4478.7 4572.6 4599.1 4532.2 4532.3 4537.9 4539.6 4577.3 4375.2 4520.4 4531.3 4539.2 4577.0 4733.1 4804.0 4841.7 4851.1 4859.7 5172.9 5175.1 5177.1 5178.7 5179.4 5034.7 5192.6 5196.0 5196.6 5250.8 4698.6 4788.0 4803.4 4806.9 4825.7 5364.7 5372.7 5383.6 5386.8 5391.1 4788.0 5133.7 5146.7 5148.9 5197.5 5149.7 5163.5 5168.9 5261.4 5320.9 5178.2 5192.2 5194.3 5204.9 5258.4 4867.2 5172.9 5176.7 5183.8 5187.6 4251.7 4286.7 4314.7 4315.7 4351.1 3751.6 3755.2 3755.5 3782.2 3804.4 4181.4 4185.7 4195.2 4197.5 4234.5 4911.0 5013.5 5039.8 5086.9 5195.2 4139.7 4148.1 4173.2 4182.4 4197.5 4622.4 4628.9 4629.0 4641.4 4669.6 4691.8 4796.3 4796.5 4802.6 4872.1 5219.3 5251.9 5259.8 5260.6 5263.6 4936.7 4953.7 4970.2 4970.9 4997.2 4940.7 5095.6 5097.7 5111.2 5121.7 5091.4 5147.1 5156.3 5200.9 5214.9 4906.5 4959.2 4984.7 4984.8 5026.2 5012.2 5028.1 5034.0 5036.2 5036.6 4614.8 4737.4 4741.5 4756.5 4883.0 5055.5 5057.5 5068.5 5071.9 5079.2 4892.2 4894.3 4905.6 4909.8 4935.8 15:43:56 INFO - 2015-10-23 15:43:56,272 DEBUG : BROWSER_OUTPUT: 15:43:56 INFO - 2015-10-23 15:43:56,272 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:45608.34 Median:30670.89 stddev:78118.67 (254.7%) stddev-sans-first:78264.40 15:43:56 INFO - 2015-10-23 15:43:56,273 DEBUG : BROWSER_OUTPUT: Values: 502.7 506.5 509.1 537.9 546.8 28764.4 37176.3 37650.4 37664.8 37716.4 40749.4 41369.0 41385.4 41407.4 41458.8 7139.1 7246.7 7951.4 8125.2 8126.3 10181.7 10455.6 10457.9 10459.7 10468.2 10044.5 10140.3 10156.6 10168.8 10174.9 7329.7 7530.3 10216.8 10221.1 10528.8 10110.8 10271.3 10279.6 10291.1 10292.5 38631.0 39217.6 39270.2 39271.2 39272.2 2733.7 2752.9 2769.3 2789.2 2794.9 28599.1 28790.9 28811.4 28816.7 29455.0 2607.9 2621.7 2630.2 2671.2 2760.0 235516.0 237022.0 237239.0 239819.0 240078.0 53126.5 54216.5 57024.1 57521.7 62884.0 58764.4 58787.0 59159.4 59205.7 59217.4 14299.9 14487.3 14499.3 14554.6 14588.9 10839.6 10970.4 10990.9 10991.0 11025.7 19132.2 19171.4 19172.4 19176.4 19304.7 8138.1 8364.0 8414.1 8446.1 8446.5 756.6 839.7 854.2 877.2 939.3 26344.2 26364.3 26454.1 26493.6 26583.9 876.6 894.2 910.1 915.5 1071.8 13783.2 13885.4 14086.0 14108.2 14124.6 285938.0 291920.0 292001.0 292418.0 292939.0 47012.3 47533.8 47656.0 47659.0 47670.8 1085.1 1094.8 1096.7 1105.2 1134.7 25213.0 25706.5 26183.9 26221.5 26264.2 36561.3 37634.8 41256.8 41686.8 41698.4 348224.0 353920.0 353923.0 356532.0 356782.0 29549.0 29957.6 29986.6 30042.2 30047.4 39305.0 39764.0 40576.6 40625.8 40809.8 34168.3 34774.5 34794.5 35145.0 38621.2 34478.8 34684.5 35099.6 35229.0 35365.6 34717.8 34887.5 38505.0 38508.0 38655.2 29939.9 31294.4 31905.8 31929.7 32012.4 24803.9 25033.5 25065.9 26660.3 27145.9 15:43:56 INFO - 2015-10-23 15:43:56,274 DEBUG : BROWSER_OUTPUT: 15:43:56 INFO - 2015-10-23 15:43:56,274 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:24861.53 Median:481.36 stddev:73656.71 (15301.8%) stddev-sans-first:73840.35 15:43:56 INFO - 2015-10-23 15:43:56,274 DEBUG : BROWSER_OUTPUT: Values: 353.4 353.9 354.6 354.8 358.6 12184.3 12581.2 15415.3 15443.3 15953.8 14949.9 15521.4 15522.1 15525.6 15643.9 4901.5 4928.7 5080.1 5080.9 5087.3 3549.1 3685.2 5004.9 5613.8 5860.8 5734.7 5763.0 5774.4 5809.3 5818.4 5648.5 5806.9 5823.5 5825.8 5826.3 5103.3 5111.1 5135.2 5153.9 5158.8 18987.1 19323.7 19581.8 19793.1 19831.5 1641.2 1651.5 1718.5 1745.0 2491.3 11970.7 12402.3 12607.1 12670.4 13180.5 1822.1 1864.9 1905.6 1933.0 1954.4 58306.1 60955.7 61102.4 61185.1 61592.0 14709.1 15275.7 15285.2 15292.2 15345.4 22500.4 22631.0 22700.0 22740.8 22748.5 8087.1 8089.9 8144.5 8163.5 8246.4 6966.5 7022.8 7025.4 7040.8 7075.2 7359.4 7450.6 7453.2 7467.7 7475.6 1051.8 1056.9 1071.6 2057.5 3157.9 387.6 389.0 394.2 408.5 446.1 9379.0 9427.2 10211.3 10380.9 10454.8 413.3 419.4 420.8 422.1 444.5 5554.7 5647.4 5820.9 5832.2 5896.7 440636.0 444413.0 445337.0 447658.0 472061.8 66289.7 67002.3 67069.3 67519.3 68028.3 472.8 480.7 480.9 481.8 482.0 9680.3 9755.8 9835.5 10208.6 10230.0 32137.0 33200.7 33229.5 34015.8 34017.3 39513.8 39889.2 41048.8 41731.8 42950.1 6047.8 6088.4 6149.5 6165.6 6172.1 9165.2 11232.2 11351.8 11456.4 11479.9 8338.0 9331.9 9453.1 9589.6 9651.5 9308.2 9494.3 9575.3 9624.5 9741.1 9598.4 9622.4 9777.0 9852.0 10033.0 5880.0 5949.2 5967.8 5969.3 5992.3 9500.1 9616.9 9761.9 9795.1 10024.9 15:43:56 INFO - 2015-10-23 15:43:56,274 DEBUG : BROWSER_OUTPUT: 15:43:56 INFO - 2015-10-23 15:43:56,275 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5739.63 Median:4590.40 stddev:3190.12 (69.5%) stddev-sans-first:3181.58 15:43:56 INFO - 2015-10-23 15:43:56,275 DEBUG : BROWSER_OUTPUT: Values: 1295.9 1446.9 1610.9 1655.8 1825.9 7275.1 7893.2 8052.9 8060.8 8072.8 7793.3 7911.3 7966.5 7978.0 7993.2 2025.8 2050.4 2056.5 2058.5 2058.9 2039.6 2050.4 2061.5 2100.4 2103.0 2028.8 2077.8 2084.8 2091.6 2092.3 2043.6 2050.5 2059.2 2060.5 2060.9 2162.4 2178.2 2206.5 2210.3 2210.7 6774.4 7864.0 7913.0 7931.3 8096.6 3993.5 4448.6 4530.2 4535.3 4537.0 2754.0 2813.4 2855.9 2856.0 2936.6 828.1 838.6 847.1 849.0 853.5 11970.2 12046.1 12057.9 12079.8 12088.1 4305.9 4353.2 4356.7 4364.6 4451.6 10164.9 10214.5 10223.9 10249.4 10261.9 4588.2 4589.4 4591.4 4599.0 4600.6 4032.5 4037.2 4043.3 4050.1 4050.8 3514.6 3575.3 3589.9 3611.1 3618.8 1157.7 1185.7 1192.3 1194.4 1194.5 1164.1 1178.9 1191.2 1205.6 1208.7 7963.8 8098.0 8117.2 8127.7 8133.7 1134.9 1136.1 1143.8 1166.6 1168.4 5571.4 6031.3 6104.5 6112.8 6125.2 9790.2 10366.7 10366.8 10423.0 10547.8 10007.2 10250.0 10277.8 10279.4 10287.0 7570.4 7643.2 7664.7 7665.6 7669.0 7735.0 7867.1 7921.9 7927.0 7944.0 8856.8 9036.5 9056.2 9062.4 9133.8 9036.6 9084.5 9105.3 9226.4 9234.1 7039.0 7080.4 7169.5 7181.8 7185.6 8137.5 8210.8 8213.4 8216.5 8221.9 7587.7 7631.4 7642.2 7704.5 7764.3 7472.2 7489.8 7644.7 7667.2 7712.0 7516.8 7558.1 7565.4 7568.7 7577.1 6780.2 7021.3 7030.3 7041.2 7043.5 7133.2 7208.1 7223.2 7226.7 7228.7 15:43:56 INFO - 2015-10-23 15:43:56,275 DEBUG : BROWSER_OUTPUT: 15:43:56 INFO - 2015-10-23 15:43:56,276 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4453.72 Median:4961.33 stddev:1257.96 (25.4%) stddev-sans-first:1222.36 15:43:56 INFO - 2015-10-23 15:43:56,277 DEBUG : BROWSER_OUTPUT: Values: 305.9 314.7 318.8 322.6 329.6 5199.3 5218.2 5225.4 5227.9 5229.6 5115.6 5129.2 5129.9 5130.5 5135.0 3900.8 3966.2 4006.3 4085.5 4087.9 4048.6 4075.8 4079.4 4084.9 4086.7 4036.1 4036.1 4037.9 4042.8 4043.1 3849.4 3916.7 3972.6 4042.7 4047.4 3600.2 3605.3 3605.9 3608.5 3616.0 4936.5 4936.5 4937.5 4939.7 4946.0 4518.8 4544.7 4546.0 4548.0 4551.6 5131.1 5258.5 5326.3 5344.3 5348.9 3904.5 3907.3 3907.7 3907.9 3907.9 5627.9 5633.9 5635.9 5636.3 5637.9 4027.7 4032.1 4033.1 4033.5 4035.2 3997.2 4088.1 4100.5 4109.8 4117.7 4585.6 4587.4 4590.0 4590.3 4591.8 5326.2 5342.1 5343.1 5343.5 5345.7 1465.9 1487.2 1488.2 1488.4 1488.5 1020.8 1034.9 1049.7 1050.9 1052.8 4431.4 4433.8 4434.1 4434.9 4435.1 5537.1 5538.7 5540.1 5545.0 5545.3 4412.1 4422.2 4426.6 4430.0 4434.7 2851.8 2902.2 2972.2 2991.5 3026.9 5121.0 5204.3 5205.8 5214.5 5216.4 5642.2 5648.9 5655.9 5657.5 5659.8 5193.8 5200.7 5201.1 5208.8 5209.7 5330.6 5469.4 5473.3 5546.1 5568.4 5383.4 5394.0 5401.5 5404.1 5404.5 5242.6 5257.1 5258.2 5275.2 5283.2 4012.0 4032.3 4033.0 4034.4 4038.8 5437.9 5561.1 5573.6 5581.1 5582.1 5261.8 5265.0 5271.4 5273.6 5274.5 5290.4 5290.6 5293.8 5294.1 5296.2 4976.7 5066.4 5115.5 5123.2 5124.3 5305.2 5311.3 5312.7 5312.9 5319.9 4911.4 5014.3 5018.4 5098.1 5100.2 15:43:56 INFO - 2015-10-23 15:43:56,277 DEBUG : BROWSER_OUTPUT: 15:43:56 INFO - 2015-10-23 15:43:56,277 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:10186.95 Median:2903.99 stddev:10874.38 (374.5%) stddev-sans-first:10881.06 15:43:56 INFO - 2015-10-23 15:43:56,279 DEBUG : BROWSER_OUTPUT: Values: 601.6 694.0 750.2 763.2 770.1 10600.3 10775.2 10818.5 10829.9 10832.4 9257.0 10045.4 10127.1 10131.4 10148.0 620.5 794.4 816.9 819.4 819.8 802.4 803.2 803.4 808.3 809.9 656.7 666.6 829.5 838.8 839.3 830.8 844.2 848.0 848.8 848.9 629.6 631.8 632.2 632.4 632.6 10923.5 11030.3 11055.1 11068.6 11078.5 9584.6 9985.6 10237.0 10390.6 10678.1 8278.1 8299.7 8329.5 8414.7 8473.0 2656.1 2702.9 2726.8 2752.1 2782.6 31811.4 32214.2 32233.5 32672.7 32755.5 8377.7 8378.6 8631.7 8726.1 8753.5 14721.6 14725.7 14731.3 14732.0 14764.3 5088.4 5122.9 5148.4 5282.0 5289.0 2933.9 2947.6 2948.7 3013.7 3071.2 2420.2 2475.5 2478.1 2514.4 2558.1 441.9 445.5 448.5 458.2 459.2 375.6 407.5 506.7 524.1 537.4 7065.3 7349.4 7558.3 7802.5 7939.1 430.9 456.3 524.0 526.0 559.3 3265.2 3301.1 3304.6 3400.1 3649.8 41250.8 41390.2 41441.0 41473.0 41483.8 34699.1 35172.0 35429.8 35435.5 35497.5 29580.0 30183.2 30228.2 30466.7 31376.1 7139.3 7278.0 7308.1 7330.7 7384.0 22858.1 23131.7 23505.3 23553.4 23579.9 30820.7 31208.7 31219.4 31254.5 31293.5 6373.5 6593.7 6617.3 6628.9 6648.2 9937.4 9992.6 10145.4 10235.1 10243.8 9495.5 9751.5 9782.5 9806.8 9808.7 9006.9 9077.3 9275.6 9297.0 9480.1 8850.6 9118.6 9174.2 9202.0 9262.8 8560.2 8570.3 8702.6 8704.5 8734.4 8205.5 8251.2 8320.4 8400.7 8464.9 15:43:56 INFO - 2015-10-23 15:43:56,279 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:43:56 INFO - 2015-10-23 15:43:56,279 DEBUG : BROWSER_OUTPUT: 15:43:56 INFO - 2015-10-23 15:43:56,937 INFO : Browser exited with error code: 0 15:43:56 INFO - 2015-10-23 15:43:56,961 INFO : Completed test dromaeo_css (00:18:41) 15:43:56 INFO - 2015-10-23 15:43:56,961 INFO : Starting test kraken 15:43:56 INFO - 2015-10-23 15:43:56,961 DEBUG : operating with platform_type : linux_ 15:43:56 INFO - 2015-10-23 15:43:56,961 INFO : Initialising browser for kraken test... 15:43:56 INFO - 2015-10-23 15:43:56,967 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYFNZyi/profile http://localhost:56946/getInfo.html 15:44:01 INFO - 2015-10-23 15:44:01,117 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:44:01 INFO - 2015-10-23 15:44:01,118 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:44:01 INFO - 2015-10-23 15:44:01,118 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:44:01 INFO - 2015-10-23 15:44:01,118 DEBUG : BROWSER_OUTPUT: __metrics 15:44:01 INFO - 2015-10-23 15:44:01,234 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:44:02 INFO - 2015-10-23 15:44:02,387 INFO : Browser initialized. 15:44:02 INFO - 2015-10-23 15:44:02,388 INFO : Running cycle 1/1 for kraken test... 15:44:02 INFO - 2015-10-23 15:44:02,388 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYFNZyi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:44:14 INFO - 2015-10-23 15:44:14,373 DEBUG : BROWSER_OUTPUT: RSS: Main: 141692928 15:44:14 INFO - 2015-10-23 15:44:14,373 DEBUG : BROWSER_OUTPUT: 15:44:17 INFO - 2015-10-23 15:44:17,035 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56946/tests/kraken/driver.html?testName=audio-beat-detection) 15:44:17 INFO - 2015-10-23 15:44:17,621 DEBUG : BROWSER_OUTPUT: RSS: Main: 177643520 15:44:17 INFO - 2015-10-23 15:44:17,621 DEBUG : BROWSER_OUTPUT: 15:44:20 INFO - 2015-10-23 15:44:20,270 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56946/tests/kraken/driver.html?testName=audio-dft) 15:44:20 INFO - 2015-10-23 15:44:20,843 DEBUG : BROWSER_OUTPUT: RSS: Main: 172474368 15:44:20 INFO - 2015-10-23 15:44:20,843 DEBUG : BROWSER_OUTPUT: 15:44:23 INFO - 2015-10-23 15:44:23,845 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56946/tests/kraken/driver.html?testName=audio-fft) 15:44:24 INFO - 2015-10-23 15:44:24,419 DEBUG : BROWSER_OUTPUT: RSS: Main: 152256512 15:44:24 INFO - 2015-10-23 15:44:24,419 DEBUG : BROWSER_OUTPUT: 15:44:26 INFO - 2015-10-23 15:44:26,375 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56946/tests/kraken/driver.html?testName=audio-oscillator) 15:44:26 INFO - 2015-10-23 15:44:26,948 DEBUG : BROWSER_OUTPUT: RSS: Main: 154353664 15:44:26 INFO - 2015-10-23 15:44:26,948 DEBUG : BROWSER_OUTPUT: 15:44:29 INFO - 2015-10-23 15:44:29,182 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56946/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:44:29 INFO - 2015-10-23 15:44:29,743 DEBUG : BROWSER_OUTPUT: RSS: Main: 160694272 15:44:29 INFO - 2015-10-23 15:44:29,743 DEBUG : BROWSER_OUTPUT: 15:44:32 INFO - 2015-10-23 15:44:32,715 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56946/tests/kraken/driver.html?testName=imaging-darkroom) 15:44:33 INFO - 2015-10-23 15:44:33,288 DEBUG : BROWSER_OUTPUT: RSS: Main: 155344896 15:44:33 INFO - 2015-10-23 15:44:33,288 DEBUG : BROWSER_OUTPUT: 15:44:36 INFO - 2015-10-23 15:44:36,699 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56946/tests/kraken/driver.html?testName=imaging-desaturate) 15:44:37 INFO - 2015-10-23 15:44:37,272 DEBUG : BROWSER_OUTPUT: RSS: Main: 153055232 15:44:37 INFO - 2015-10-23 15:44:37,272 DEBUG : BROWSER_OUTPUT: 15:44:40 INFO - 2015-10-23 15:44:40,111 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56946/tests/kraken/driver.html?testName=json-parse-financial) 15:44:40 INFO - 2015-10-23 15:44:40,680 DEBUG : BROWSER_OUTPUT: RSS: Main: 156143616 15:44:40 INFO - 2015-10-23 15:44:40,680 DEBUG : BROWSER_OUTPUT: 15:44:42 INFO - 2015-10-23 15:44:42,397 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56946/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:44:42 INFO - 2015-10-23 15:44:42,966 DEBUG : BROWSER_OUTPUT: RSS: Main: 163463168 15:44:42 INFO - 2015-10-23 15:44:42,966 DEBUG : BROWSER_OUTPUT: 15:44:44 INFO - 2015-10-23 15:44:44,858 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56946/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:44:45 INFO - 2015-10-23 15:44:45,431 DEBUG : BROWSER_OUTPUT: RSS: Main: 164876288 15:44:45 INFO - 2015-10-23 15:44:45,432 DEBUG : BROWSER_OUTPUT: 15:44:47 INFO - 2015-10-23 15:44:47,571 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56946/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:44:48 INFO - 2015-10-23 15:44:48,152 DEBUG : BROWSER_OUTPUT: RSS: Main: 174432256 15:44:48 INFO - 2015-10-23 15:44:48,153 DEBUG : BROWSER_OUTPUT: 15:44:50 INFO - 2015-10-23 15:44:50,640 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56946/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:44:51 INFO - 2015-10-23 15:44:51,224 DEBUG : BROWSER_OUTPUT: RSS: Main: 180121600 15:44:51 INFO - 2015-10-23 15:44:51,224 DEBUG : BROWSER_OUTPUT: 15:44:53 INFO - 2015-10-23 15:44:53,759 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56946/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:44:54 INFO - 2015-10-23 15:44:54,329 DEBUG : BROWSER_OUTPUT: RSS: Main: 178814976 15:44:54 INFO - 2015-10-23 15:44:54,329 DEBUG : BROWSER_OUTPUT: 15:44:56 INFO - 2015-10-23 15:44:56,099 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56946/tests/kraken/driver.html?testName=ai-astar) 15:44:56 INFO - 2015-10-23 15:44:56,349 DEBUG : BROWSER_OUTPUT: RSS: Main: 232181760 15:44:56 INFO - 2015-10-23 15:44:56,349 DEBUG : BROWSER_OUTPUT: 15:44:56 INFO - 2015-10-23 15:44:56,352 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:44:56 INFO - 2015-10-23 15:44:56,352 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:44:56 INFO - 2015-10-23 15:44:56,352 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |0;ai-astar;113;114;107;113;114;113;102;112;107;115 15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;134;132;138;148;138;139;131;128;156;129 15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;159;157;158;157;157;158;158;164;162 15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;70;72;82;71;71;70;85;79;73 15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;95;132;94;93;94;128;93;94;131 15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;111;110;110;109;109;110;110 15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;148;148;147;148;147;148;147;147;148 15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;95;96;95;96;95;96;95 15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;69;69;68;69;69;68;68;78;66;67 15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;70;69;69;69;77;69;68;71;69;72 15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;86;84;84;83;84;84;91;85;87;84 15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;118;120;127;118;124;119;120;127;120;126 15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;131;142;154;141;150;140;140;140;144;141 15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;73;72;74;72;72;70;71;72;70;71 15:44:56 INFO - 2015-10-23 15:44:56,354 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:44:56 INFO - 2015-10-23 15:44:56,354 DEBUG : BROWSER_OUTPUT: __start_cc_report 15:44:56 INFO - 2015-10-23 15:44:56,354 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,932 15:44:56 INFO - 2015-10-23 15:44:56,354 DEBUG : BROWSER_OUTPUT: __end_cc_report 15:44:56 INFO - 2015-10-23 15:44:56,354 DEBUG : BROWSER_OUTPUT: __startTimestamp1445640296351__endTimestamp 15:44:56 INFO - 2015-10-23 15:44:56,354 DEBUG : BROWSER_OUTPUT: 15:44:56 INFO - 2015-10-23 15:44:56,357 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:44:56 INFO - 2015-10-23 15:44:56,357 DEBUG : BROWSER_OUTPUT: Number of tests: 14 15:44:56 INFO - 2015-10-23 15:44:56,357 DEBUG : BROWSER_OUTPUT: 15:44:56 INFO - 2015-10-23 15:44:56,357 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.00 Median:113.00 stddev:4.22 (3.7%) stddev-sans-first:4.41 15:44:56 INFO - 2015-10-23 15:44:56,357 DEBUG : BROWSER_OUTPUT: Values: 113.0 114.0 107.0 113.0 114.0 113.0 102.0 112.0 107.0 115.0 15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: 15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:137.30 Median:138.00 stddev:8.83 (6.4%) stddev-sans-first:9.29 15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: Values: 134.0 132.0 138.0 148.0 138.0 139.0 131.0 128.0 156.0 129.0 15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: 15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:158.90 Median:158.50 stddev:2.33 (1.5%) stddev-sans-first:2.47 15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: Values: 159.0 159.0 157.0 158.0 157.0 157.0 158.0 158.0 164.0 162.0 15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: 15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:74.40 Median:72.50 stddev:5.50 (7.6%) stddev-sans-first:5.70 15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: Values: 71.0 70.0 72.0 82.0 71.0 71.0 70.0 85.0 79.0 73.0 15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: 15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:104.70 Median:93.50 stddev:17.73 (19.0%) stddev-sans-first:18.29 15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: Values: 93.0 95.0 132.0 94.0 93.0 94.0 128.0 93.0 94.0 131.0 15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: 15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.90 Median:110.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 111.0 110.0 110.0 109.0 109.0 110.0 110.0 15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: 15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.50 Median:148.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: Values: 147.0 148.0 148.0 147.0 148.0 147.0 148.0 147.0 147.0 148.0 15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: 15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 95.0 96.0 95.0 96.0 95.0 96.0 95.0 15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: 15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:69.10 Median:69.00 stddev:3.28 (4.8%) stddev-sans-first:3.48 15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: Values: 69.0 69.0 68.0 69.0 69.0 68.0 68.0 78.0 66.0 67.0 15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: 15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:69.50 stddev:2.63 (3.8%) stddev-sans-first:2.78 15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: Values: 70.0 69.0 69.0 69.0 77.0 69.0 68.0 71.0 69.0 72.0 15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: 15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:84.50 stddev:2.35 (2.8%) stddev-sans-first:2.47 15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: Values: 86.0 84.0 84.0 83.0 84.0 84.0 91.0 85.0 87.0 84.0 15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: 15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:121.90 Median:122.00 stddev:3.70 (3.0%) stddev-sans-first:3.64 15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT: Values: 118.0 120.0 127.0 118.0 124.0 119.0 120.0 127.0 120.0 126.0 15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT: 15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:142.30 Median:141.50 stddev:6.20 (4.4%) stddev-sans-first:5.05 15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT: Values: 131.0 142.0 154.0 141.0 150.0 140.0 140.0 140.0 144.0 141.0 15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT: 15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.70 Median:72.00 stddev:1.25 (1.7%) stddev-sans-first:1.24 15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT: Values: 73.0 72.0 74.0 72.0 72.0 70.0 71.0 72.0 70.0 71.0 15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT: 15:44:57 INFO - 2015-10-23 15:44:57,089 INFO : Browser exited with error code: 0 15:44:57 INFO - 2015-10-23 15:44:57,142 INFO : Completed test kraken (00:01:00) 15:44:57 INFO - 2015-10-23 15:44:57,142 INFO : Starting test v8_7 15:44:57 INFO - 2015-10-23 15:44:57,142 DEBUG : operating with platform_type : linux_ 15:44:57 INFO - 2015-10-23 15:44:57,142 INFO : Initialising browser for v8_7 test... 15:44:57 INFO - 2015-10-23 15:44:57,148 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpE3i7VW/profile http://localhost:56946/getInfo.html 15:45:01 INFO - 2015-10-23 15:45:01,439 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:45:01 INFO - 2015-10-23 15:45:01,439 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:45:01 INFO - 2015-10-23 15:45:01,439 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:45:01 INFO - 2015-10-23 15:45:01,439 DEBUG : BROWSER_OUTPUT: __metrics 15:45:01 INFO - 2015-10-23 15:45:01,515 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:45:02 INFO - 2015-10-23 15:45:02,245 DEBUG : BROWSER_OUTPUT: console.error: 15:45:02 INFO - 2015-10-23 15:45:02,246 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] 15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: Stack: 15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:791:13 15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 15:45:02 INFO - 2015-10-23 15:45:02,248 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 15:45:02 INFO - 2015-10-23 15:45:02,248 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 15:45:02 INFO - 2015-10-23 15:45:02,248 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 15:45:02 INFO - 2015-10-23 15:45:02,248 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 6229.85 15:45:38 INFO - RETURN: kraken: 1499.80 15:45:38 INFO - RETURN: v8_7: 19893.62 15:45:38 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,203,35]]", "result": "19893.62"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,203,35]]", "result": "1499.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,203,35]]", "result": "6229.85"}}} 15:45:38 INFO - Return code: 0 15:45:38 INFO - # TBPL SUCCESS # 15:45:38 INFO - Running post-action listener: _resource_record_post_action 15:45:38 INFO - Running post-run listener: _resource_record_post_run 15:45:39 INFO - Total resource usage - Wall time: 1237s; CPU: 13.0%; Read bytes: 7880704; Write bytes: 410234880; Read time: 1276; Write time: 502376 15:45:39 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 9506816; Read time: 0; Write time: 3248 15:45:39 INFO - run-tests - Wall time: 1223s; CPU: 13.0%; Read bytes: 5644288; Write bytes: 400728064; Read time: 1120; Write time: 499128 15:45:39 INFO - Running post-run listener: _upload_blobber_files 15:45:39 INFO - Blob upload gear active. 15:45:39 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 15:45:39 INFO - Copying logs to upload dir... 15:45:39 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1276.138925 ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2015-10-23 15:45:39.996741) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-23 15:45:40.000226) ========= 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=0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009882 build_url: 'https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/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-23 15:45:40.252292) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:45:40.252699) ========= 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=0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004550 ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:45:40.509184) ========= ========= Started reboot slave lost (results: 0, elapsed: 3 secs) (at 2015-10-23 15:45:40.509545) ========= ========= Finished reboot slave lost (results: 0, elapsed: 3 secs) (at 2015-10-23 15:45:43.728722) =========