builder: b2g-inbound_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-049 starttime: 1448000285.44 results: success (0) buildid: 20151119211235 builduid: f2d6b3911cb741aa9e3a3e1d0e77dd7a revision: eb83bfa5f73945d1f28d6ba6da8080d6e1b5f26f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 22:18:05.439065) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 22:18:05.439510) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 22:18:05.439859) ========= 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=0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.007662 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 22:18:05.463595) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 22:18:05.463908) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 22:18:05.479399) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 22:18:05.479764) ========= 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=0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004649 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 22:18:05.529248) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 22:18:05.529530) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 22:18:05.529890) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 22:18:05.530171) ========= 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=0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-19 22:18:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 73.7M=0s 2015-11-19 22:18:06 (73.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.203286 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 22:18:05.745902) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 22:18:05.746221) ========= 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=0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015068 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 22:18:05.798662) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-19 22:18:05.799162) ========= bash -c 'python archiver_client.py mozharness --repo integration/b2g-inbound --rev eb83bfa5f73945d1f28d6ba6da8080d6e1b5f26f --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 eb83bfa5f73945d1f28d6ba6da8080d6e1b5f26f --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=0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-19 22:18:06,839 truncating revision to first 12 chars 2015-11-19 22:18:06,839 Setting DEBUG logging. 2015-11-19 22:18:06,839 attempt 1/10 2015-11-19 22:18:06,839 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/eb83bfa5f739?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-19 22:18:07,959 unpacking tar archive at: b2g-inbound-eb83bfa5f739/testing/mozharness/ program finished with exit code 0 elapsedTime=1.401090 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-19 22:18:07.213007) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 22:18:07.213389) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 22:18:07.221531) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 22:18:07.221839) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 22:18:07.222245) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2015-11-19 22:18:07.222544) ========= /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=0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:18:08 INFO - MultiFileLogger online at 20151119 22:18:08 in /builds/slave/test 22:18:08 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 22:18:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:18:08 INFO - {'append_to_log': False, 22:18:08 INFO - 'base_work_dir': '/builds/slave/test', 22:18:08 INFO - 'blob_upload_branch': 'B2G-Inbound-Non-PGO', 22:18:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:18:08 INFO - 'branch': 'B2G-Inbound-Non-PGO', 22:18:08 INFO - 'buildbot_json_path': 'buildprops.json', 22:18:08 INFO - 'config_files': ('talos/linux_config.py',), 22:18:08 INFO - 'default_actions': ('clobber', 22:18:08 INFO - 'read-buildbot-config', 22:18:08 INFO - 'download-and-extract', 22:18:08 INFO - 'populate-webroot', 22:18:08 INFO - 'create-virtualenv', 22:18:08 INFO - 'install', 22:18:08 INFO - 'run-tests'), 22:18:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:18:08 INFO - 'download_minidump_stackwalk': True, 22:18:08 INFO - 'download_symbols': 'ondemand', 22:18:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:18:08 INFO - 'tooltool.py': '/tools/tooltool.py', 22:18:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:18:08 INFO - '/tools/misc-python/virtualenv.py')}, 22:18:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:18:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:18:08 INFO - 'installer_path': 'installer.exe', 22:18:08 INFO - 'log_level': 'info', 22:18:08 INFO - 'log_name': 'talos', 22:18:08 INFO - 'log_to_console': True, 22:18:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:18:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:18:08 INFO - 'opt_config_files': (), 22:18:08 INFO - 'pip_index': False, 22:18:08 INFO - 'sps_profile': False, 22:18:08 INFO - 'sps_profile_interval': 0, 22:18:08 INFO - 'suite': 'dromaeojs', 22:18:08 INFO - 'system_bits': '64', 22:18:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:18:08 INFO - 'title': 'talos-linux64-ix-049', 22:18:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:18:08 INFO - 'use_talos_json': True, 22:18:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:18:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:18:08 INFO - 'work_dir': 'build'} 22:18:08 INFO - ##### 22:18:08 INFO - ##### Running clobber step. 22:18:08 INFO - ##### 22:18:08 INFO - Running pre-action listener: _resource_record_pre_action 22:18:08 INFO - Running main action method: clobber 22:18:08 INFO - rmtree: /builds/slave/test/build 22:18:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:18:08 INFO - Running post-action listener: _resource_record_post_action 22:18:08 INFO - ##### 22:18:08 INFO - ##### Running read-buildbot-config step. 22:18:08 INFO - ##### 22:18:08 INFO - Running pre-action listener: _resource_record_pre_action 22:18:08 INFO - Running main action method: read_buildbot_config 22:18:08 INFO - Using buildbot properties: 22:18:08 INFO - { 22:18:08 INFO - "properties": { 22:18:08 INFO - "buildnumber": 277, 22:18:08 INFO - "product": "firefox", 22:18:08 INFO - "script_repo_revision": "production", 22:18:08 INFO - "builddir": "b2g-inbound_ubuntu64_hw_test-dromaeojs", 22:18:08 INFO - "repository": "", 22:18:08 INFO - "buildername": "Ubuntu HW 12.04 x64 b2g-inbound talos dromaeojs", 22:18:08 INFO - "buildid": "20151119211235", 22:18:08 INFO - "slavename": "talos-linux64-ix-049", 22:18:08 INFO - "pgo_build": "False", 22:18:08 INFO - "basedir": "/builds/slave/test", 22:18:08 INFO - "project": "", 22:18:08 INFO - "platform": "ubuntu64_hw", 22:18:08 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:18:08 INFO - "slavebuilddir": "test", 22:18:08 INFO - "scheduler": "tests-b2g-inbound-linux64-talos", 22:18:08 INFO - "branch": "b2g-inbound", 22:18:08 INFO - "repo_path": "integration/b2g-inbound", 22:18:08 INFO - "stage_platform": "linux64", 22:18:08 INFO - "builduid": "f2d6b3911cb741aa9e3a3e1d0e77dd7a", 22:18:08 INFO - "revision": "eb83bfa5f73945d1f28d6ba6da8080d6e1b5f26f" 22:18:08 INFO - }, 22:18:08 INFO - "sourcestamp": { 22:18:08 INFO - "repository": "", 22:18:08 INFO - "hasPatch": false, 22:18:08 INFO - "project": "", 22:18:08 INFO - "branch": "b2g-inbound-linux64-talos", 22:18:08 INFO - "changes": [ 22:18:08 INFO - { 22:18:08 INFO - "category": null, 22:18:08 INFO - "files": [ 22:18:08 INFO - { 22:18:08 INFO - "url": null, 22:18:08 INFO - "name": "https://queue.taskcluster.net/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 22:18:08 INFO - } 22:18:08 INFO - ], 22:18:08 INFO - "repository": "", 22:18:08 INFO - "rev": "eb83bfa5f73945d1f28d6ba6da8080d6e1b5f26f", 22:18:08 INFO - "who": "philringnalda@gmail.com", 22:18:08 INFO - "when": 1448000215, 22:18:08 INFO - "number": 6687007, 22:18:08 INFO - "comments": "Merge m-c to b-i", 22:18:08 INFO - "project": "", 22:18:08 INFO - "at": "Thu 19 Nov 2015 22:16:55", 22:18:08 INFO - "branch": "b2g-inbound-linux64-talos", 22:18:08 INFO - "revlink": "", 22:18:08 INFO - "properties": [ 22:18:08 INFO - [ 22:18:08 INFO - "buildid", 22:18:08 INFO - "20151119211235", 22:18:08 INFO - "Change" 22:18:08 INFO - ], 22:18:08 INFO - [ 22:18:08 INFO - "builduid", 22:18:08 INFO - "f2d6b3911cb741aa9e3a3e1d0e77dd7a", 22:18:08 INFO - "Change" 22:18:08 INFO - ], 22:18:08 INFO - [ 22:18:08 INFO - "pgo_build", 22:18:08 INFO - "False", 22:18:08 INFO - "Change" 22:18:08 INFO - ] 22:18:08 INFO - ], 22:18:08 INFO - "revision": "eb83bfa5f73945d1f28d6ba6da8080d6e1b5f26f" 22:18:08 INFO - } 22:18:08 INFO - ], 22:18:08 INFO - "revision": "eb83bfa5f73945d1f28d6ba6da8080d6e1b5f26f" 22:18:08 INFO - } 22:18:08 INFO - } 22:18:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 22:18:08 INFO - Running post-action listener: _resource_record_post_action 22:18:08 INFO - ##### 22:18:08 INFO - ##### Running download-and-extract step. 22:18:08 INFO - ##### 22:18:08 INFO - Running pre-action listener: _resource_record_pre_action 22:18:08 INFO - Running main action method: download_and_extract 22:18:08 INFO - mkdir: /builds/slave/test/build/tests 22:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:18:08 INFO - https://queue.taskcluster.net/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 22:18:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/test_packages.json 22:18:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/test_packages.json 22:18:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 22:18:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 22:18:12 INFO - Downloaded 1302 bytes. 22:18:12 INFO - Reading from file /builds/slave/test/build/test_packages.json 22:18:12 INFO - Using the following test package requirements: 22:18:12 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 22:18:12 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 22:18:12 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:18:12 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 22:18:12 INFO - u'jsshell-linux-x86_64.zip'], 22:18:12 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 22:18:12 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:18:12 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 22:18:12 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 22:18:12 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:18:12 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 22:18:12 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:18:12 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 22:18:12 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:18:12 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 22:18:12 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 22:18:12 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:18:12 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:18:12 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')]} 22:18:12 INFO - https://queue.taskcluster.net/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:18:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 22:18:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 22:18:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 22:18:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:18:14 INFO - Downloaded 21336647 bytes. 22:18:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 22:18:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 22:18:14 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 22:18:15 INFO - Return code: 0 22:18:15 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:18: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')]} 22:18:15 INFO - https://queue.taskcluster.net/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:18:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 22:18:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 22:18:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 22:18:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:18:21 INFO - Downloaded 21336647 bytes. 22:18:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 22:18:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 22:18:21 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 22:18:22 INFO - Return code: 0 22:18:22 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')]} 22:18:22 INFO - https://queue.taskcluster.net/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:18:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 22:18:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 22:18:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 22:18:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:18:24 INFO - Downloaded 11130997 bytes. 22:18:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 22:18:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 22:18:24 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 22:18:24 INFO - Return code: 0 22:18: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')]} 22:18:24 INFO - https://queue.taskcluster.net/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:18:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 22:18:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 22:18:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 22:18:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:18:26 INFO - Downloaded 55781387 bytes. 22:18:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 22:18:26 INFO - mkdir: /builds/slave/test/properties 22:18:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:18:26 INFO - Writing to file /builds/slave/test/properties/build_url 22:18:26 INFO - Contents: 22:18:26 INFO - build_url:https://queue.taskcluster.net/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 22:18:26 INFO - Running post-action listener: _resource_record_post_action 22:18:26 INFO - Running post-action listener: set_extra_try_arguments 22:18:26 INFO - ##### 22:18:26 INFO - ##### Running populate-webroot step. 22:18:26 INFO - ##### 22:18:26 INFO - Running pre-action listener: _resource_record_pre_action 22:18:26 INFO - Running main action method: populate_webroot 22:18:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:18:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:18:26 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 22:18:26 INFO - '../fennec_ids.txt'], 22:18:26 INFO - 'tests': ['tcheck2']}, 22:18:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:18:26 INFO - '--tppagecycles', 22:18:26 INFO - '7'], 22:18:26 INFO - 'tests': ['tsvgm']}}, 22:18:26 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 22:18:26 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 22:18:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:18:26 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:18:26 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 22:18:26 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:18:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:18:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:18:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:18:26 INFO - 'talos_options': ['--e10s'], 22:18:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:18:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:18:26 INFO - 'tests': ['damp', 'tps']}, 22:18:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:18:26 INFO - 'talos_options': ['--e10s'], 22:18:26 INFO - 'tests': ['damp', 'tps']}, 22:18:26 INFO - 'g3': {'tests': ['dromaeo_dom']}, 22:18:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:18:26 INFO - 'other': {'tests': ['a11yr', 22:18:26 INFO - 'ts_paint', 22:18:26 INFO - 'tpaint', 22:18:26 INFO - 'sessionrestore', 22:18:26 INFO - 'sessionrestore_no_auto_restore']}, 22:18:26 INFO - 'other-e10s': {'talos_options': ['--e10s'], 22:18:26 INFO - 'tests': ['a11yr', 22:18:26 INFO - 'ts_paint', 22:18:26 INFO - 'tpaint', 22:18:26 INFO - 'sessionrestore', 22:18:26 INFO - 'sessionrestore_no_auto_restore']}, 22:18:26 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 22:18:26 INFO - 'tests': ['a11yr', 22:18:26 INFO - 'ts_paint', 22:18:26 INFO - 'tpaint', 22:18:26 INFO - 'sessionrestore', 22:18:26 INFO - 'sessionrestore_no_auto_restore']}, 22:18:26 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 22:18:26 INFO - 'tests': ['a11yr', 22:18:26 INFO - 'ts_paint', 22:18:26 INFO - 'tpaint', 22:18:26 INFO - 'sessionrestore', 22:18:26 INFO - 'sessionrestore_no_auto_restore']}, 22:18:26 INFO - 'other_l64': {'tests': ['a11yr', 22:18:26 INFO - 'ts_paint', 22:18:26 INFO - 'tpaint', 22:18:26 INFO - 'sessionrestore', 22:18:26 INFO - 'sessionrestore_no_auto_restore']}, 22:18:26 INFO - 'other_nol64': {'tests': ['a11yr', 22:18:26 INFO - 'ts_paint', 22:18:26 INFO - 'tpaint', 22:18:26 INFO - 'sessionrestore', 22:18:26 INFO - 'sessionrestore_no_auto_restore']}, 22:18:26 INFO - 'svgr': {'tests': ['tsvgx', 22:18:26 INFO - 'tsvgr_opacity', 22:18:26 INFO - 'tart', 22:18:26 INFO - 'tscrollx', 22:18:26 INFO - 'cart']}, 22:18:26 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 22:18:26 INFO - 'tests': ['tsvgx', 22:18:26 INFO - 'tsvgr_opacity', 22:18:26 INFO - 'tart', 22:18:26 INFO - 'tscrollx', 22:18:26 INFO - 'cart']}, 22:18:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:18:26 INFO - 'tests': ['tp5o']}, 22:18:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:18:26 INFO - 'talos_options': ['--e10s'], 22:18:26 INFO - 'tests': ['tp5o']}, 22:18:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:18:26 INFO - 'talos_options': ['--xperf_path', 22:18:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:18:26 INFO - 'tests': ['tp5n']}, 22:18:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:18:26 INFO - 'talos_options': ['--e10s', 22:18:26 INFO - '--xperf_path', 22:18:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:18:26 INFO - 'tests': ['tp5n']}}, 22:18:26 INFO - 'talos.zip': {'path': '', 22:18:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:18:26 INFO - Running post-action listener: _resource_record_post_action 22:18:26 INFO - ##### 22:18:26 INFO - ##### Running create-virtualenv step. 22:18:26 INFO - ##### 22:18:26 INFO - Running pre-action listener: _resource_record_pre_action 22:18:26 INFO - Running main action method: create_virtualenv 22:18:26 INFO - Creating virtualenv /builds/slave/test/build/venv 22:18:26 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 22:18:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:18:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:18:26 INFO - Using real prefix '/usr' 22:18:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:18:27 INFO - Installing distribute.............................................................................................................................................................................................done. 22:18:29 INFO - Installing pip.................done. 22:18:29 INFO - Return code: 0 22:18:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:18:29 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')]} 22:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:18:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18:29 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')]} 22:18:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:18:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18:29 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 0x7f0997a99c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218c6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x223d900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2283b70>, '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 0x22d4dc0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d5250>, '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': '0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082', '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 22:18:29 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 22:18:29 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 22:18:29 INFO - Using env: {'DISPLAY': ':0', 22:18:29 INFO - 'HOME': '/home/cltbld', 22:18:29 INFO - 'LANG': 'en_US.UTF-8', 22:18:29 INFO - 'LANGUAGE': 'en_US:en', 22:18:29 INFO - 'LOGNAME': 'cltbld', 22:18:29 INFO - 'MAIL': '/var/mail/cltbld', 22:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:18:29 INFO - 'MOZ_NO_REMOTE': '1', 22:18:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:18:29 INFO - 'NO_EM_RESTART': '1', 22:18:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:18:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:18:29 INFO - 'PWD': '/builds/slave/test', 22:18:29 INFO - 'SHELL': '/bin/bash', 22:18:29 INFO - 'SHLVL': '1', 22:18:29 INFO - 'TERM': 'linux', 22:18:29 INFO - 'TMOUT': '86400', 22:18:29 INFO - 'USER': 'cltbld', 22:18:29 INFO - 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082', 22:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:18:29 INFO - '_': '/tools/buildbot/bin/python'} 22:18:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:18:29 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 22:18:29 INFO - Cleaning up... 22:18:29 INFO - Return code: 0 22:18:29 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:18:29 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')]} 22:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:18:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18:29 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')]} 22:18:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:18:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18:29 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 0x7f0997a99c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218c6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x223d900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2283b70>, '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 0x22d4dc0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d5250>, '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': '0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082', '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 22:18:29 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 22:18:29 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 22:18:29 INFO - Using env: {'DISPLAY': ':0', 22:18:29 INFO - 'HOME': '/home/cltbld', 22:18:29 INFO - 'LANG': 'en_US.UTF-8', 22:18:29 INFO - 'LANGUAGE': 'en_US:en', 22:18:29 INFO - 'LOGNAME': 'cltbld', 22:18:29 INFO - 'MAIL': '/var/mail/cltbld', 22:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:18:29 INFO - 'MOZ_NO_REMOTE': '1', 22:18:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:18:29 INFO - 'NO_EM_RESTART': '1', 22:18:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:18:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:18:29 INFO - 'PWD': '/builds/slave/test', 22:18:29 INFO - 'SHELL': '/bin/bash', 22:18:29 INFO - 'SHLVL': '1', 22:18:29 INFO - 'TERM': 'linux', 22:18:29 INFO - 'TMOUT': '86400', 22:18:29 INFO - 'USER': 'cltbld', 22:18:29 INFO - 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082', 22:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:18:29 INFO - '_': '/tools/buildbot/bin/python'} 22:18:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:18:29 INFO - Downloading/unpacking psutil>=0.7.1 22:18:29 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 22:18:29 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 22:18:29 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 22:18:29 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 22:18:29 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 22:18:29 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 22:18:31 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 22:18:31 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 22:18:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:18:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:18:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:18:31 INFO - Installing collected packages: psutil 22:18:31 INFO - Running setup.py install for psutil 22:18:31 INFO - building 'psutil._psutil_linux' extension 22:18:31 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 22:18:31 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 22:18:31 INFO - building 'psutil._psutil_posix' extension 22:18:31 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 22:18:31 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 22:18:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:18:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:18:31 INFO - Successfully installed psutil 22:18:31 INFO - Cleaning up... 22:18:31 INFO - Return code: 0 22:18:31 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:18: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')]} 22:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:18:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18: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')]} 22:18:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:18:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18:31 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 0x7f0997a99c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218c6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x223d900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2283b70>, '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 0x22d4dc0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d5250>, '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': '0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082', '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 22:18:31 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 22:18:31 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 22:18:31 INFO - Using env: {'DISPLAY': ':0', 22:18:31 INFO - 'HOME': '/home/cltbld', 22:18:31 INFO - 'LANG': 'en_US.UTF-8', 22:18:31 INFO - 'LANGUAGE': 'en_US:en', 22:18:31 INFO - 'LOGNAME': 'cltbld', 22:18:31 INFO - 'MAIL': '/var/mail/cltbld', 22:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:18:31 INFO - 'MOZ_NO_REMOTE': '1', 22:18:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:18:31 INFO - 'NO_EM_RESTART': '1', 22:18:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:18:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:18:31 INFO - 'PWD': '/builds/slave/test', 22:18:31 INFO - 'SHELL': '/bin/bash', 22:18:31 INFO - 'SHLVL': '1', 22:18:31 INFO - 'TERM': 'linux', 22:18:31 INFO - 'TMOUT': '86400', 22:18:31 INFO - 'USER': 'cltbld', 22:18:31 INFO - 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082', 22:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:18:31 INFO - '_': '/tools/buildbot/bin/python'} 22:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:18:32 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:18:32 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 22:18:32 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 22:18:32 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 22:18:32 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 22:18:32 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 22:18:32 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 22:18:33 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:18:33 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 22:18:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:18:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:18:33 INFO - Installing collected packages: mozsystemmonitor 22:18:33 INFO - Running setup.py install for mozsystemmonitor 22:18:33 INFO - Successfully installed mozsystemmonitor 22:18:33 INFO - Cleaning up... 22:18:33 INFO - Return code: 0 22:18:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:18:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:18:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:18:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18:33 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 0x7f0997a99c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218c6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x223d900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2283b70>, '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 0x22d4dc0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d5250>, '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': '0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082', '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 22:18:33 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 22:18:33 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 22:18:33 INFO - Using env: {'DISPLAY': ':0', 22:18:33 INFO - 'HOME': '/home/cltbld', 22:18:33 INFO - 'LANG': 'en_US.UTF-8', 22:18:33 INFO - 'LANGUAGE': 'en_US:en', 22:18:33 INFO - 'LOGNAME': 'cltbld', 22:18:33 INFO - 'MAIL': '/var/mail/cltbld', 22:18:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:18:33 INFO - 'MOZ_NO_REMOTE': '1', 22:18:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:18:33 INFO - 'NO_EM_RESTART': '1', 22:18:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:18:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:18:33 INFO - 'PWD': '/builds/slave/test', 22:18:33 INFO - 'SHELL': '/bin/bash', 22:18:33 INFO - 'SHLVL': '1', 22:18:33 INFO - 'TERM': 'linux', 22:18:33 INFO - 'TMOUT': '86400', 22:18:33 INFO - 'USER': 'cltbld', 22:18:33 INFO - 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082', 22:18:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:18:33 INFO - '_': '/tools/buildbot/bin/python'} 22:18:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:18:34 INFO - Downloading/unpacking blobuploader==1.2.4 22:18:34 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 22:18:34 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 22:18:34 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 22:18:34 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 22:18:34 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 22:18:34 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 22:18:35 INFO - Downloading blobuploader-1.2.4.tar.gz 22:18:35 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 22:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:18:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:18:36 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 22:18:36 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 22:18:36 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 22:18:36 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 22:18:36 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 22:18:36 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 22:18:36 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 22:18:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:18:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:18:36 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 22:18:36 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 22:18:36 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 22:18:36 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 22:18:36 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 22:18:36 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 22:18:36 INFO - Downloading docopt-0.6.1.tar.gz 22:18:36 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 22:18:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:18:36 INFO - Installing collected packages: blobuploader, requests, docopt 22:18:36 INFO - Running setup.py install for blobuploader 22:18:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:18:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:18:36 INFO - Running setup.py install for requests 22:18:37 INFO - Running setup.py install for docopt 22:18:37 INFO - Successfully installed blobuploader requests docopt 22:18:37 INFO - Cleaning up... 22:18:37 INFO - Return code: 0 22:18:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:18: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')]} 22:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:18:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18: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')]} 22:18:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:18:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18:37 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 0x7f0997a99c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218c6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x223d900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2283b70>, '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 0x22d4dc0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d5250>, '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': '0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082', '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 22:18:37 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 22:18:37 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 22:18:37 INFO - Using env: {'DISPLAY': ':0', 22:18:37 INFO - 'HOME': '/home/cltbld', 22:18:37 INFO - 'LANG': 'en_US.UTF-8', 22:18:37 INFO - 'LANGUAGE': 'en_US:en', 22:18:37 INFO - 'LOGNAME': 'cltbld', 22:18:37 INFO - 'MAIL': '/var/mail/cltbld', 22:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:18:37 INFO - 'MOZ_NO_REMOTE': '1', 22:18:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:18:37 INFO - 'NO_EM_RESTART': '1', 22:18:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:18:37 INFO - 'PWD': '/builds/slave/test', 22:18:37 INFO - 'SHELL': '/bin/bash', 22:18:37 INFO - 'SHLVL': '1', 22:18:37 INFO - 'TERM': 'linux', 22:18:37 INFO - 'TMOUT': '86400', 22:18:37 INFO - 'USER': 'cltbld', 22:18:37 INFO - 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082', 22:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:18:37 INFO - '_': '/tools/buildbot/bin/python'} 22:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:18:37 INFO - Running setup.py (path:/tmp/pip-mznivT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:18:37 INFO - Running setup.py (path:/tmp/pip-U0yoj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:18:37 INFO - Running setup.py (path:/tmp/pip-0cNTXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:18:37 INFO - Running setup.py (path:/tmp/pip-Y97c7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:18:37 INFO - Running setup.py (path:/tmp/pip-FMff3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:18:37 INFO - Running setup.py (path:/tmp/pip-J2xxXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:18:37 INFO - Running setup.py (path:/tmp/pip-A9XGjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:18:37 INFO - Running setup.py (path:/tmp/pip-N11upJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:18:38 INFO - Running setup.py (path:/tmp/pip-8_DPEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:18:38 INFO - Running setup.py (path:/tmp/pip-8Q5Rwy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:18:38 INFO - Running setup.py (path:/tmp/pip-USUwSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:18:38 INFO - Running setup.py (path:/tmp/pip-u9nMZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:18:38 INFO - Running setup.py (path:/tmp/pip-DYskAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:18:38 INFO - Running setup.py (path:/tmp/pip-QoMNBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:18:38 INFO - Running setup.py (path:/tmp/pip-Z8PAyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:18:38 INFO - Running setup.py (path:/tmp/pip-jfAzYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:18:38 INFO - Running setup.py (path:/tmp/pip-SbIC1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:18:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:18:38 INFO - Running setup.py install for manifestparser 22:18:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:18:38 INFO - Running setup.py install for mozcrash 22:18:38 INFO - Running setup.py install for mozdebug 22:18:38 INFO - Running setup.py install for mozdevice 22:18:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:18:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:18:39 INFO - Running setup.py install for mozfile 22:18:39 INFO - Running setup.py install for mozhttpd 22:18:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:18:39 INFO - Running setup.py install for mozinfo 22:18:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:18:39 INFO - Running setup.py install for mozInstall 22:18:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:18:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:18:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:18:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:18:39 INFO - Running setup.py install for mozleak 22:18:39 INFO - Running setup.py install for mozlog 22:18:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:18:39 INFO - Running setup.py install for moznetwork 22:18:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:18:39 INFO - Running setup.py install for mozprocess 22:18:39 INFO - Running setup.py install for mozprofile 22:18:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:18:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:18:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:18:40 INFO - Running setup.py install for mozrunner 22:18:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:18:40 INFO - Running setup.py install for mozscreenshot 22:18:40 INFO - Running setup.py install for moztest 22:18:40 INFO - Running setup.py install for mozversion 22:18:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:18:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:18:40 INFO - Cleaning up... 22:18:40 INFO - Return code: 0 22:18:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:18:40 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')]} 22:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:18:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18:40 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')]} 22:18:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:18:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18:40 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 0x7f0997a99c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218c6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x223d900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2283b70>, '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 0x22d4dc0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d5250>, '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': '0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082', '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 22:18:40 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 22:18:40 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 22:18:40 INFO - Using env: {'DISPLAY': ':0', 22:18:40 INFO - 'HOME': '/home/cltbld', 22:18:40 INFO - 'LANG': 'en_US.UTF-8', 22:18:40 INFO - 'LANGUAGE': 'en_US:en', 22:18:40 INFO - 'LOGNAME': 'cltbld', 22:18:40 INFO - 'MAIL': '/var/mail/cltbld', 22:18:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:18:40 INFO - 'MOZ_NO_REMOTE': '1', 22:18:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:18:40 INFO - 'NO_EM_RESTART': '1', 22:18:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:18:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:18:40 INFO - 'PWD': '/builds/slave/test', 22:18:40 INFO - 'SHELL': '/bin/bash', 22:18:40 INFO - 'SHLVL': '1', 22:18:40 INFO - 'TERM': 'linux', 22:18:40 INFO - 'TMOUT': '86400', 22:18:40 INFO - 'USER': 'cltbld', 22:18:40 INFO - 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082', 22:18:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:18:40 INFO - '_': '/tools/buildbot/bin/python'} 22:18:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:18:40 INFO - Running setup.py (path:/tmp/pip-K2dn3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:18:40 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)) 22:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:18:40 INFO - Running setup.py (path:/tmp/pip-wx6zgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:18:40 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)) 22:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:18:40 INFO - Running setup.py (path:/tmp/pip-inW3Q2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:18:40 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)) 22:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:18:40 INFO - Running setup.py (path:/tmp/pip-52buj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:18:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:18:40 INFO - Running setup.py (path:/tmp/pip-ak5UBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:18:41 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)) 22:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:18:41 INFO - Running setup.py (path:/tmp/pip-m5vSU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:18:41 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)) 22:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:18:41 INFO - Running setup.py (path:/tmp/pip-p7cybN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:18:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:18:41 INFO - Running setup.py (path:/tmp/pip-V8pyuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:18:41 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)) 22:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:18:41 INFO - Running setup.py (path:/tmp/pip-NlfPM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:18:41 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)) 22:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:18:41 INFO - Running setup.py (path:/tmp/pip-l97pcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:18:41 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)) 22:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:18:41 INFO - Running setup.py (path:/tmp/pip-etVUcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:18:41 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)) 22:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:18:41 INFO - Running setup.py (path:/tmp/pip-bO8B1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:18:41 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)) 22:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:18:41 INFO - Running setup.py (path:/tmp/pip-H8oVwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:18:41 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)) 22:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:18:41 INFO - Running setup.py (path:/tmp/pip-HUjEyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:18:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:18:41 INFO - Running setup.py (path:/tmp/pip-D26b7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:18:41 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)) 22:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:18:41 INFO - Running setup.py (path:/tmp/pip-tayGy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:18:41 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)) 22:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:18:41 INFO - Running setup.py (path:/tmp/pip-NyX53D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:18:41 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)) 22:18:41 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)) 22:18:41 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)) 22:18:41 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)) 22:18:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:18:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:18:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:18:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:18:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:18:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:18:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:18:43 INFO - Downloading blessings-1.5.1.tar.gz 22:18:43 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 22:18:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:18:43 INFO - Installing collected packages: blessings 22:18:43 INFO - Running setup.py install for blessings 22:18:43 INFO - Successfully installed blessings 22:18:43 INFO - Cleaning up... 22:18:43 INFO - Return code: 0 22:18:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:18:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:18:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:18:44 INFO - Reading from file tmpfile_stdout 22:18:44 INFO - Current package versions: 22:18:44 INFO - argparse == 1.2.1 22:18:44 INFO - blessings == 1.5.1 22:18:44 INFO - blobuploader == 1.2.4 22:18:44 INFO - docopt == 0.6.1 22:18:44 INFO - manifestparser == 1.1 22:18:44 INFO - mozInstall == 1.12 22:18:44 INFO - mozcrash == 0.16 22:18:44 INFO - mozdebug == 0.1 22:18:44 INFO - mozdevice == 0.47 22:18:44 INFO - mozfile == 1.2 22:18:44 INFO - mozhttpd == 0.7 22:18:44 INFO - mozinfo == 0.9 22:18:44 INFO - mozleak == 0.1 22:18:44 INFO - mozlog == 3.0 22:18:44 INFO - moznetwork == 0.27 22:18:44 INFO - mozprocess == 0.22 22:18:44 INFO - mozprofile == 0.27 22:18:44 INFO - mozrunner == 6.11 22:18:44 INFO - mozscreenshot == 0.1 22:18:44 INFO - mozsystemmonitor == 0.0 22:18:44 INFO - moztest == 0.7 22:18:44 INFO - mozversion == 1.4 22:18:44 INFO - psutil == 3.1.1 22:18:44 INFO - requests == 1.2.3 22:18:44 INFO - wsgiref == 0.1.2 22:18:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:18: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')]} 22:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:18:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18: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')]} 22:18:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:18:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18: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', '-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 0x7f0997a99c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218c6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x223d900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2283b70>, '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 0x22d4dc0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d5250>, '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': '0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082', '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 22:18:44 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 22:18:44 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 22:18:44 INFO - Using env: {'DISPLAY': ':0', 22:18:44 INFO - 'HOME': '/home/cltbld', 22:18:44 INFO - 'LANG': 'en_US.UTF-8', 22:18:44 INFO - 'LANGUAGE': 'en_US:en', 22:18:44 INFO - 'LOGNAME': 'cltbld', 22:18:44 INFO - 'MAIL': '/var/mail/cltbld', 22:18:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:18:44 INFO - 'MOZ_NO_REMOTE': '1', 22:18:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:18:44 INFO - 'NO_EM_RESTART': '1', 22:18:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:18:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:18:44 INFO - 'PWD': '/builds/slave/test', 22:18:44 INFO - 'SHELL': '/bin/bash', 22:18:44 INFO - 'SHLVL': '1', 22:18:44 INFO - 'TERM': 'linux', 22:18:44 INFO - 'TMOUT': '86400', 22:18:44 INFO - 'USER': 'cltbld', 22:18:44 INFO - 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082', 22:18:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:18:44 INFO - '_': '/tools/buildbot/bin/python'} 22:18:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:18:44 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)) 22:18:44 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)) 22:18:44 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)) 22:18:44 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)) 22:18:44 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)) 22:18:44 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)) 22:18:44 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)) 22:18:44 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)) 22:18:44 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)) 22:18:44 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)) 22:18:44 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)) 22:18:44 INFO - Cleaning up... 22:18:44 INFO - Return code: 0 22:18:44 INFO - Running post-action listener: _resource_record_post_action 22:18:44 INFO - Running post-action listener: _start_resource_monitoring 22:18:44 INFO - Starting resource monitoring. 22:18:44 INFO - ##### 22:18:44 INFO - ##### Running install step. 22:18:44 INFO - ##### 22:18:44 INFO - Running pre-action listener: _resource_record_pre_action 22:18:44 INFO - Running main action method: install 22:18:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:18:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:18:44 INFO - Reading from file tmpfile_stdout 22:18:44 INFO - Detecting whether we're running mozinstall >=1.0... 22:18:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:18:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:18:44 INFO - Reading from file tmpfile_stdout 22:18:44 INFO - Output received: 22:18:44 INFO - Usage: mozinstall [options] installer 22:18:44 INFO - Options: 22:18:44 INFO - -h, --help show this help message and exit 22:18:44 INFO - -d DEST, --destination=DEST 22:18:44 INFO - Directory to install application into. [default: 22:18:44 INFO - "/builds/slave/test"] 22:18:44 INFO - --app=APP Application being installed. [default: firefox] 22:18:44 INFO - mkdir: /builds/slave/test/build/application 22:18:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:18:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:18:59 INFO - Reading from file tmpfile_stdout 22:18:59 INFO - Output received: 22:18:59 INFO - /builds/slave/test/build/application/firefox/firefox 22:18:59 INFO - Running post-action listener: _resource_record_post_action 22:18:59 INFO - ##### 22:18:59 INFO - ##### Running run-tests step. 22:18:59 INFO - ##### 22:18:59 INFO - Running pre-action listener: _resource_record_pre_action 22:18:59 INFO - Running main action method: run_tests 22:18:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:18:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:18:59 INFO - Python 2.7.3 22:18:59 INFO - Return code: 0 22:18:59 INFO - grabbing minidump binary from tooltool 22:18:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:18:59 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 0x2283b70>, '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 0x22d4dc0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d5250>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:18:59 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 22:18:59 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 22:18:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:18:59 INFO - Return code: 0 22:18:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:18:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:18:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:18:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:18:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:18:59 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/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--webServer', 'localhost'] in /builds/slave/test/build 22:18:59 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/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-049 --webServer localhost 22:18:59 INFO - Using env: {'DISPLAY': ':0', 22:18:59 INFO - 'HOME': '/home/cltbld', 22:18:59 INFO - 'LANG': 'en_US.UTF-8', 22:18:59 INFO - 'LANGUAGE': 'en_US:en', 22:18:59 INFO - 'LOGNAME': 'cltbld', 22:18:59 INFO - 'MAIL': '/var/mail/cltbld', 22:18:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:18:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:18:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:18:59 INFO - 'MOZ_NO_REMOTE': '1', 22:18:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:18:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:18:59 INFO - 'NO_EM_RESTART': '1', 22:18:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:18:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:18:59 INFO - 'PWD': '/builds/slave/test', 22:18:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:18:59 INFO - 'SHELL': '/bin/bash', 22:18:59 INFO - 'SHLVL': '1', 22:18:59 INFO - 'TERM': 'linux', 22:18:59 INFO - 'TMOUT': '86400', 22:18:59 INFO - 'USER': 'cltbld', 22:18:59 INFO - 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082', 22:18:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:18:59 INFO - '_': '/tools/buildbot/bin/python'} 22:18:59 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/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--webServer', 'localhost'] with output_timeout 3600 22:18:59 INFO - mozversion INFO | application_buildid: 20151119211235 22:18:59 INFO - mozversion INFO | application_changeset: eb83bfa5f73945d1f28d6ba6da8080d6e1b5f26f 22:18:59 INFO - mozversion INFO | application_display_name: Nightly 22:18:59 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:18:59 INFO - mozversion INFO | application_name: Firefox 22:18:59 INFO - mozversion INFO | application_remotingname: firefox 22:18:59 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/b2g-inbound 22:18:59 INFO - mozversion INFO | application_vendor: Mozilla 22:18:59 INFO - mozversion INFO | application_version: 45.0a1 22:18:59 INFO - mozversion INFO | platform_buildid: 20151119211235 22:18:59 INFO - mozversion INFO | platform_changeset: eb83bfa5f73945d1f28d6ba6da8080d6e1b5f26f 22:18:59 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/b2g-inbound 22:18:59 INFO - mozversion INFO | platform_version: 45.0a1 22:18:59 INFO - 2015-11-19 22:18:59,880 DEBUG : using testdate: 1448000339 22:18:59 INFO - 2015-11-19 22:18:59,880 DEBUG : actual date: 1448000339 22:18:59 INFO - 2015-11-19 22:18:59,891 INFO : starting webserver on 'localhost:40330' 22:18:59 INFO - 2015-11-19 22:18:59,892 INFO : Starting test suite talos-linux64-ix-049 22:18:59 INFO - 2015-11-19 22:18:59,892 INFO : Starting test dromaeo_css 22:18:59 INFO - 2015-11-19 22:18:59,892 DEBUG : operating with platform_type : linux_ 22:18:59 INFO - 2015-11-19 22:18:59,893 INFO : Initialising browser for dromaeo_css test... 22:18:59 INFO - 2015-11-19 22:18:59,909 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvVoIgG/profile http://localhost:40330/getInfo.html 22:19:03 INFO - 2015-11-19 22:19:03,950 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:19:03 INFO - 2015-11-19 22:19:03,951 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:19:03 INFO - 2015-11-19 22:19:03,951 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 22:19:03 INFO - 2015-11-19 22:19:03,951 DEBUG : BROWSER_OUTPUT: __metrics 22:19:04 INFO - 2015-11-19 22:19:04,072 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:19:05 INFO - 2015-11-19 22:19:05,228 INFO : Browser initialized. 22:19:05 INFO - 2015-11-19 22:19:05,229 INFO : Running cycle 1/1 for dromaeo_css test... 22:19:05 INFO - 2015-11-19 22:19:05,229 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvVoIgG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:19:17 INFO - 2015-11-19 22:19:17,098 DEBUG : BROWSER_OUTPUT: RSS: Main: 141758464 22:19:17 INFO - 2015-11-19 22:19:17,099 DEBUG : BROWSER_OUTPUT: 22:20:08 INFO - 2015-11-19 22:20:08,607 DEBUG : BROWSER_OUTPUT: 1448000408605 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] 22:22:20 INFO - 2015-11-19 22:22:20,801 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40330/tests/dromaeo/cssquery-ext.html) 22:22:21 INFO - 2015-11-19 22:22:21,910 DEBUG : BROWSER_OUTPUT: RSS: Main: 245727232 22:22:21 INFO - 2015-11-19 22:22:21,910 DEBUG : BROWSER_OUTPUT: 22:25:24 INFO - 2015-11-19 22:25:24,413 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/dromaeo/cssquery-ext.html (next: http://localhost:40330/tests/dromaeo/cssquery-jquery.html) 22:25:24 INFO - 2015-11-19 22:25:24,994 DEBUG : BROWSER_OUTPUT: RSS: Main: 216104960 22:25:24 INFO - 2015-11-19 22:25:24,994 DEBUG : BROWSER_OUTPUT: 22:28:27 INFO - 2015-11-19 22:28:27,004 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40330/tests/dromaeo/cssquery-mootools.html) 22:28:27 INFO - 2015-11-19 22:28:27,579 DEBUG : BROWSER_OUTPUT: RSS: Main: 206721024 22:28:27 INFO - 2015-11-19 22:28:27,579 DEBUG : BROWSER_OUTPUT: 22:31:29 INFO - 2015-11-19 22:31:29,508 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40330/tests/dromaeo/cssquery-prototype.html) 22:31:30 INFO - 2015-11-19 22:31:30,089 DEBUG : BROWSER_OUTPUT: RSS: Main: 199684096 22:31:30 INFO - 2015-11-19 22:31:30,089 DEBUG : BROWSER_OUTPUT: 22:34:32 INFO - 2015-11-19 22:34:32,861 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40330/tests/dromaeo/cssquery-yui.html) 22:34:33 INFO - 2015-11-19 22:34:33,443 DEBUG : BROWSER_OUTPUT: RSS: Main: 201183232 22:34:33 INFO - 2015-11-19 22:34:33,443 DEBUG : BROWSER_OUTPUT: 22:37:35 INFO - 2015-11-19 22:37:35,942 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/dromaeo/cssquery-yui.html (next: http://localhost:40330/tests/dromaeo/cssquery-dojo.html) 22:37:36 INFO - 2015-11-19 22:37:36,201 DEBUG : BROWSER_OUTPUT: RSS: Main: 244379648 22:37:36 INFO - 2015-11-19 22:37:36,201 DEBUG : BROWSER_OUTPUT: 22:37:36 INFO - 2015-11-19 22:37:36,201 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:37:36 INFO - 2015-11-19 22:37:36,201 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:37:36 INFO - 2015-11-19 22:37:36,201 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:37:36 INFO - 2015-11-19 22:37:36,202 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:37:36 INFO - 2015-11-19 22:37:36,202 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2672.104844876966;2804.929876753081;2810.0305394638845;2821.8024738268323;2822.1674605991216;4617.607503362218;4740.786664600093;4840.4191497020365;4894.070126675933;4904.607631389489;4719.646026548014;4721.197396442604;4792.185328494155;4808.951910480894;4852.854414367565;4335.783210839453;4494.573015563533;4505.166544189336;4538.432695913011;4544.341070544769;4297.333913243452;4510.097980403916;4522.6155776759015;4566.725996440207;4587.564181402761;4253.29820579604;4377.299632058855;4391.297392417198;4422.226110430662;4441.222786012468;4492.303692927569;4493.078918821667;4503.1218912312115;4522.344260082303;4541.227991241497;4471.016376397188;4488.528704486047;4490.730556166641;4503.234450143484;4518.909621807578;4451.755153466558;4530.478994915594;4587.885302867422;4591.104734576759;4675.088357770236;5117.590592752608;5118.59051275897;5118.6416950813455;5120.427833097227;5123.590112790967;5136.743162841843;5145.048166089248;5146.459621739701;5191.402988656278;5198.012377648235;4598.218302888517;4632.629389648854;4725.976370118127;4743.810247590127;4745.549172828576;5279.472052794767;5345.7594408251725;5348.652337598043;5352.116900711417;5399.730013499309;5104.566111880457;5128.820491282787;5133.4609865964285;5135.152699804565;5178.663386879841;4933.50664933511;5049.2426136079885;5053.317802096743;5169.508896654812;5286.497782710637;5121.8463446096675;5127.410347809975;5132.897342053138;5133.153029750049;5140.768665410065;5125.1287281162295;5142.51146141116;5148.099082660594;5149.871253218699;5169.922451163236;4088.059746258318;4102.651274641629;4157.771322577288;4193.979030104891;4196.286631272691;3776.6789822864816;3782.5460944686815;3792.2984247914223;3800.9239815204096;3813.8474461021246;4425.579569940851;4434.578715022068;4436.245838207513;4470.083632855257;4475.01549659081;4939.604831613534;5054.620903432257;5056.544910958031;5079.08576456234;5219.269302297681;4361.1495758327455;4363.890902727394;4398.714083584557;4417.646588272994;4450.710703804307;4521.480029796549;4523.819047238074;4526.049529598756;4540.409746732904;4568.086382723469;4696.882577935579;4804.375431193923;4808.8797780055775;4845.8061677532505;4861.392325959326;5070.366204224472;5176.948230517647;5177.326947496952;5193.532582067632;5224.164133738584;4924.990376972783;4945.357103576512;4947.208446648519;4949.752512374295;4992.500749924979;4861.829835955725;5134.691918484903;5143.485651434827;5154.484551544815;5202.297689811979;5053.7220452875445;5076.238564215398;5077.898442031213;5183.092958732132;5218.947810521846;4991.301217829434;5045.899082018413;5053.191489361684;5054.444011158843;5107.668001580034;4839.516048395273;5018.924716129334;5025.698458092526;5050.191969284897;5054.1155297822;4812.711237325771;4850.660453768202;4881.414230292387;4887.926681099715;4927.5565199130815;5043.823466178666;5080.720560369067;5090.312807771052;5091.59267258611;5127.230915362726;4943.4562198157455;4958.355413796184;4964.503549645151;4965.975170124127;5019.7239151847 22:37:36 INFO - 2015-11-19 22:37:36,202 DEBUG : BROWSER_OUTPUT: |1;ext.html;556.69381839988;575.830130111617;577.3677822784051;584.4301805739404;585.8008277185756;27700.307492312702;28288.71711282889;28327.291817704543;36580.26839463209;37094.258114837685;40594;40989.385159222635;41158.17683646325;41180.58819411805;41197.38203926943;7897.052353717562;8039.638216280281;8069.233422824822;8081.595920203996;8084.797880052987;7214.42284617232;7405.444591655647;9949.403035817873;10063.29556931015;10149.289549731522;10000.699979000641;10072.949635251813;10106.646267380644;10116.241281903887;10121.898781012205;7174.677139528734;7352.117745870476;10045.447500387476;10139.44233067181;10147.644832430904;7085.468589855782;10062.949685251528;10358.119559837423;10358.637447689294;10365.429901355423;36834.63165368339;37361;37383.43924841102;37391;37394.25211495755;2932.3695405487924;2938.1919972007654;2947.690492498278;2973.5391014392803;2985.477541430241;28069.298267543272;28202.71797282042;28531.714682853115;28678.569821452696;29149.708502915124;3458.394780913355;3513.3851575974104;3520.3135388599235;3597.8201089945396;3640.2537479816574;222590;223059;223979;224594;224977;52190.47809521932;52962.73518632459;52976;56973.71513142408;57410.42589574134;47931.520684792704;66004.6699766498;66771.66614166995;67056.66471667709;67068;15138.697226055418;15201.61995950088;15319.157446340558;15329.846701533064;15374.154421506924;10608.469576521144;10675.83986240207;10691.465426728788;10699.679009629723;10923.781524369468;19077.523061923286;19126.087391260764;19228.61542769137;19237.230510779693;19246.23015079409;7687.154413014564;7869.291763741291;7883.329916957008;7945.205479452125;7975.64718408071;799.0172088331385;822.4859462835727;846.754441212054;963.7590602349412;1057.1173070486077;24724.876375618365;24736.134235301677;24923;25048.248552543453;25065.37336566564;934.8036912248504;939.0421769794907;985.0641890204334;1016.2225897188656;1072.6752460711016;13190.73618527624;13249.403776830066;13304.46782128704;13313.534026309033;13384.397702103428;263862;268111;268449;268783;270749;46658.76670616625;47001.5299847004;47086;47201.29198061963;47215.291770623466;1177.770334784726;1191.9642410727865;1192.3322939154102;1197.3235122155954;1197.329495482515;24558.508829822946;25083;25195.622065668664;25535.744642553338;25597.87201063983;39486.210275794896;39795.80102099587;39814;39828.60171398249;39880.40179397253;248672;250141;250171;250366;250464;24469.51060978806;24823.25530234096;28433.715662843108;29239.561406578494;29782.553261700657;37069.62930370662;37489.4376584346;37626;37771.81114094412;37967;32297.677023229466;32410.189745255615;32658.183545411553;32798.5080223792;33089;27359.72640273572;30775.2306192347;32837;33250.33499330048;33375;30804.845975770873;30944.822448969102;31214;31266.37467250597;31274.21814454657;27889.721102788713;29028.854855725585;29616.703832962256;29945.550816738203;30258.394832103673;21908.780912191312;22204.66692999574;22299.665505017114;22343.553128937005;22386 22:37:36 INFO - 2015-11-19 22:37:36,202 DEBUG : BROWSER_OUTPUT: |2;jquery.html;334.74057605355847;342.8217326989967;344.4316877152698;345.49880768062764;345.9965400345997;15071.246437678114;15084.698306033886;16023.759643605355;16031.438899638515;16045.518634440956;15363.539093827176;15569.37722491099;15842.84157158428;15884.285207165676;16230.026198428073;4877.804887804493;5014.749262536877;5018.946021335524;5020.573251273646;5022.0709168803705;4920.384951881015;5088.771005304751;5088.92366614501;5101.642884998052;5137.12668846297;3388.7966721996636;3472.496488009237;3479.1302174456387;5097.770600322994;5101.872453188681;5111.897762044757;5219.869503262449;5252.763625636855;5260.92108618371;5264.526192642661;4904.656674032819;5018.824341148043;5038.294638750541;5144.922826157611;5155.149400348938;10445.738856528571;11194.44027798615;11325.207235493519;13955.441782328695;14321.212333321662;1841.5672317005492;1847.9445616631522;1886.4152112844924;1887.8961657108853;1912.139537208248;8334.291585215264;8372.28835548979;8599.957000215021;8604.78488037804;11627;1721.9225134868962;1738.8174241704692;1742.9912850435794;1757;1773.6364045370667;54529.1820622691;58158;58313.70843145757;58665;58736.706316468146;9438.764030899283;9641.710748677551;9697.9030209697;9723.222142228604;9783.266255030901;12502.74994500105;12503.874961250453;12504.562340318045;12515.311657858723;12518.624441266777;7004.509684322151;7147;7192.964035179791;7253.057102576631;7300.3064708853635;7026.016357709925;7041.5422997505;7054.894176587355;7060.470464715064;7090.184628767654;4137.110521237948;4145.502539695196;4147.730397524151;4163.084121493267;4164.333706606928;1596.2976290432173;1610.1707620575412;1611.4521062838692;1711.8972861628342;1740.3299729604194;847.3517758914436;893.78102364921;897.9730608081768;930.9627614895328;936.9390989585792;15746.212689365486;16044;16167.35330586787;16245.431409900597;16389.262483188286;781.86708259596;814.6782021101632;862.3532350736947;886.5788750343664;922.5156792683895;7295.379892709179;9019.143181397889;9130.81738365229;9181.449113053104;9380.48407337603;306116;309533;310391;384444;395077;41527.169456610696;56918;61278.693606531684;63321.68339158367;63842.68078659577;1080.254624309224;1080.719013056611;1125.5497800879714;1149.7298134938283;1150.1259043127284;14450.132992020512;14478.493252736103;14521.491747788778;14713.55859324222;14956;32770;32892.671073289916;33237.16907077342;33930.49104263487;34458.655413445544;40034.599654004254;40181.397279040255;40475.1904961905;41410.58589414067;42838.5716142847;7955.164707705608;8183.181681831769;8259.256666900008;8280.378971577276;8284.420090593601;9564.61741530331;10839.620613278497;14736.336864840894;14770.63073423173;14940.701185976437;12823.42294596764;12853.42159602839;12904.354782261038;13241.933790330986;13525.256110913993;13153.342332883509;13190.80213796813;13210.735785284049;13623;13799;12848.293343866177;12893.548725794552;13085.869141308465;13151.21092734439;13415.798763018758;8736.733173689652;8855.424397414148;8987.505687187268;9070.546472676471;9085.227755640844;12842.101052926579;12945.741085178432;13004;13179.60461186166;13280.269585172908 22:37:36 INFO - 2015-11-19 22:37:36,203 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1460.861218184272;1639.19679357115;1673.4393978017363;1709.4700642800724;1716.2019660857698;6291.213598300213;6739.393454589086;6830.931690683092;6844.82887927802;6856.211535673393;6939.0979172707475;7069.151701795791;7070.363667269958;7072.787816365517;7074.9292507074915;2098.475381154711;2121.1833444124045;2124.808767210951;2127.7446706395253;2129.5208578069887;2116.3650904728597;2131.712218850451;2159.373781603333;2164.101897712447;2164.7077644518026;2086.540960988588;2092.048118106258;2106.494441334084;2118.766935637079;2122.4587730128874;2095.738032745907;2096.9056392462376;2100.771048936374;2103.3900168950986;2105.5578328551023;2062.092679221138;2111.345482900306;2112.271266413085;2112.809847113752;2113.9471513212293;6068.393160683896;6214.067889816519;6981.965090174567;7008.754693585751;7011.859762804766;4127.236461254647;4188.120494696117;4203.390508376299;4223.324268117117;4253.808578613939;2935.6183696119583;2988.9103326900226;3041.133277016062;3050.2221933407063;3057.464943634855;904.7918978634885;909.4815954905706;941.5857022909967;946.5078159357096;965.560669895203;9510.239180865514;9573.904260957372;9586.712398628053;9608.231341492663;9617.615295388247;4098.139390727981;4098.159877225162;4140.130572579732;4158.771267580312;4171.541130475645;8317.500949943023;8340.791480212922;8347.749567512985;8363.832723345498;8368.539730314891;4323.286657701507;4327.199468098412;4335.956640433556;4337.9783101084295;4342.392065110887;3884.2425726982965;3909.4135879618284;3911.6870650347905;3912.3544615138185;3916.5691773904846;3257.885973990899;3289.4243507386113;3337.098983274502;3338.88313909012;3340.766146369731;1118.6588090632436;1121.9439028048564;1127.0307535519446;1129.7119234595125;1139.5783560082823;1022.4070039377143;1082.1667316166665;1170.5122105930786;1214.5627574073326;1214.842070530843;6663.36698013688;6726;6728.3271672832325;6729.966350168218;6742.932570674328;447.85220877110476;453.0350353746499;457.6498978281617;460.31413194340195;461.64222727386544;4926.704397736147;5119.283300337956;5166.276721259027;5168.095583272912;5170.896582068338;7750.418718596128;8162.102168761431;8290.46112002718;8329.08380078191;8365.916340836635;8253.33973282148;8273.875891861628;8322.875156872651;8328.583570821556;8331.8333633327;6419.16550848396;6509.837254068686;6534.313897040837;6546.116274303004;6597.505187110986;6550.443212327005;6608.768693095719;6630.867382652224;6645.235797883305;6656.900146497709;7215.386692131227;7456.254374562717;7494.17564067963;7494.700211991677;7526.247375262649;7509.586972716553;7661.310482056641;7675.194104618935;7789.532628042336;7793.376529877482;6171.444569988723;6179.938200618116;6243.219597550306;6245.531585131042;6261.279952805486;6752.561083529555;6780.966095169492;6803;6826.419754320938;6831.2144103428745;6374.58565193261;6414.03789431589;6444.947749036295;6476.190476190476;6516.934830651822;6181.196444462371;6193.938060619516;6242.656653883898;6296.150019747275;6320;6366.363363663597;6403.29563747978;6432.710528026344;6432.807015789534;6444.806655800334;5771.076627739541;5880.206172166874;5916.970415147896;5922.851928701817;5925.08161234992;5848.239728835224;6012.849678758066;6044.032954727222;6046.818595442144;6051.425114614105 22:37:36 INFO - 2015-11-19 22:37:36,205 DEBUG : BROWSER_OUTPUT: |4;prototype.html;316.72444972873586;317.2813577247534;321.6799284711712;325.55886773422014;329.4432409228404;5386.192071189319;5434;5450.100733378993;5452.400235974044;5483.7532311046;5126.974365128179;5214.817481388152;5281.023010743015;5365.597580181482;5391.838244852651;4212.241796476633;4212.578742125778;4215.3887686285625;4230.365445183224;4237.449131612886;3985.2627263956115;4225.830966761326;4236.639885609711;4240.342746874239;4242.406063151161;4063.6952228582827;4067.3085575452246;4192.748435093904;4199.286121359376;4206.747595144286;4178.582141785812;4184.686148538872;4192.3711443283755;4203.432536607549;4205.390218418343;3718.7025037996896;3719.1817800083936;3722;3723.7393382463238;3723.832427540767;5147;5187.0922588546855;5192.818251361185;5198.844034678966;5200.583953283728;4647.9535204647855;4777.140114779339;4779.378680771512;4783.306420568998;4784.42586889572;5659.320881494205;5660.066089095294;5661.66030038199;5662.6036177467595;5674.858128546778;3943.9014024649327;3948.0524674078015;3953.327934251184;3955.0310174007263;3958.5843486433796;5891.764329426818;5957.940420595825;5963.314218864799;5966.075258334965;5976.8505787355225;3938.3895496198134;4048.08917993455;4054.655354294859;4074.2869997750277;4075.3886916962692;4126.896827579274;4133.379993001074;4133.772642504691;4134.37984302351;4140.0270936329935;4753.643476739258;4757.26262429324;4758.500357462486;4761.738104404291;4763.45220299663;5670;5670.688112153871;5690.459406356389;5698.088305871123;5698.430156984351;1418.2979425184394;1439.165284135199;1461.8245810502808;1464.05568408376;1466.1423067505416;963.7735132243918;981.8919918808925;986.063239922077;986.2455221755363;991.6479649724388;4761.595264402565;4819.759012049383;4823.662343635911;4824.324594556765;4831.033793241366;5655.321361436571;5678.716064196774;5687.971560142173;5688.544916406678;5701.429857014348;4754.120487709784;4798.616110711135;4807.399075115611;4883.804647814119;4942.481039490802;3131.968680313213;3156.6685498022835;3167.160702413862;3169.5721077654684;3171.365726854592;5549.5837812164245;5586.860328491739;5589.245451864028;5590.748416321276;5594.888102237933;5879.735411906475;5993.160957465958;5997.850053748604;6032.336442991267;6056.06131049688;5552.194931734876;5575.637583557137;5576.9163462548095;5594.692291923902;5631.971840140773;5848.590598658053;5884.293884733859;5888.499477544286;5896.380880007538;5902.380250073852;5771.336296325976;5788.594798364243;5809.738561764815;5813.418658134322;5813.476787089182;5474.863128421821;5603.439656034364;5610.13042978339;5614.887702246014;5621.072523033818;4038.7172897896867;4063.837446502107;4070.2876996526084;4078.7348822326453;4081.4285999959434;5893.145493903446;5893.5874488785375;5895.852603684943;5905.5570832186895;5905.616134951214;5535.833924982257;5608.551315894801;5646.661200327993;5660.462256085665;5669.177969194526;5630.18362337467;5645.237892884408;5647.632903861236;5650.745716442852;5661.745221464962;5535.391106978149;5536.141898005815;5536.944630553804;5539.14143307788;5543.279373681396;5706.343770466449;5706.743196556248;5707.942920570741;5712.143178523254;5714.399988001366;5417.485338892799;5563.554915606661;5565.1374037024325;5567.387587365468;5569.10894256917 22:37:36 INFO - 2015-11-19 22:37:36,206 DEBUG : BROWSER_OUTPUT: |5;yui.html;685.516710718943;696.5855316086927;707.7558242406371;737.365865355794;757.5454727163702;10888;11342.262752921064;11348.943255283733;11351.148663850214;11372.658820235385;12043.939780301109;12168.81746773799;12181.878181218186;12182.208156469824;12188.207766495194;613.6471528870917;621.6518749500271;782.0302824497638;792.251322500239;807.6728924785442;802.9317508011796;803.0884945586743;810.5906517208801;811.1077814404167;811.1563973467587;840.8402403543307;851.4167795060404;851.8296340731847;852.7313896122732;852.7654894903889;651.8663673946829;660.8314879705669;823.1562648285495;842.8525008123615;845.2519520224585;619.9597026193283;625.4058644287944;625.6996641612006;626.0421555020826;626.0484064222402;12233.204841685261;12403.317817520032;12414.193077450027;12418.56535021279;12421.37893105341;11273.943630281878;11995.880041199563;12169.634910952685;12250.75498490034;12423.627291181188;9690.176335011533;9693.321467497279;9698.51507424633;9762.267829912786;9778.804423911553;3203.775735698502;3215.8552865121123;3228.4350238708134;3261.233610101648;3338.3490219407227;39517.80241098805;40743.59256407428;43436.56563434388;43827.78086109581;43959;9169.083091690778;9196.862047069299;9275.953620231856;9345.252379809735;9414.67048653294;16611.750823737653;16613.41853035138;16619.252133653772;16631.584210394838;16634.50096497107;5857.970710146422;5872.471477567039;5902.439268269508;6034.306054803666;6132.816015519542;3543;3570.107473131717;3571.053670777246;3583.6774690278;3601.081724160322;2810.9297267568472;2835.149455163484;2842.0905310300504;2847.700991395915;2852.3867368515453;498.180493088874;508.9109306084981;510.2168171856183;515.6519349439188;530.1411713024847;509.41671785805784;558.2463674040094;561.6012631031931;584.197749941427;586.4077281945181;7326.780196594111;7372.741954031583;7535.397168226528;7628.313451789366;7649.847003060019;490.78160218702334;567.4240645744572;571.9370869204384;592.6947621974687;595.5771402304326;3501.8970270768505;3536.4695295705337;3621.3119507294055;3674.356987527172;3765.246950609889;48516;49411;49897.25154122691;50441.24338134931;51389.486105139214;44372;45291.54708452939;45889;46017.30974035392;46105;40558.1888362237;41048.331858429105;41449.585504144576;41567.1686566267;41791.99999999939;7211.639418028973;7245.275472452712;7289.781306560812;7336.486445948733;7532.359749421361;31137.065888023397;31698.207544811565;31964.52053219157;31965;32327.838360808044;36997;38812.61187388203;39914;40030;40215.396769049075;8768.25469835071;8783.868241976248;8962.283017358464;9124.589393477178;9159.862602061108;9869.259805514472;9910.542272136589;9989.635455668064;10464;10572.259941803999;9669.468179250209;9726.319157658896;9782.315237933277;9926.404415735078;10125.291229613857;9447.952760236154;9448.149666529771;9448.669296574586;9594.568244428878;9663;8609.87085193735;8701.129887011248;8815.338849586178;9331.346805723535;10476.214283928888;8774.166454186841;8917.375783695079;9015.233705135137;9110.817783644157;9146.405483643543;8779.604917778588;8902.243309318781;8940.68707595231;9023.548822558978;9101.863472047793 22:37:36 INFO - 2015-11-19 22:37:36,206 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:37:36 INFO - 2015-11-19 22:37:36,206 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:37:36 INFO - 2015-11-19 22:37:36,206 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,903 22:37:36 INFO - 2015-11-19 22:37:36,206 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:37:36 INFO - 2015-11-19 22:37:36,206 DEBUG : BROWSER_OUTPUT: __startTimestamp1448001456194__endTimestamp 22:37:36 INFO - 2015-11-19 22:37:36,207 DEBUG : BROWSER_OUTPUT: 22:37:36 INFO - 2015-11-19 22:37:36,208 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:37:36 INFO - 2015-11-19 22:37:36,208 DEBUG : BROWSER_OUTPUT: Number of tests: 6 22:37:36 INFO - 2015-11-19 22:37:36,208 DEBUG : BROWSER_OUTPUT: 22:37:36 INFO - 2015-11-19 22:37:36,208 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4746.70 Median:4881.41 stddev:496.67 (10.2%) stddev-sans-first:470.78 22:37:36 INFO - 2015-11-19 22:37:36,208 DEBUG : BROWSER_OUTPUT: Values: 2672.1 2804.9 2810.0 2821.8 2822.2 4617.6 4740.8 4840.4 4894.1 4904.6 4719.6 4721.2 4792.2 4809.0 4852.9 4335.8 4494.6 4505.2 4538.4 4544.3 4297.3 4510.1 4522.6 4566.7 4587.6 4253.3 4377.3 4391.3 4422.2 4441.2 4492.3 4493.1 4503.1 4522.3 4541.2 4471.0 4488.5 4490.7 4503.2 4518.9 4451.8 4530.5 4587.9 4591.1 4675.1 5117.6 5118.6 5118.6 5120.4 5123.6 5136.7 5145.0 5146.5 5191.4 5198.0 4598.2 4632.6 4726.0 4743.8 4745.5 5279.5 5345.8 5348.7 5352.1 5399.7 5104.6 5128.8 5133.5 5135.2 5178.7 4933.5 5049.2 5053.3 5169.5 5286.5 5121.8 5127.4 5132.9 5133.2 5140.8 5125.1 5142.5 5148.1 5149.9 5169.9 4088.1 4102.7 4157.8 4194.0 4196.3 3776.7 3782.5 3792.3 3800.9 3813.8 4425.6 4434.6 4436.2 4470.1 4475.0 4939.6 5054.6 5056.5 5079.1 5219.3 4361.1 4363.9 4398.7 4417.6 4450.7 4521.5 4523.8 4526.0 4540.4 4568.1 4696.9 4804.4 4808.9 4845.8 4861.4 5070.4 5176.9 5177.3 5193.5 5224.2 4925.0 4945.4 4947.2 4949.8 4992.5 4861.8 5134.7 5143.5 5154.5 5202.3 5053.7 5076.2 5077.9 5183.1 5218.9 4991.3 5045.9 5053.2 5054.4 5107.7 4839.5 5018.9 5025.7 5050.2 5054.1 4812.7 4850.7 4881.4 4887.9 4927.6 5043.8 5080.7 5090.3 5091.6 5127.2 4943.5 4958.4 4964.5 4966.0 5019.7 22:37:36 INFO - 2015-11-19 22:37:36,208 DEBUG : BROWSER_OUTPUT: 22:37:36 INFO - 2015-11-19 22:37:36,208 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:40792.73 Median:16295.12 stddev:64602.87 (396.5%) stddev-sans-first:64713.45 22:37:36 INFO - 2015-11-19 22:37:36,208 DEBUG : BROWSER_OUTPUT: Values: 556.7 575.8 577.4 584.4 585.8 27700.3 28288.7 28327.3 36580.3 37094.3 40594.0 40989.4 41158.2 41180.6 41197.4 7897.1 8039.6 8069.2 8081.6 8084.8 7214.4 7405.4 9949.4 10063.3 10149.3 10000.7 10072.9 10106.6 10116.2 10121.9 7174.7 7352.1 10045.4 10139.4 10147.6 7085.5 10062.9 10358.1 10358.6 10365.4 36834.6 37361.0 37383.4 37391.0 37394.3 2932.4 2938.2 2947.7 2973.5 2985.5 28069.3 28202.7 28531.7 28678.6 29149.7 3458.4 3513.4 3520.3 3597.8 3640.3 222590.0 223059.0 223979.0 224594.0 224977.0 52190.5 52962.7 52976.0 56973.7 57410.4 47931.5 66004.7 66771.7 67056.7 67068.0 15138.7 15201.6 15319.2 15329.8 15374.2 10608.5 10675.8 10691.5 10699.7 10923.8 19077.5 19126.1 19228.6 19237.2 19246.2 7687.2 7869.3 7883.3 7945.2 7975.6 799.0 822.5 846.8 963.8 1057.1 24724.9 24736.1 24923.0 25048.2 25065.4 934.8 939.0 985.1 1016.2 1072.7 13190.7 13249.4 13304.5 13313.5 13384.4 263862.0 268111.0 268449.0 268783.0 270749.0 46658.8 47001.5 47086.0 47201.3 47215.3 1177.8 1192.0 1192.3 1197.3 1197.3 24558.5 25083.0 25195.6 25535.7 25597.9 39486.2 39795.8 39814.0 39828.6 39880.4 248672.0 250141.0 250171.0 250366.0 250464.0 24469.5 24823.3 28433.7 29239.6 29782.6 37069.6 37489.4 37626.0 37771.8 37967.0 32297.7 32410.2 32658.2 32798.5 33089.0 27359.7 30775.2 32837.0 33250.3 33375.0 30804.8 30944.8 31214.0 31266.4 31274.2 27889.7 29028.9 29616.7 29945.6 30258.4 21908.8 22204.7 22299.7 22343.6 22386.0 22:37:36 INFO - 2015-11-19 22:37:36,209 DEBUG : BROWSER_OUTPUT: 22:37:36 INFO - 2015-11-19 22:37:36,209 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:21642.59 Median:193961.57 stddev:56275.15 (29.0%) stddev-sans-first:56410.27 22:37:36 INFO - 2015-11-19 22:37:36,210 DEBUG : BROWSER_OUTPUT: Values: 334.7 342.8 344.4 345.5 346.0 15071.2 15084.7 16023.8 16031.4 16045.5 15363.5 15569.4 15842.8 15884.3 16230.0 4877.8 5014.7 5018.9 5020.6 5022.1 4920.4 5088.8 5088.9 5101.6 5137.1 3388.8 3472.5 3479.1 5097.8 5101.9 5111.9 5219.9 5252.8 5260.9 5264.5 4904.7 5018.8 5038.3 5144.9 5155.1 10445.7 11194.4 11325.2 13955.4 14321.2 1841.6 1847.9 1886.4 1887.9 1912.1 8334.3 8372.3 8600.0 8604.8 11627.0 1721.9 1738.8 1743.0 1757.0 1773.6 54529.2 58158.0 58313.7 58665.0 58736.7 9438.8 9641.7 9697.9 9723.2 9783.3 12502.7 12503.9 12504.6 12515.3 12518.6 7004.5 7147.0 7193.0 7253.1 7300.3 7026.0 7041.5 7054.9 7060.5 7090.2 4137.1 4145.5 4147.7 4163.1 4164.3 1596.3 1610.2 1611.5 1711.9 1740.3 847.4 893.8 898.0 931.0 936.9 15746.2 16044.0 16167.4 16245.4 16389.3 781.9 814.7 862.4 886.6 922.5 7295.4 9019.1 9130.8 9181.4 9380.5 306116.0 309533.0 310391.0 384444.0 395077.0 41527.2 56918.0 61278.7 63321.7 63842.7 1080.3 1080.7 1125.5 1149.7 1150.1 14450.1 14478.5 14521.5 14713.6 14956.0 32770.0 32892.7 33237.2 33930.5 34458.7 40034.6 40181.4 40475.2 41410.6 42838.6 7955.2 8183.2 8259.3 8280.4 8284.4 9564.6 10839.6 14736.3 14770.6 14940.7 12823.4 12853.4 12904.4 13241.9 13525.3 13153.3 13190.8 13210.7 13623.0 13799.0 12848.3 12893.5 13085.9 13151.2 13415.8 8736.7 8855.4 8987.5 9070.5 9085.2 12842.1 12945.7 13004.0 13179.6 13280.3 22:37:36 INFO - 2015-11-19 22:37:36,210 DEBUG : BROWSER_OUTPUT: 22:37:36 INFO - 2015-11-19 22:37:36,210 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4936.11 Median:6119.92 stddev:2514.03 (41.1%) stddev-sans-first:2507.52 22:37:36 INFO - 2015-11-19 22:37:36,210 DEBUG : BROWSER_OUTPUT: Values: 1460.9 1639.2 1673.4 1709.5 1716.2 6291.2 6739.4 6830.9 6844.8 6856.2 6939.1 7069.2 7070.4 7072.8 7074.9 2098.5 2121.2 2124.8 2127.7 2129.5 2116.4 2131.7 2159.4 2164.1 2164.7 2086.5 2092.0 2106.5 2118.8 2122.5 2095.7 2096.9 2100.8 2103.4 2105.6 2062.1 2111.3 2112.3 2112.8 2113.9 6068.4 6214.1 6982.0 7008.8 7011.9 4127.2 4188.1 4203.4 4223.3 4253.8 2935.6 2988.9 3041.1 3050.2 3057.5 904.8 909.5 941.6 946.5 965.6 9510.2 9573.9 9586.7 9608.2 9617.6 4098.1 4098.2 4140.1 4158.8 4171.5 8317.5 8340.8 8347.7 8363.8 8368.5 4323.3 4327.2 4336.0 4338.0 4342.4 3884.2 3909.4 3911.7 3912.4 3916.6 3257.9 3289.4 3337.1 3338.9 3340.8 1118.7 1121.9 1127.0 1129.7 1139.6 1022.4 1082.2 1170.5 1214.6 1214.8 6663.4 6726.0 6728.3 6730.0 6742.9 447.9 453.0 457.6 460.3 461.6 4926.7 5119.3 5166.3 5168.1 5170.9 7750.4 8162.1 8290.5 8329.1 8365.9 8253.3 8273.9 8322.9 8328.6 8331.8 6419.2 6509.8 6534.3 6546.1 6597.5 6550.4 6608.8 6630.9 6645.2 6656.9 7215.4 7456.3 7494.2 7494.7 7526.2 7509.6 7661.3 7675.2 7789.5 7793.4 6171.4 6179.9 6243.2 6245.5 6261.3 6752.6 6781.0 6803.0 6826.4 6831.2 6374.6 6414.0 6444.9 6476.2 6516.9 6181.2 6193.9 6242.7 6296.2 6320.0 6366.4 6403.3 6432.7 6432.8 6444.8 5771.1 5880.2 5917.0 5922.9 5925.1 5848.2 6012.8 6044.0 6046.8 6051.4 22:37:36 INFO - 2015-11-19 22:37:36,210 DEBUG : BROWSER_OUTPUT: 22:37:36 INFO - 2015-11-19 22:37:36,210 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4694.52 Median:5404.66 stddev:1373.47 (25.4%) stddev-sans-first:1337.44 22:37:36 INFO - 2015-11-19 22:37:36,211 DEBUG : BROWSER_OUTPUT: Values: 316.7 317.3 321.7 325.6 329.4 5386.2 5434.0 5450.1 5452.4 5483.8 5127.0 5214.8 5281.0 5365.6 5391.8 4212.2 4212.6 4215.4 4230.4 4237.4 3985.3 4225.8 4236.6 4240.3 4242.4 4063.7 4067.3 4192.7 4199.3 4206.7 4178.6 4184.7 4192.4 4203.4 4205.4 3718.7 3719.2 3722.0 3723.7 3723.8 5147.0 5187.1 5192.8 5198.8 5200.6 4648.0 4777.1 4779.4 4783.3 4784.4 5659.3 5660.1 5661.7 5662.6 5674.9 3943.9 3948.1 3953.3 3955.0 3958.6 5891.8 5957.9 5963.3 5966.1 5976.9 3938.4 4048.1 4054.7 4074.3 4075.4 4126.9 4133.4 4133.8 4134.4 4140.0 4753.6 4757.3 4758.5 4761.7 4763.5 5670.0 5670.7 5690.5 5698.1 5698.4 1418.3 1439.2 1461.8 1464.1 1466.1 963.8 981.9 986.1 986.2 991.6 4761.6 4819.8 4823.7 4824.3 4831.0 5655.3 5678.7 5688.0 5688.5 5701.4 4754.1 4798.6 4807.4 4883.8 4942.5 3132.0 3156.7 3167.2 3169.6 3171.4 5549.6 5586.9 5589.2 5590.7 5594.9 5879.7 5993.2 5997.9 6032.3 6056.1 5552.2 5575.6 5576.9 5594.7 5632.0 5848.6 5884.3 5888.5 5896.4 5902.4 5771.3 5788.6 5809.7 5813.4 5813.5 5474.9 5603.4 5610.1 5614.9 5621.1 4038.7 4063.8 4070.3 4078.7 4081.4 5893.1 5893.6 5895.9 5905.6 5905.6 5535.8 5608.6 5646.7 5660.5 5669.2 5630.2 5645.2 5647.6 5650.7 5661.7 5535.4 5536.1 5536.9 5539.1 5543.3 5706.3 5706.7 5707.9 5712.1 5714.4 5417.5 5563.6 5565.1 5567.4 5569.1 22:37:36 INFO - 2015-11-19 22:37:36,211 DEBUG : BROWSER_OUTPUT: 22:37:36 INFO - 2015-11-19 22:37:36,211 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12202.97 Median:3221.08 stddev:14077.71 (437.0%) stddev-sans-first:14090.63 22:37:36 INFO - 2015-11-19 22:37:36,212 DEBUG : BROWSER_OUTPUT: Values: 685.5 696.6 707.8 737.4 757.5 10888.0 11342.3 11348.9 11351.1 11372.7 12043.9 12168.8 12181.9 12182.2 12188.2 613.6 621.7 782.0 792.3 807.7 802.9 803.1 810.6 811.1 811.2 840.8 851.4 851.8 852.7 852.8 651.9 660.8 823.2 842.9 845.3 620.0 625.4 625.7 626.0 626.0 12233.2 12403.3 12414.2 12418.6 12421.4 11273.9 11995.9 12169.6 12250.8 12423.6 9690.2 9693.3 9698.5 9762.3 9778.8 3203.8 3215.9 3228.4 3261.2 3338.3 39517.8 40743.6 43436.6 43827.8 43959.0 9169.1 9196.9 9276.0 9345.3 9414.7 16611.8 16613.4 16619.3 16631.6 16634.5 5858.0 5872.5 5902.4 6034.3 6132.8 3543.0 3570.1 3571.1 3583.7 3601.1 2810.9 2835.1 2842.1 2847.7 2852.4 498.2 508.9 510.2 515.7 530.1 509.4 558.2 561.6 584.2 586.4 7326.8 7372.7 7535.4 7628.3 7649.8 490.8 567.4 571.9 592.7 595.6 3501.9 3536.5 3621.3 3674.4 3765.2 48516.0 49411.0 49897.3 50441.2 51389.5 44372.0 45291.5 45889.0 46017.3 46105.0 40558.2 41048.3 41449.6 41567.2 41792.0 7211.6 7245.3 7289.8 7336.5 7532.4 31137.1 31698.2 31964.5 31965.0 32327.8 36997.0 38812.6 39914.0 40030.0 40215.4 8768.3 8783.9 8962.3 9124.6 9159.9 9869.3 9910.5 9989.6 10464.0 10572.3 9669.5 9726.3 9782.3 9926.4 10125.3 9448.0 9448.1 9448.7 9594.6 9663.0 8609.9 8701.1 8815.3 9331.3 10476.2 8774.2 8917.4 9015.2 9110.8 9146.4 8779.6 8902.2 8940.7 9023.5 9101.9 22:37:36 INFO - 2015-11-19 22:37:36,212 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:37:36 INFO - 2015-11-19 22:37:36,212 DEBUG : BROWSER_OUTPUT: 22:37:36 INFO - 2015-11-19 22:37:36,917 INFO : Browser exited with error code: 0 22:37:36 INFO - 2015-11-19 22:37:36,933 INFO : Completed test dromaeo_css (00:18:37) 22:37:36 INFO - 2015-11-19 22:37:36,934 INFO : Starting test kraken 22:37:36 INFO - 2015-11-19 22:37:36,934 DEBUG : operating with platform_type : linux_ 22:37:36 INFO - 2015-11-19 22:37:36,934 INFO : Initialising browser for kraken test... 22:37:36 INFO - 2015-11-19 22:37:36,940 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSj3E01/profile http://localhost:40330/getInfo.html 22:37:40 INFO - 2015-11-19 22:37:40,900 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:37:40 INFO - 2015-11-19 22:37:40,900 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:37:40 INFO - 2015-11-19 22:37:40,900 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 22:37:40 INFO - 2015-11-19 22:37:40,900 DEBUG : BROWSER_OUTPUT: __metrics 22:37:40 INFO - 2015-11-19 22:37:40,989 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:37:42 INFO - 2015-11-19 22:37:42,209 INFO : Browser initialized. 22:37:42 INFO - 2015-11-19 22:37:42,210 INFO : Running cycle 1/1 for kraken test... 22:37:42 INFO - 2015-11-19 22:37:42,210 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSj3E01/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:37:54 INFO - 2015-11-19 22:37:54,182 DEBUG : BROWSER_OUTPUT: RSS: Main: 141750272 22:37:54 INFO - 2015-11-19 22:37:54,183 DEBUG : BROWSER_OUTPUT: 22:37:56 INFO - 2015-11-19 22:37:56,797 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40330/tests/kraken/driver.html?testName=audio-beat-detection) 22:37:57 INFO - 2015-11-19 22:37:57,379 DEBUG : BROWSER_OUTPUT: RSS: Main: 178528256 22:37:57 INFO - 2015-11-19 22:37:57,379 DEBUG : BROWSER_OUTPUT: 22:38:00 INFO - 2015-11-19 22:38:00,124 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40330/tests/kraken/driver.html?testName=audio-dft) 22:38:00 INFO - 2015-11-19 22:38:00,705 DEBUG : BROWSER_OUTPUT: RSS: Main: 167899136 22:38:00 INFO - 2015-11-19 22:38:00,705 DEBUG : BROWSER_OUTPUT: 22:38:03 INFO - 2015-11-19 22:38:03,705 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40330/tests/kraken/driver.html?testName=audio-fft) 22:38:04 INFO - 2015-11-19 22:38:04,278 DEBUG : BROWSER_OUTPUT: RSS: Main: 153108480 22:38:04 INFO - 2015-11-19 22:38:04,279 DEBUG : BROWSER_OUTPUT: 22:38:06 INFO - 2015-11-19 22:38:06,295 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40330/tests/kraken/driver.html?testName=audio-oscillator) 22:38:06 INFO - 2015-11-19 22:38:06,865 DEBUG : BROWSER_OUTPUT: RSS: Main: 154152960 22:38:06 INFO - 2015-11-19 22:38:06,865 DEBUG : BROWSER_OUTPUT: 22:38:09 INFO - 2015-11-19 22:38:09,085 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40330/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:38:09 INFO - 2015-11-19 22:38:09,646 DEBUG : BROWSER_OUTPUT: RSS: Main: 156479488 22:38:09 INFO - 2015-11-19 22:38:09,646 DEBUG : BROWSER_OUTPUT: 22:38:12 INFO - 2015-11-19 22:38:12,590 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40330/tests/kraken/driver.html?testName=imaging-darkroom) 22:38:13 INFO - 2015-11-19 22:38:13,171 DEBUG : BROWSER_OUTPUT: RSS: Main: 157110272 22:38:13 INFO - 2015-11-19 22:38:13,171 DEBUG : BROWSER_OUTPUT: 22:38:16 INFO - 2015-11-19 22:38:16,619 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40330/tests/kraken/driver.html?testName=imaging-desaturate) 22:38:17 INFO - 2015-11-19 22:38:17,193 DEBUG : BROWSER_OUTPUT: RSS: Main: 157859840 22:38:17 INFO - 2015-11-19 22:38:17,193 DEBUG : BROWSER_OUTPUT: 22:38:19 INFO - 2015-11-19 22:38:19,996 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40330/tests/kraken/driver.html?testName=json-parse-financial) 22:38:20 INFO - 2015-11-19 22:38:20,582 DEBUG : BROWSER_OUTPUT: RSS: Main: 157433856 22:38:20 INFO - 2015-11-19 22:38:20,582 DEBUG : BROWSER_OUTPUT: 22:38:22 INFO - 2015-11-19 22:38:22,354 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40330/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:38:22 INFO - 2015-11-19 22:38:22,921 DEBUG : BROWSER_OUTPUT: RSS: Main: 162488320 22:38:22 INFO - 2015-11-19 22:38:22,921 DEBUG : BROWSER_OUTPUT: 22:38:24 INFO - 2015-11-19 22:38:24,831 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40330/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:38:25 INFO - 2015-11-19 22:38:25,421 DEBUG : BROWSER_OUTPUT: RSS: Main: 163864576 22:38:25 INFO - 2015-11-19 22:38:25,421 DEBUG : BROWSER_OUTPUT: 22:38:27 INFO - 2015-11-19 22:38:27,517 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40330/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:38:28 INFO - 2015-11-19 22:38:28,090 DEBUG : BROWSER_OUTPUT: RSS: Main: 171200512 22:38:28 INFO - 2015-11-19 22:38:28,091 DEBUG : BROWSER_OUTPUT: 22:38:30 INFO - 2015-11-19 22:38:30,611 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40330/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:38:31 INFO - 2015-11-19 22:38:31,193 DEBUG : BROWSER_OUTPUT: RSS: Main: 177500160 22:38:31 INFO - 2015-11-19 22:38:31,193 DEBUG : BROWSER_OUTPUT: 22:38:33 INFO - 2015-11-19 22:38:33,788 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40330/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:38:34 INFO - 2015-11-19 22:38:34,363 DEBUG : BROWSER_OUTPUT: RSS: Main: 176103424 22:38:34 INFO - 2015-11-19 22:38:34,363 DEBUG : BROWSER_OUTPUT: 22:38:36 INFO - 2015-11-19 22:38:36,114 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40330/tests/kraken/driver.html?testName=ai-astar) 22:38:36 INFO - 2015-11-19 22:38:36,364 DEBUG : BROWSER_OUTPUT: RSS: Main: 234328064 22:38:36 INFO - 2015-11-19 22:38:36,364 DEBUG : BROWSER_OUTPUT: 22:38:36 INFO - 2015-11-19 22:38:36,366 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:38:36 INFO - 2015-11-19 22:38:36,366 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:38:36 INFO - 2015-11-19 22:38:36,366 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:38:36 INFO - 2015-11-19 22:38:36,366 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:38:36 INFO - 2015-11-19 22:38:36,366 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;113;106;112;111;111;100;111;116;112 22:38:36 INFO - 2015-11-19 22:38:36,367 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;163;130;162;173;130;167;159;132;168;129 22:38:36 INFO - 2015-11-19 22:38:36,367 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;160;161;161;159;160;162;161;162;160 22:38:36 INFO - 2015-11-19 22:38:36,367 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;72;88;93;73;72;72;112;91;73 22:38:36 INFO - 2015-11-19 22:38:36,367 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;91;92;127;90;92;92;123;91;92;129 22:38:36 INFO - 2015-11-19 22:38:36,368 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;110;109;110;109;110;110;110 22:38:36 INFO - 2015-11-19 22:38:36,368 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;147;146;147;148;147;146;147;146;147 22:38:36 INFO - 2015-11-19 22:38:36,368 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 22:38:36 INFO - 2015-11-19 22:38:36,368 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;71;71;71;73;72;70;72;80;70;69 22:38:36 INFO - 2015-11-19 22:38:36,368 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;69;68;69;69;68;68;68;69;69 22:38:36 INFO - 2015-11-19 22:38:36,369 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;83;83;83;84;82;82;83;85;83 22:38:36 INFO - 2015-11-19 22:38:36,369 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;119;121;120;134;126;125;125;120;123;123 22:38:36 INFO - 2015-11-19 22:38:36,369 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;138;151;152;157;137;146;147;149;146;138 22:38:36 INFO - 2015-11-19 22:38:36,369 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;68;69;70;68;68;69;69;70;67;69 22:38:36 INFO - 2015-11-19 22:38:36,370 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:38:36 INFO - 2015-11-19 22:38:36,370 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:38:36 INFO - 2015-11-19 22:38:36,370 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,965 22:38:36 INFO - 2015-11-19 22:38:36,370 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:38:36 INFO - 2015-11-19 22:38:36,370 DEBUG : BROWSER_OUTPUT: __startTimestamp1448001516364__endTimestamp 22:38:36 INFO - 2015-11-19 22:38:36,371 DEBUG : BROWSER_OUTPUT: 22:38:36 INFO - 2015-11-19 22:38:36,374 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:38:36 INFO - 2015-11-19 22:38:36,374 DEBUG : BROWSER_OUTPUT: Number of tests: 14 22:38:36 INFO - 2015-11-19 22:38:36,374 DEBUG : BROWSER_OUTPUT: 22:38:36 INFO - 2015-11-19 22:38:36,375 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:110.10 Median:111.50 stddev:4.38 (3.9%) stddev-sans-first:4.63 22:38:36 INFO - 2015-11-19 22:38:36,375 DEBUG : BROWSER_OUTPUT: Values: 109.0 113.0 106.0 112.0 111.0 111.0 100.0 111.0 116.0 112.0 22:38:36 INFO - 2015-11-19 22:38:36,375 DEBUG : BROWSER_OUTPUT: 22:38:36 INFO - 2015-11-19 22:38:36,375 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:151.30 Median:162.50 stddev:18.51 (11.4%) stddev-sans-first:19.14 22:38:36 INFO - 2015-11-19 22:38:36,375 DEBUG : BROWSER_OUTPUT: Values: 163.0 130.0 162.0 173.0 130.0 167.0 159.0 132.0 168.0 129.0 22:38:36 INFO - 2015-11-19 22:38:36,375 DEBUG : BROWSER_OUTPUT: 22:38:36 INFO - 2015-11-19 22:38:36,375 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 22:38:36 INFO - 2015-11-19 22:38:36,375 DEBUG : BROWSER_OUTPUT: Values: 161.0 160.0 161.0 161.0 159.0 160.0 162.0 161.0 162.0 160.0 22:38:36 INFO - 2015-11-19 22:38:36,376 DEBUG : BROWSER_OUTPUT: 22:38:36 INFO - 2015-11-19 22:38:36,376 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.90 Median:73.00 stddev:13.67 (18.7%) stddev-sans-first:14.11 22:38:36 INFO - 2015-11-19 22:38:36,376 DEBUG : BROWSER_OUTPUT: Values: 73.0 72.0 88.0 93.0 73.0 72.0 72.0 112.0 91.0 73.0 22:38:36 INFO - 2015-11-19 22:38:36,376 DEBUG : BROWSER_OUTPUT: 22:38:36 INFO - 2015-11-19 22:38:36,376 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:101.90 Median:91.50 stddev:16.93 (18.5%) stddev-sans-first:17.50 22:38:36 INFO - 2015-11-19 22:38:36,376 DEBUG : BROWSER_OUTPUT: Values: 91.0 92.0 127.0 90.0 92.0 92.0 123.0 91.0 92.0 129.0 22:38:36 INFO - 2015-11-19 22:38:36,376 DEBUG : BROWSER_OUTPUT: 22:38:36 INFO - 2015-11-19 22:38:36,376 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.60 Median:110.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 22:38:36 INFO - 2015-11-19 22:38:36,377 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 110.0 109.0 110.0 109.0 110.0 110.0 110.0 22:38:36 INFO - 2015-11-19 22:38:36,377 DEBUG : BROWSER_OUTPUT: 22:38:36 INFO - 2015-11-19 22:38:36,377 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 22:38:36 INFO - 2015-11-19 22:38:36,377 DEBUG : BROWSER_OUTPUT: Values: 147.0 147.0 146.0 147.0 148.0 147.0 146.0 147.0 146.0 147.0 22:38:36 INFO - 2015-11-19 22:38:36,377 DEBUG : BROWSER_OUTPUT: 22:38:36 INFO - 2015-11-19 22:38:36,377 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 22:38:36 INFO - 2015-11-19 22:38:36,377 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 22:38:36 INFO - 2015-11-19 22:38:36,378 DEBUG : BROWSER_OUTPUT: 22:38:36 INFO - 2015-11-19 22:38:36,378 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:71.90 Median:71.50 stddev:3.07 (4.3%) stddev-sans-first:3.24 22:38:36 INFO - 2015-11-19 22:38:36,378 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 71.0 73.0 72.0 70.0 72.0 80.0 70.0 69.0 22:38:36 INFO - 2015-11-19 22:38:36,378 DEBUG : BROWSER_OUTPUT: 22:38:36 INFO - 2015-11-19 22:38:36,378 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.50 Median:69.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 22:38:36 INFO - 2015-11-19 22:38:36,378 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 68.0 69.0 69.0 68.0 68.0 68.0 69.0 69.0 22:38:36 INFO - 2015-11-19 22:38:36,378 DEBUG : BROWSER_OUTPUT: 22:38:36 INFO - 2015-11-19 22:38:36,378 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:83.00 stddev:0.88 (1.1%) stddev-sans-first:0.93 22:38:36 INFO - 2015-11-19 22:38:36,379 DEBUG : BROWSER_OUTPUT: Values: 83.0 83.0 83.0 83.0 84.0 82.0 82.0 83.0 85.0 83.0 22:38:36 INFO - 2015-11-19 22:38:36,379 DEBUG : BROWSER_OUTPUT: 22:38:36 INFO - 2015-11-19 22:38:36,379 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:123.60 Median:124.00 stddev:4.38 (3.5%) stddev-sans-first:4.31 22:38:36 INFO - 2015-11-19 22:38:36,379 DEBUG : BROWSER_OUTPUT: Values: 119.0 121.0 120.0 134.0 126.0 125.0 125.0 120.0 123.0 123.0 22:38:36 INFO - 2015-11-19 22:38:36,379 DEBUG : BROWSER_OUTPUT: 22:38:36 INFO - 2015-11-19 22:38:36,379 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.10 Median:148.00 stddev:6.67 (4.5%) stddev-sans-first:6.40 22:38:36 INFO - 2015-11-19 22:38:36,379 DEBUG : BROWSER_OUTPUT: Values: 138.0 151.0 152.0 157.0 137.0 146.0 147.0 149.0 146.0 138.0 22:38:36 INFO - 2015-11-19 22:38:36,379 DEBUG : BROWSER_OUTPUT: 22:38:36 INFO - 2015-11-19 22:38:36,379 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.00 stddev:0.95 (1.4%) stddev-sans-first:0.97 22:38:36 INFO - 2015-11-19 22:38:36,379 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 70.0 68.0 68.0 69.0 69.0 70.0 67.0 69.0 22:38:36 INFO - 2015-11-19 22:38:36,380 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:38:36 INFO - 2015-11-19 22:38:36,380 DEBUG : BROWSER_OUTPUT: 22:38:37 INFO - 2015-11-19 22:38:37,285 INFO : Browser exited with error code: 0 22:38:37 INFO - 2015-11-19 22:38:37,336 INFO : Completed test kraken (00:01:00) 22:38:37 INFO - 2015-11-19 22:38:37,336 INFO : Starting test v8_7 22:38:37 INFO - 2015-11-19 22:38:37,336 DEBUG : operating with platform_type : linux_ 22:38:37 INFO - 2015-11-19 22:38:37,336 INFO : Initialising browser for v8_7 test... 22:38:37 INFO - 2015-11-19 22:38:37,343 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplDSk9P/profile http://localhost:40330/getInfo.html 22:38:41 INFO - 2015-11-19 22:38:41,385 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:38:41 INFO - 2015-11-19 22:38:41,385 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:38:41 INFO - 2015-11-19 22:38:41,385 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 22:38:41 INFO - 2015-11-19 22:38:41,385 DEBUG : BROWSER_OUTPUT: __metrics 22:38:41 INFO - 2015-11-19 22:38:41,548 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:38:42 INFO - 2015-11-19 22:38:42,712 INFO : Browser initialized. 22:38:42 INFO - 2015-11-19 22:38:42,713 INFO : Running cycle 1/1 for v8_7 test... 22:38:42 INFO - 2015-11-19 22:38:42,713 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplDSk9P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:38:54 INFO - 2015-11-19 22:38:54,685 DEBUG : BROWSER_OUTPUT: RSS: Main: 142565376 22:38:54 INFO - 2015-11-19 22:38:54,685 DEBUG : BROWSER_OUTPUT: 22:39:16 INFO - 2015-11-19 22:39:16,131 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40330/tests/v8_7/run.html (next: http://localhost:40330/tests/v8_7/run.html) 22:39:16 INFO - 2015-11-19 22:39:16,389 DEBUG : BROWSER_OUTPUT: RSS: Main: 444375040 22:39:16 INFO - 2015-11-19 22:39:16,389 DEBUG : BROWSER_OUTPUT: 22:39:16 INFO - 2015-11-19 22:39:16,389 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:39:16 INFO - 2015-11-19 22:39:16,390 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:39:16 INFO - 2015-11-19 22:39:16,390 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:39:16 INFO - 2015-11-19 22:39:16,390 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:39:16 INFO - 2015-11-19 22:39:16,390 DEBUG : BROWSER_OUTPUT: |0;Richards;167.1402306535183 22:39:16 INFO - 2015-11-19 22:39:16,390 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;192.01228878648234 22:39:16 INFO - 2015-11-19 22:39:16,390 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.72027972027973 22:39:16 INFO - 2015-11-19 22:39:16,390 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5035.175879396985 22:39:16 INFO - 2015-11-19 22:39:16,391 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1976.2845849802372 22:39:16 INFO - 2015-11-19 22:39:16,391 DEBUG : BROWSER_OUTPUT: |5;Earley;786.7820613690008 22:39:16 INFO - 2015-11-19 22:39:16,391 DEBUG : BROWSER_OUTPUT: |6;Boyer;12000 22:39:16 INFO - 2015-11-19 22:39:16,391 DEBUG : BROWSER_OUTPUT: |7;RegExp;26205.128205128207 22:39:16 INFO - 2015-11-19 22:39:16,391 DEBUG : BROWSER_OUTPUT: |8;Splay;629.3266205160478 22:39:16 INFO - 2015-11-19 22:39:16,391 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 22:39:16 INFO - 2015-11-19 22:39:16,391 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:39:16 INFO - 2015-11-19 22:39:16,392 DEBUG : BROWSER_OUTPUT: __startTimestamp1448001556382__endTimestamp 22:39:16 INFO - 2015-11-19 22:39:16,392 DEBUG : BROWSER_OUTPUT: 22:39:16 INFO - 2015-11-19 22:39:16,392 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:39:16 INFO - 2015-11-19 22:39:16,392 DEBUG : BROWSER_OUTPUT: Number of tests: 10 22:39:16 INFO - 2015-11-19 22:39:16,392 DEBUG : BROWSER_OUTPUT: 22:39:16 INFO - 2015-11-19 22:39:16,392 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.14 Median:167.14 stddev:0.00 (0.0%) 22:39:16 INFO - 2015-11-19 22:39:16,392 DEBUG : BROWSER_OUTPUT: Values: 167.1 22:39:16 INFO - 2015-11-19 22:39:16,393 DEBUG : BROWSER_OUTPUT: 22:39:16 INFO - 2015-11-19 22:39:16,393 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:192.01 Median:192.01 stddev:0.00 (0.0%) 22:39:16 INFO - 2015-11-19 22:39:16,393 DEBUG : BROWSER_OUTPUT: Values: 192.0 22:39:16 INFO - 2015-11-19 22:39:16,393 DEBUG : BROWSER_OUTPUT: 22:39:16 INFO - 2015-11-19 22:39:16,393 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.72 Median:279.72 stddev:0.00 (0.0%) 22:39:16 INFO - 2015-11-19 22:39:16,393 DEBUG : BROWSER_OUTPUT: Values: 279.7 22:39:16 INFO - 2015-11-19 22:39:16,393 DEBUG : BROWSER_OUTPUT: 22:39:16 INFO - 2015-11-19 22:39:16,393 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5035.18 Median:5035.18 stddev:0.00 (0.0%) 22:39:16 INFO - 2015-11-19 22:39:16,393 DEBUG : BROWSER_OUTPUT: Values: 5035.2 22:39:16 INFO - 2015-11-19 22:39:16,393 DEBUG : BROWSER_OUTPUT: 22:39:16 INFO - 2015-11-19 22:39:16,393 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1976.28 Median:1976.28 stddev:0.00 (0.0%) 22:39:16 INFO - 2015-11-19 22:39:16,393 DEBUG : BROWSER_OUTPUT: Values: 1976.3 22:39:16 INFO - 2015-11-19 22:39:16,393 DEBUG : BROWSER_OUTPUT: 22:39:16 INFO - 2015-11-19 22:39:16,394 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:786.78 Median:786.78 stddev:0.00 (0.0%) 22:39:16 INFO - 2015-11-19 22:39:16,394 DEBUG : BROWSER_OUTPUT: Values: 786.8 22:39:16 INFO - 2015-11-19 22:39:16,394 DEBUG : BROWSER_OUTPUT: 22:39:16 INFO - 2015-11-19 22:39:16,394 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12000.00 Median:12000.00 stddev:0.00 (0.0%) 22:39:16 INFO - 2015-11-19 22:39:16,394 DEBUG : BROWSER_OUTPUT: Values: 12000.0 22:39:16 INFO - 2015-11-19 22:39:16,394 DEBUG : BROWSER_OUTPUT: 22:39:16 INFO - 2015-11-19 22:39:16,394 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26205.13 Median:26205.13 stddev:0.00 (0.0%) 22:39:16 INFO - 2015-11-19 22:39:16,394 DEBUG : BROWSER_OUTPUT: Values: 26205.1 22:39:16 INFO - 2015-11-19 22:39:16,394 DEBUG : BROWSER_OUTPUT: 22:39:16 INFO - 2015-11-19 22:39:16,394 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:629.33 Median:629.33 stddev:0.00 (0.0%) 22:39:16 INFO - 2015-11-19 22:39:16,394 DEBUG : BROWSER_OUTPUT: Values: 629.3 22:39:16 INFO - 2015-11-19 22:39:16,394 DEBUG : BROWSER_OUTPUT: 22:39:16 INFO - 2015-11-19 22:39:16,394 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 22:39:16 INFO - 2015-11-19 22:39:16,394 DEBUG : BROWSER_OUTPUT: Values: 4826.9 22:39:16 INFO - 2015-11-19 22:39:16,394 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:39:16 INFO - 2015-11-19 22:39:16,394 DEBUG : BROWSER_OUTPUT: 22:39:17 INFO - 2015-11-19 22:39:17,237 INFO : Browser exited with error code: 0 22:39:17 INFO - 2015-11-19 22:39:17,249 INFO : Completed test v8_7 (00:00:39) 22:39:17 INFO - 2015-11-19 22:39:17,365 INFO : Completed test suite (00:20:17) 22:39:17 INFO - 2015-11-19 22:39:17,365 DEBUG : Working with test: dromaeo_css 22:39:17 INFO - 2015-11-19 22:39:17,365 DEBUG : Generating results file: dromaeo_css 22:39:17 INFO - 2015-11-19 22:39:17,366 DEBUG : Working with test: kraken 22:39:17 INFO - 2015-11-19 22:39:17,366 DEBUG : Generating results file: kraken 22:39:17 INFO - 2015-11-19 22:39:17,366 INFO : javascript benchmark 22:39:17 INFO - 2015-11-19 22:39:17,367 DEBUG : Working with test: v8_7 22:39:17 INFO - 2015-11-19 22:39:17,367 DEBUG : Generating results file: v8_7 22:39:17 INFO - 2015-11-19 22:39:17,367 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:39:17 INFO - 2015-11-19 22:39:17,418 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,203,35]] 22:39:17 INFO - 2015-11-19 22:39:17,418 DEBUG : process_Request line: dromaeo_css 6291.64 graph.html#tests=[[72,203,35]] 22:39:17 INFO - 2015-11-19 22:39:17,419 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:39:17 INFO - 2015-11-19 22:39:17,454 DEBUG : process_Request line: kraken 1519.20 graph.html#tests=[[232,203,35]] 22:39:17 INFO - 2015-11-19 22:39:17,454 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:39:17 INFO - 2015-11-19 22:39:17,490 DEBUG : process_Request line: v8_7 19800.93 graph.html#tests=[[230,203,35]] 22:39:17 INFO - 2015-11-19 22:39:17,492 INFO : javascript benchmark 22:39:17 INFO - 2015-11-19 22:39:17,495 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2672.104844876966, 2804.929876753081, 2810.0305394638845, 2821.8024738268323, 2822.1674605991216, 4617.607503362218, 4740.786664600093, 4840.4191497020365, 4894.070126675933, 4904.607631389489, 4719.646026548014, 4721.197396442604, 4792.185328494155, 4808.951910480894, 4852.854414367565, 4335.783210839453, 4494.573015563533, 4505.166544189336, 4538.432695913011, 4544.341070544769, 4297.333913243452, 4510.097980403916, 4522.6155776759015, 4566.725996440207, 4587.564181402761, 4253.29820579604, 4377.299632058855, 4391.297392417198, 4422.226110430662, 4441.222786012468, 4492.303692927569, 4493.078918821667, 4503.1218912312115, 4522.344260082303, 4541.227991241497, 4471.016376397188, 4488.528704486047, 4490.730556166641, 4503.234450143484, 4518.909621807578, 4451.755153466558, 4530.478994915594, 4587.885302867422, 4591.104734576759, 4675.088357770236, 5117.590592752608, 5118.59051275897, 5118.6416950813455, 5120.427833097227, 5123.590112790967, 5136.743162841843, 5145.048166089248, 5146.459621739701, 5191.402988656278, 5198.012377648235, 4598.218302888517, 4632.629389648854, 4725.976370118127, 4743.810247590127, 4745.549172828576, 5279.472052794767, 5345.7594408251725, 5348.652337598043, 5352.116900711417, 5399.730013499309, 5104.566111880457, 5128.820491282787, 5133.4609865964285, 5135.152699804565, 5178.663386879841, 4933.50664933511, 5049.2426136079885, 5053.317802096743, 5169.508896654812, 5286.497782710637, 5121.8463446096675, 5127.410347809975, 5132.897342053138, 5133.153029750049, 5140.768665410065, 5125.1287281162295, 5142.51146141116, 5148.099082660594, 5149.871253218699, 5169.922451163236, 4088.059746258318, 4102.651274641629, 4157.771322577288, 4193.979030104891, 4196.286631272691, 3776.6789822864816, 3782.5460944686815, 3792.2984247914223, 3800.9239815204096, 3813.8474461021246, 4425.579569940851, 4434.578715022068, 4436.245838207513, 4470.083632855257, 4475.01549659081, 4939.604831613534, 5054.620903432257, 5056.544910958031, 5079.08576456234, 5219.269302297681, 4361.1495758327455, 4363.890902727394, 4398.714083584557, 4417.646588272994, 4450.710703804307, 4521.480029796549, 4523.819047238074, 4526.049529598756, 4540.409746732904, 4568.086382723469, 4696.882577935579, 4804.375431193923, 4808.8797780055775, 4845.8061677532505, 4861.392325959326, 5070.366204224472, 5176.948230517647, 5177.326947496952, 5193.532582067632, 5224.164133738584, 4924.990376972783, 4945.357103576512, 4947.208446648519, 4949.752512374295, 4992.500749924979, 4861.829835955725, 5134.691918484903, 5143.485651434827, 5154.484551544815, 5202.297689811979, 5053.7220452875445, 5076.238564215398, 5077.898442031213, 5183.092958732132, 5218.947810521846, 4991.301217829434, 5045.899082018413, 5053.191489361684, 5054.444011158843, 5107.668001580034, 4839.516048395273, 5018.924716129334, 5025.698458092526, 5050.191969284897, 5054.1155297822, 4812.711237325771, 4850.660453768202, 4881.414230292387, 4887.926681099715, 4927.5565199130815, 5043.823466178666, 5080.720560369067, 5090.312807771052, 5091.59267258611, 5127.230915362726, 4943.4562198157455, 4958.355413796184, 4964.503549645151, 4965.975170124127, 5019.7239151847], "unit": "ms", "name": "dojo.html", "value": 4716.097930640278}, {"lowerIsBetter": false, "replicates": [556.69381839988, 575.830130111617, 577.3677822784051, 584.4301805739404, 585.8008277185756, 27700.307492312702, 28288.71711282889, 28327.291817704543, 36580.26839463209, 37094.258114837685, 40594.0, 40989.385159222635, 41158.17683646325, 41180.58819411805, 41197.38203926943, 7897.052353717562, 8039.638216280281, 8069.233422824822, 8081.595920203996, 8084.797880052987, 7214.42284617232, 7405.444591655647, 9949.403035817873, 10063.29556931015, 10149.289549731522, 10000.699979000641, 10072.949635251813, 10106.646267380644, 10116.241281903887, 10121.898781012205, 7174.677139528734, 7352.117745870476, 10045.447500387476, 10139.44233067181, 10147.644832430904, 7085.468589855782, 10062.949685251528, 10358.119559837423, 10358.637447689294, 10365.429901355423, 36834.63165368339, 37361.0, 37383.43924841102, 37391.0, 37394.25211495755, 2932.3695405487924, 2938.1919972007654, 2947.690492498278, 2973.5391014392803, 2985.477541430241, 28069.298267543272, 28202.71797282042, 28531.714682853115, 28678.569821452696, 29149.708502915124, 3458.394780913355, 3513.3851575974104, 3520.3135388599235, 3597.8201089945396, 3640.2537479816574, 222590.0, 223059.0, 223979.0, 224594.0, 224977.0, 52190.47809521932, 52962.73518632459, 52976.0, 56973.71513142408, 57410.42589574134, 47931.520684792704, 66004.6699766498, 66771.66614166995, 67056.66471667709, 67068.0, 15138.697226055418, 15201.61995950088, 15319.157446340558, 15329.846701533064, 15374.154421506924, 10608.469576521144, 10675.83986240207, 10691.465426728788, 10699.679009629723, 10923.781524369468, 19077.523061923286, 19126.087391260764, 19228.61542769137, 19237.230510779693, 19246.23015079409, 7687.154413014564, 7869.291763741291, 7883.329916957008, 7945.205479452125, 7975.64718408071, 799.0172088331385, 822.4859462835727, 846.754441212054, 963.7590602349412, 1057.1173070486077, 24724.876375618365, 24736.134235301677, 24923.0, 25048.248552543453, 25065.37336566564, 934.8036912248504, 939.0421769794907, 985.0641890204334, 1016.2225897188656, 1072.6752460711016, 13190.73618527624, 13249.403776830066, 13304.46782128704, 13313.534026309033, 13384.397702103428, 263862.0, 268111.0, 268449.0, 268783.0, 270749.0, 46658.76670616625, 47001.5299847004, 47086.0, 47201.29198061963, 47215.291770623466, 1177.770334784726, 1191.9642410727865, 1192.3322939154102, 1197.3235122155954, 1197.329495482515, 24558.508829822946, 25083.0, 25195.622065668664, 25535.744642553338, 25597.87201063983, 39486.210275794896, 39795.80102099587, 39814.0, 39828.60171398249, 39880.40179397253, 248672.0, 250141.0, 250171.0, 250366.0, 250464.0, 24469.51060978806, 24823.25530234096, 28433.715662843108, 29239.561406578494, 29782.553261700657, 37069.62930370662, 37489.4376584346, 37626.0, 37771.81114094412, 37967.0, 32297.677023229466, 32410.189745255615, 32658.183545411553, 32798.5080223792, 33089.0, 27359.72640273572, 30775.2306192347, 32837.0, 33250.33499330048, 33375.0, 30804.845975770873, 30944.822448969102, 31214.0, 31266.37467250597, 31274.21814454657, 27889.721102788713, 29028.854855725585, 29616.703832962256, 29945.550816738203, 30258.394832103673, 21908.780912191312, 22204.66692999574, 22299.665505017114, 22343.553128937005, 22386.0], "unit": "ms", "name": "ext.html", "value": 17084.73745134774}, {"lowerIsBetter": false, "replicates": [334.74057605355847, 342.8217326989967, 344.4316877152698, 345.49880768062764, 345.9965400345997, 15071.246437678114, 15084.698306033886, 16023.759643605355, 16031.438899638515, 16045.518634440956, 15363.539093827176, 15569.37722491099, 15842.84157158428, 15884.285207165676, 16230.026198428073, 4877.804887804493, 5014.749262536877, 5018.946021335524, 5020.573251273646, 5022.0709168803705, 4920.384951881015, 5088.771005304751, 5088.92366614501, 5101.642884998052, 5137.12668846297, 3388.7966721996636, 3472.496488009237, 3479.1302174456387, 5097.770600322994, 5101.872453188681, 5111.897762044757, 5219.869503262449, 5252.763625636855, 5260.92108618371, 5264.526192642661, 4904.656674032819, 5018.824341148043, 5038.294638750541, 5144.922826157611, 5155.149400348938, 10445.738856528571, 11194.44027798615, 11325.207235493519, 13955.441782328695, 14321.212333321662, 1841.5672317005492, 1847.9445616631522, 1886.4152112844924, 1887.8961657108853, 1912.139537208248, 8334.291585215264, 8372.28835548979, 8599.957000215021, 8604.78488037804, 11627.0, 1721.9225134868962, 1738.8174241704692, 1742.9912850435794, 1757.0, 1773.6364045370667, 54529.1820622691, 58158.0, 58313.70843145757, 58665.0, 58736.706316468146, 9438.764030899283, 9641.710748677551, 9697.9030209697, 9723.222142228604, 9783.266255030901, 12502.74994500105, 12503.874961250453, 12504.562340318045, 12515.311657858723, 12518.624441266777, 7004.509684322151, 7147.0, 7192.964035179791, 7253.057102576631, 7300.3064708853635, 7026.016357709925, 7041.5422997505, 7054.894176587355, 7060.470464715064, 7090.184628767654, 4137.110521237948, 4145.502539695196, 4147.730397524151, 4163.084121493267, 4164.333706606928, 1596.2976290432173, 1610.1707620575412, 1611.4521062838692, 1711.8972861628342, 1740.3299729604194, 847.3517758914436, 893.78102364921, 897.9730608081768, 930.9627614895328, 936.9390989585792, 15746.212689365486, 16044.0, 16167.35330586787, 16245.431409900597, 16389.262483188286, 781.86708259596, 814.6782021101632, 862.3532350736947, 886.5788750343664, 922.5156792683895, 7295.379892709179, 9019.143181397889, 9130.81738365229, 9181.449113053104, 9380.48407337603, 306116.0, 309533.0, 310391.0, 384444.0, 395077.0, 41527.169456610696, 56918.0, 61278.693606531684, 63321.68339158367, 63842.68078659577, 1080.254624309224, 1080.719013056611, 1125.5497800879714, 1149.7298134938283, 1150.1259043127284, 14450.132992020512, 14478.493252736103, 14521.491747788778, 14713.55859324222, 14956.0, 32770.0, 32892.671073289916, 33237.16907077342, 33930.49104263487, 34458.655413445544, 40034.599654004254, 40181.397279040255, 40475.1904961905, 41410.58589414067, 42838.5716142847, 7955.164707705608, 8183.181681831769, 8259.256666900008, 8280.378971577276, 8284.420090593601, 9564.61741530331, 10839.620613278497, 14736.336864840894, 14770.63073423173, 14940.701185976437, 12823.42294596764, 12853.42159602839, 12904.354782261038, 13241.933790330986, 13525.256110913993, 13153.342332883509, 13190.80213796813, 13210.735785284049, 13623.0, 13799.0, 12848.293343866177, 12893.548725794552, 13085.869141308465, 13151.21092734439, 13415.798763018758, 8736.733173689652, 8855.424397414148, 8987.505687187268, 9070.546472676471, 9085.227755640844, 12842.101052926579, 12945.741085178432, 13004.0, 13179.60461186166, 13280.269585172908], "unit": "ms", "name": "jquery.html", "value": 7968.170656649575}, {"lowerIsBetter": false, "replicates": [1460.861218184272, 1639.19679357115, 1673.4393978017363, 1709.4700642800724, 1716.2019660857698, 6291.213598300213, 6739.393454589086, 6830.931690683092, 6844.82887927802, 6856.211535673393, 6939.0979172707475, 7069.151701795791, 7070.363667269958, 7072.787816365517, 7074.9292507074915, 2098.475381154711, 2121.1833444124045, 2124.808767210951, 2127.7446706395253, 2129.5208578069887, 2116.3650904728597, 2131.712218850451, 2159.373781603333, 2164.101897712447, 2164.7077644518026, 2086.540960988588, 2092.048118106258, 2106.494441334084, 2118.766935637079, 2122.4587730128874, 2095.738032745907, 2096.9056392462376, 2100.771048936374, 2103.3900168950986, 2105.5578328551023, 2062.092679221138, 2111.345482900306, 2112.271266413085, 2112.809847113752, 2113.9471513212293, 6068.393160683896, 6214.067889816519, 6981.965090174567, 7008.754693585751, 7011.859762804766, 4127.236461254647, 4188.120494696117, 4203.390508376299, 4223.324268117117, 4253.808578613939, 2935.6183696119583, 2988.9103326900226, 3041.133277016062, 3050.2221933407063, 3057.464943634855, 904.7918978634885, 909.4815954905706, 941.5857022909967, 946.5078159357096, 965.560669895203, 9510.239180865514, 9573.904260957372, 9586.712398628053, 9608.231341492663, 9617.615295388247, 4098.139390727981, 4098.159877225162, 4140.130572579732, 4158.771267580312, 4171.541130475645, 8317.500949943023, 8340.791480212922, 8347.749567512985, 8363.832723345498, 8368.539730314891, 4323.286657701507, 4327.199468098412, 4335.956640433556, 4337.9783101084295, 4342.392065110887, 3884.2425726982965, 3909.4135879618284, 3911.6870650347905, 3912.3544615138185, 3916.5691773904846, 3257.885973990899, 3289.4243507386113, 3337.098983274502, 3338.88313909012, 3340.766146369731, 1118.6588090632436, 1121.9439028048564, 1127.0307535519446, 1129.7119234595125, 1139.5783560082823, 1022.4070039377143, 1082.1667316166665, 1170.5122105930786, 1214.5627574073326, 1214.842070530843, 6663.36698013688, 6726.0, 6728.3271672832325, 6729.966350168218, 6742.932570674328, 447.85220877110476, 453.0350353746499, 457.6498978281617, 460.31413194340195, 461.64222727386544, 4926.704397736147, 5119.283300337956, 5166.276721259027, 5168.095583272912, 5170.896582068338, 7750.418718596128, 8162.102168761431, 8290.46112002718, 8329.08380078191, 8365.916340836635, 8253.33973282148, 8273.875891861628, 8322.875156872651, 8328.583570821556, 8331.8333633327, 6419.16550848396, 6509.837254068686, 6534.313897040837, 6546.116274303004, 6597.505187110986, 6550.443212327005, 6608.768693095719, 6630.867382652224, 6645.235797883305, 6656.900146497709, 7215.386692131227, 7456.254374562717, 7494.17564067963, 7494.700211991677, 7526.247375262649, 7509.586972716553, 7661.310482056641, 7675.194104618935, 7789.532628042336, 7793.376529877482, 6171.444569988723, 6179.938200618116, 6243.219597550306, 6245.531585131042, 6261.279952805486, 6752.561083529555, 6780.966095169492, 6803.0, 6826.419754320938, 6831.2144103428745, 6374.58565193261, 6414.03789431589, 6444.947749036295, 6476.190476190476, 6516.934830651822, 6181.196444462371, 6193.938060619516, 6242.656653883898, 6296.150019747275, 6320.0, 6366.363363663597, 6403.29563747978, 6432.710528026344, 6432.807015789534, 6444.806655800334, 5771.076627739541, 5880.206172166874, 5916.970415147896, 5922.851928701817, 5925.08161234992, 5848.239728835224, 6012.849678758066, 6044.032954727222, 6046.818595442144, 6051.425114614105], "unit": "ms", "name": "mootools.html", "value": 4031.3204907516947}, {"lowerIsBetter": false, "replicates": [316.72444972873586, 317.2813577247534, 321.6799284711712, 325.55886773422014, 329.4432409228404, 5386.192071189319, 5434.0, 5450.100733378993, 5452.400235974044, 5483.7532311046, 5126.974365128179, 5214.817481388152, 5281.023010743015, 5365.597580181482, 5391.838244852651, 4212.241796476633, 4212.578742125778, 4215.3887686285625, 4230.365445183224, 4237.449131612886, 3985.2627263956115, 4225.830966761326, 4236.639885609711, 4240.342746874239, 4242.406063151161, 4063.6952228582827, 4067.3085575452246, 4192.748435093904, 4199.286121359376, 4206.747595144286, 4178.582141785812, 4184.686148538872, 4192.3711443283755, 4203.432536607549, 4205.390218418343, 3718.7025037996896, 3719.1817800083936, 3722.0, 3723.7393382463238, 3723.832427540767, 5147.0, 5187.0922588546855, 5192.818251361185, 5198.844034678966, 5200.583953283728, 4647.9535204647855, 4777.140114779339, 4779.378680771512, 4783.306420568998, 4784.42586889572, 5659.320881494205, 5660.066089095294, 5661.66030038199, 5662.6036177467595, 5674.858128546778, 3943.9014024649327, 3948.0524674078015, 3953.327934251184, 3955.0310174007263, 3958.5843486433796, 5891.764329426818, 5957.940420595825, 5963.314218864799, 5966.075258334965, 5976.8505787355225, 3938.3895496198134, 4048.08917993455, 4054.655354294859, 4074.2869997750277, 4075.3886916962692, 4126.896827579274, 4133.379993001074, 4133.772642504691, 4134.37984302351, 4140.0270936329935, 4753.643476739258, 4757.26262429324, 4758.500357462486, 4761.738104404291, 4763.45220299663, 5670.0, 5670.688112153871, 5690.459406356389, 5698.088305871123, 5698.430156984351, 1418.2979425184394, 1439.165284135199, 1461.8245810502808, 1464.05568408376, 1466.1423067505416, 963.7735132243918, 981.8919918808925, 986.063239922077, 986.2455221755363, 991.6479649724388, 4761.595264402565, 4819.759012049383, 4823.662343635911, 4824.324594556765, 4831.033793241366, 5655.321361436571, 5678.716064196774, 5687.971560142173, 5688.544916406678, 5701.429857014348, 4754.120487709784, 4798.616110711135, 4807.399075115611, 4883.804647814119, 4942.481039490802, 3131.968680313213, 3156.6685498022835, 3167.160702413862, 3169.5721077654684, 3171.365726854592, 5549.5837812164245, 5586.860328491739, 5589.245451864028, 5590.748416321276, 5594.888102237933, 5879.735411906475, 5993.160957465958, 5997.850053748604, 6032.336442991267, 6056.06131049688, 5552.194931734876, 5575.637583557137, 5576.9163462548095, 5594.692291923902, 5631.971840140773, 5848.590598658053, 5884.293884733859, 5888.499477544286, 5896.380880007538, 5902.380250073852, 5771.336296325976, 5788.594798364243, 5809.738561764815, 5813.418658134322, 5813.476787089182, 5474.863128421821, 5603.439656034364, 5610.13042978339, 5614.887702246014, 5621.072523033818, 4038.7172897896867, 4063.837446502107, 4070.2876996526084, 4078.7348822326453, 4081.4285999959434, 5893.145493903446, 5893.5874488785375, 5895.852603684943, 5905.5570832186895, 5905.616134951214, 5535.833924982257, 5608.551315894801, 5646.661200327993, 5660.462256085665, 5669.177969194526, 5630.18362337467, 5645.237892884408, 5647.632903861236, 5650.745716442852, 5661.745221464962, 5535.391106978149, 5536.141898005815, 5536.944630553804, 5539.14143307788, 5543.279373681396, 5706.343770466449, 5706.743196556248, 5707.942920570741, 5712.143178523254, 5714.399988001366, 5417.485338892799, 5563.554915606661, 5565.1374037024325, 5567.387587365468, 5569.10894256917], "unit": "ms", "name": "prototype.html", "value": 4257.023282369767}, {"lowerIsBetter": false, "replicates": [685.516710718943, 696.5855316086927, 707.7558242406371, 737.365865355794, 757.5454727163702, 10888.0, 11342.262752921064, 11348.943255283733, 11351.148663850214, 11372.658820235385, 12043.939780301109, 12168.81746773799, 12181.878181218186, 12182.208156469824, 12188.207766495194, 613.6471528870917, 621.6518749500271, 782.0302824497638, 792.251322500239, 807.6728924785442, 802.9317508011796, 803.0884945586743, 810.5906517208801, 811.1077814404167, 811.1563973467587, 840.8402403543307, 851.4167795060404, 851.8296340731847, 852.7313896122732, 852.7654894903889, 651.8663673946829, 660.8314879705669, 823.1562648285495, 842.8525008123615, 845.2519520224585, 619.9597026193283, 625.4058644287944, 625.6996641612006, 626.0421555020826, 626.0484064222402, 12233.204841685261, 12403.317817520032, 12414.193077450027, 12418.56535021279, 12421.37893105341, 11273.943630281878, 11995.880041199563, 12169.634910952685, 12250.75498490034, 12423.627291181188, 9690.176335011533, 9693.321467497279, 9698.51507424633, 9762.267829912786, 9778.804423911553, 3203.775735698502, 3215.8552865121123, 3228.4350238708134, 3261.233610101648, 3338.3490219407227, 39517.80241098805, 40743.59256407428, 43436.56563434388, 43827.78086109581, 43959.0, 9169.083091690778, 9196.862047069299, 9275.953620231856, 9345.252379809735, 9414.67048653294, 16611.750823737653, 16613.41853035138, 16619.252133653772, 16631.584210394838, 16634.50096497107, 5857.970710146422, 5872.471477567039, 5902.439268269508, 6034.306054803666, 6132.816015519542, 3543.0, 3570.107473131717, 3571.053670777246, 3583.6774690278, 3601.081724160322, 2810.9297267568472, 2835.149455163484, 2842.0905310300504, 2847.700991395915, 2852.3867368515453, 498.180493088874, 508.9109306084981, 510.2168171856183, 515.6519349439188, 530.1411713024847, 509.41671785805784, 558.2463674040094, 561.6012631031931, 584.197749941427, 586.4077281945181, 7326.780196594111, 7372.741954031583, 7535.397168226528, 7628.313451789366, 7649.847003060019, 490.78160218702334, 567.4240645744572, 571.9370869204384, 592.6947621974687, 595.5771402304326, 3501.8970270768505, 3536.4695295705337, 3621.3119507294055, 3674.356987527172, 3765.246950609889, 48516.0, 49411.0, 49897.25154122691, 50441.24338134931, 51389.486105139214, 44372.0, 45291.54708452939, 45889.0, 46017.30974035392, 46105.0, 40558.1888362237, 41048.331858429105, 41449.585504144576, 41567.1686566267, 41791.99999999939, 7211.639418028973, 7245.275472452712, 7289.781306560812, 7336.486445948733, 7532.359749421361, 31137.065888023397, 31698.207544811565, 31964.52053219157, 31965.0, 32327.838360808044, 36997.0, 38812.61187388203, 39914.0, 40030.0, 40215.396769049075, 8768.25469835071, 8783.868241976248, 8962.283017358464, 9124.589393477178, 9159.862602061108, 9869.259805514472, 9910.542272136589, 9989.635455668064, 10464.0, 10572.259941803999, 9669.468179250209, 9726.319157658896, 9782.315237933277, 9926.404415735078, 10125.291229613857, 9447.952760236154, 9448.149666529771, 9448.669296574586, 9594.568244428878, 9663.0, 8609.87085193735, 8701.129887011248, 8815.338849586178, 9331.346805723535, 10476.214283928888, 8774.166454186841, 8917.375783695079, 9015.233705135137, 9110.817783644157, 9146.405483643543, 8779.604917778588, 8902.243309318781, 8940.68707595231, 9023.548822558978, 9101.863472047793], "unit": "ms", "name": "yui.html", "value": 5629.0718268905375}], "name": "dromaeo_css", "value": 6291.643474224088}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 113.0, 106.0, 112.0, 111.0, 111.0, 100.0, 111.0, 116.0, 112.0], "unit": "ms", "name": "ai-astar", "value": 110.1}, {"lowerIsBetter": true, "replicates": [163.0, 130.0, 162.0, 173.0, 130.0, 167.0, 159.0, 132.0, 168.0, 129.0], "unit": "ms", "name": "audio-beat-detection", "value": 151.3}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 161.0, 161.0, 159.0, 160.0, 162.0, 161.0, 162.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.7}, {"lowerIsBetter": true, "replicates": [73.0, 72.0, 88.0, 93.0, 73.0, 72.0, 72.0, 112.0, 91.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 81.9}, {"lowerIsBetter": true, "replicates": [91.0, 92.0, 127.0, 90.0, 92.0, 92.0, 123.0, 91.0, 92.0, 129.0], "unit": "ms", "name": "audio-oscillator", "value": 101.9}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 110.0, 109.0, 110.0, 109.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.6}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 146.0, 147.0, 148.0, 147.0, 146.0, 147.0, 146.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.8}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.0}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 71.0, 73.0, 72.0, 70.0, 72.0, 80.0, 70.0, 69.0], "unit": "ms", "name": "json-parse-financial", "value": 71.9}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 68.0, 69.0, 69.0, 68.0, 68.0, 68.0, 69.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.5}, {"lowerIsBetter": true, "replicates": [83.0, 83.0, 83.0, 83.0, 84.0, 82.0, 82.0, 83.0, 85.0, 83.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 83.1}, {"lowerIsBetter": true, "replicates": [119.0, 121.0, 120.0, 134.0, 126.0, 125.0, 125.0, 120.0, 123.0, 123.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 123.6}, {"lowerIsBetter": true, "replicates": [138.0, 151.0, 152.0, 157.0, 137.0, 146.0, 147.0, 149.0, 146.0, 138.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 146.1}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 70.0, 68.0, 68.0, 69.0, 69.0, 70.0, 67.0, 69.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 68.7}], "name": "kraken", "value": 1519.1999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.1402306535183], "unit": "score", "name": "Richards", "value": 211.21186599999996}, {"lowerIsBetter": false, "replicates": [192.01228878648234], "unit": "score", "name": "DeltaBlue", "value": 344.3425439999999}, {"lowerIsBetter": false, "replicates": [279.72027972027973], "unit": "score", "name": "Encrypt", "value": 951.5970750000004}, {"lowerIsBetter": false, "replicates": [5035.175879396985], "unit": "score", "name": "Decrypt", "value": 52.864290419161705}, {"lowerIsBetter": false, "replicates": [1976.2845849802372], "unit": "score", "name": "RayTrace", "value": 374.434434}, {"lowerIsBetter": false, "replicates": [786.7820613690008], "unit": "score", "name": "Earley", "value": 847.0744729999998}, {"lowerIsBetter": false, "replicates": [12000.0], "unit": "score", "name": "Boyer", "value": 55.53858333333334}, {"lowerIsBetter": false, "replicates": [26205.128205128207], "unit": "score", "name": "RegExp", "value": 34.76361545988257}, {"lowerIsBetter": false, "replicates": [629.3266205160478], "unit": "score", "name": "Splay", "value": 129.48919900000004}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 19800.92563554692}]} 22:39:17 INFO - RETURN: dromaeo_css: 6291.64 22:39:17 INFO - RETURN: kraken: 1519.20 22:39:17 INFO - RETURN: v8_7: 19800.93 22:39:17 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,203,35]]", "result": "19800.93"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,203,35]]", "result": "1519.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,203,35]]", "result": "6291.64"}}} 22:39:17 INFO - Return code: 0 22:39:17 INFO - # TBPL SUCCESS # 22:39:17 INFO - Running post-action listener: _resource_record_post_action 22:39:17 INFO - Running post-run listener: _resource_record_post_run 22:39:18 INFO - Total resource usage - Wall time: 1233s; CPU: 13.0%; Read bytes: 4096; Write bytes: 509222912; Read time: 8; Write time: 465452 22:39:18 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 236605440; Read time: 0; Write time: 332228 22:39:18 INFO - run-tests - Wall time: 1218s; CPU: 13.0%; Read bytes: 0; Write bytes: 272617472; Read time: 0; Write time: 133224 22:39:18 INFO - Running post-run listener: _upload_blobber_files 22:39:18 INFO - Blob upload gear active. 22:39:18 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 22:39:18 INFO - Copying logs to upload dir... 22:39:18 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1270.476149 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2015-11-19 22:39:17.712553) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 22:39:17.716221) ========= 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=0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010072 build_url: 'https://queue.taskcluster.net/v1/task/vrTq7hgORQeTouJ_7Bz3uA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 22:39:17.768691) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 22:39:17.769085) ========= 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=0c96bf5caf0e090009e1a122000001b2-1447990018.184826-642652082 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004515 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 22:39:17.819489) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-19 22:39:17.819917) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-19 22:39:17.820256) ========= ========= Total master_lag: 0.23 =========