builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-044 starttime: 1448417383.23 results: success (0) buildid: 20151124165331 builduid: 148eb45da5cc4726a4c2d4664a1eb3e4 revision: 099f695d31326c39595264c34988a0f4b7cbc698 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:43.235413) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:43.235799) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:43.236071) ========= 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=0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006497 basedir: '/builds/slave/test' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:43.274883) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:43.278602) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:43.337536) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:43.337816) ========= 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=0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004711 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:43.410854) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:43.411107) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:43.414463) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:43.414742) ========= 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=0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-24 18:09:43-- 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% 96.6M=0s 2015-11-24 18:09:43 (96.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.174265 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:43.608252) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:43.611567) ========= 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=0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013814 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:43.660686) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:43.661005) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 099f695d31326c39595264c34988a0f4b7cbc698 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 099f695d31326c39595264c34988a0f4b7cbc698 --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=0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-24 18:09:43,839 truncating revision to first 12 chars 2015-11-24 18:09:43,839 Setting DEBUG logging. 2015-11-24 18:09:43,839 attempt 1/10 2015-11-24 18:09:43,840 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/099f695d3132?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-24 18:09:44,084 unpacking tar archive at: mozilla-central-099f695d3132/testing/mozharness/ program finished with exit code 0 elapsedTime=0.515736 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:44.202594) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:44.202882) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:44.221129) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:44.221446) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 18:09:44.221788) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2015-11-24 18:09:44.222055) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:09:44 INFO - MultiFileLogger online at 20151124 18:09:44 in /builds/slave/test 18:09:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:09:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:09:44 INFO - {'append_to_log': False, 18:09:44 INFO - 'base_work_dir': '/builds/slave/test', 18:09:44 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:09:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:09:44 INFO - 'branch': 'Firefox-Non-PGO', 18:09:44 INFO - 'buildbot_json_path': 'buildprops.json', 18:09:44 INFO - 'config_files': ('talos/linux_config.py',), 18:09:44 INFO - 'default_actions': ('clobber', 18:09:44 INFO - 'read-buildbot-config', 18:09:44 INFO - 'download-and-extract', 18:09:44 INFO - 'populate-webroot', 18:09:44 INFO - 'create-virtualenv', 18:09:44 INFO - 'install', 18:09:44 INFO - 'run-tests'), 18:09:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:09:44 INFO - 'download_minidump_stackwalk': True, 18:09:44 INFO - 'download_symbols': 'ondemand', 18:09:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:09:44 INFO - 'tooltool.py': '/tools/tooltool.py', 18:09:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:09:44 INFO - '/tools/misc-python/virtualenv.py')}, 18:09:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:09:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:09:44 INFO - 'installer_path': 'installer.exe', 18:09:44 INFO - 'log_level': 'info', 18:09:44 INFO - 'log_name': 'talos', 18:09:44 INFO - 'log_to_console': True, 18:09:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:09:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:09:44 INFO - 'opt_config_files': (), 18:09:44 INFO - 'pip_index': False, 18:09:44 INFO - 'sps_profile': False, 18:09:44 INFO - 'sps_profile_interval': 0, 18:09:44 INFO - 'suite': 'dromaeojs', 18:09:44 INFO - 'system_bits': '64', 18:09:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:09:44 INFO - 'title': 'talos-linux64-ix-044', 18:09:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:09:44 INFO - 'use_talos_json': True, 18:09:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:09:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:09:44 INFO - 'work_dir': 'build'} 18:09:44 INFO - ##### 18:09:44 INFO - ##### Running clobber step. 18:09:44 INFO - ##### 18:09:44 INFO - Running pre-action listener: _resource_record_pre_action 18:09:44 INFO - Running main action method: clobber 18:09:44 INFO - rmtree: /builds/slave/test/build 18:09:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:09:45 INFO - Running post-action listener: _resource_record_post_action 18:09:45 INFO - ##### 18:09:45 INFO - ##### Running read-buildbot-config step. 18:09:45 INFO - ##### 18:09:45 INFO - Running pre-action listener: _resource_record_pre_action 18:09:45 INFO - Running main action method: read_buildbot_config 18:09:45 INFO - Using buildbot properties: 18:09:45 INFO - { 18:09:45 INFO - "properties": { 18:09:45 INFO - "buildnumber": 331, 18:09:45 INFO - "product": "firefox", 18:09:45 INFO - "script_repo_revision": "production", 18:09:45 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:09:45 INFO - "repository": "", 18:09:45 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 18:09:45 INFO - "buildid": "20151124165331", 18:09:45 INFO - "slavename": "talos-linux64-ix-044", 18:09:45 INFO - "pgo_build": "False", 18:09:45 INFO - "basedir": "/builds/slave/test", 18:09:45 INFO - "project": "", 18:09:45 INFO - "platform": "ubuntu64_hw", 18:09:45 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:09:45 INFO - "slavebuilddir": "test", 18:09:45 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 18:09:45 INFO - "branch": "mozilla-central", 18:09:45 INFO - "repo_path": "mozilla-central", 18:09:45 INFO - "stage_platform": "linux64", 18:09:45 INFO - "builduid": "148eb45da5cc4726a4c2d4664a1eb3e4", 18:09:45 INFO - "revision": "099f695d31326c39595264c34988a0f4b7cbc698" 18:09:45 INFO - }, 18:09:45 INFO - "sourcestamp": { 18:09:45 INFO - "repository": "", 18:09:45 INFO - "hasPatch": false, 18:09:45 INFO - "project": "", 18:09:45 INFO - "branch": "mozilla-central-linux64-talos", 18:09:45 INFO - "changes": [ 18:09:45 INFO - { 18:09:45 INFO - "category": null, 18:09:45 INFO - "files": [ 18:09:45 INFO - { 18:09:45 INFO - "url": null, 18:09:45 INFO - "name": "https://queue.taskcluster.net/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 18:09:45 INFO - } 18:09:45 INFO - ], 18:09:45 INFO - "repository": "", 18:09:45 INFO - "rev": "099f695d31326c39595264c34988a0f4b7cbc698", 18:09:45 INFO - "who": "kwierso@gmail.com", 18:09:45 INFO - "when": 1448417331, 18:09:45 INFO - "number": 6703031, 18:09:45 INFO - "comments": "Merge b2ginbound to central, a=merge", 18:09:45 INFO - "project": "", 18:09:45 INFO - "at": "Tue 24 Nov 2015 18:08:51", 18:09:45 INFO - "branch": "mozilla-central-linux64-talos", 18:09:45 INFO - "revlink": "", 18:09:45 INFO - "properties": [ 18:09:45 INFO - [ 18:09:45 INFO - "buildid", 18:09:45 INFO - "20151124165331", 18:09:45 INFO - "Change" 18:09:45 INFO - ], 18:09:45 INFO - [ 18:09:45 INFO - "builduid", 18:09:45 INFO - "148eb45da5cc4726a4c2d4664a1eb3e4", 18:09:45 INFO - "Change" 18:09:45 INFO - ], 18:09:45 INFO - [ 18:09:45 INFO - "pgo_build", 18:09:45 INFO - "False", 18:09:45 INFO - "Change" 18:09:45 INFO - ] 18:09:45 INFO - ], 18:09:45 INFO - "revision": "099f695d31326c39595264c34988a0f4b7cbc698" 18:09:45 INFO - } 18:09:45 INFO - ], 18:09:45 INFO - "revision": "099f695d31326c39595264c34988a0f4b7cbc698" 18:09:45 INFO - } 18:09:45 INFO - } 18:09:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 18:09:45 INFO - Running post-action listener: _resource_record_post_action 18:09:45 INFO - ##### 18:09:45 INFO - ##### Running download-and-extract step. 18:09:45 INFO - ##### 18:09:45 INFO - Running pre-action listener: _resource_record_pre_action 18:09:45 INFO - Running main action method: download_and_extract 18:09:45 INFO - mkdir: /builds/slave/test/build/tests 18:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:09:45 INFO - https://queue.taskcluster.net/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 18:09:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/test_packages.json 18:09:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/test_packages.json 18:09:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 18:09:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 18:09:51 INFO - Downloaded 1302 bytes. 18:09:51 INFO - Reading from file /builds/slave/test/build/test_packages.json 18:09:51 INFO - Using the following test package requirements: 18:09:51 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:09:51 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:09:51 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:09:51 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:09:51 INFO - u'jsshell-linux-x86_64.zip'], 18:09:51 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:09:51 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:09:51 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:09:51 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:09:51 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:09:51 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:09:51 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:09:51 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:09:51 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:09:51 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:09:51 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:09:51 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:09:51 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:09:51 INFO - https://queue.taskcluster.net/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:09:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:09:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:09:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/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 18:09:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/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 18:09:52 INFO - Downloaded 21345501 bytes. 18:09:52 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 18:09:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:09:52 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 18:09:53 INFO - Return code: 0 18:09:53 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 18:09:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:09:53 INFO - https://queue.taskcluster.net/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:09:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:09:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:09:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/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 18:09:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/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 18:10:02 INFO - Downloaded 21345501 bytes. 18:10:02 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 18:10:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:10:02 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 18:10:03 INFO - Return code: 0 18:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:03 INFO - https://queue.taskcluster.net/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:10:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:10:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:10:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/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 18:10:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/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 18:10:04 INFO - Downloaded 11131717 bytes. 18:10:04 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 18:10:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:10:04 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 18:10:04 INFO - Return code: 0 18:10:04 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')]} 18:10:04 INFO - https://queue.taskcluster.net/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:10:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:10:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:10:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/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 18:10:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/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 18:10:05 INFO - Downloaded 56847617 bytes. 18:10:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:10:05 INFO - mkdir: /builds/slave/test/properties 18:10:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:10:05 INFO - Writing to file /builds/slave/test/properties/build_url 18:10:05 INFO - Contents: 18:10:05 INFO - build_url:https://queue.taskcluster.net/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:10:05 INFO - Running post-action listener: _resource_record_post_action 18:10:05 INFO - Running post-action listener: set_extra_try_arguments 18:10:05 INFO - ##### 18:10:05 INFO - ##### Running populate-webroot step. 18:10:05 INFO - ##### 18:10:05 INFO - Running pre-action listener: _resource_record_pre_action 18:10:05 INFO - Running main action method: populate_webroot 18:10:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:10:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:10:05 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 18:10:05 INFO - '../fennec_ids.txt'], 18:10:05 INFO - 'tests': ['tcheck2']}, 18:10:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:10:05 INFO - '--tppagecycles', 18:10:05 INFO - '7'], 18:10:05 INFO - 'tests': ['tsvgm']}}, 18:10:05 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:10:05 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:10:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:10:05 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:10:05 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:10:05 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:10:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:10:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:10:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:10:05 INFO - 'talos_options': ['--e10s'], 18:10:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:10:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:10:05 INFO - 'tests': ['damp', 'tps']}, 18:10:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:10:05 INFO - 'talos_options': ['--e10s'], 18:10:05 INFO - 'tests': ['damp', 'tps']}, 18:10:05 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:10:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:10:05 INFO - 'other': {'tests': ['a11yr', 18:10:05 INFO - 'ts_paint', 18:10:05 INFO - 'tpaint', 18:10:05 INFO - 'sessionrestore', 18:10:05 INFO - 'sessionrestore_no_auto_restore']}, 18:10:05 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:10:05 INFO - 'tests': ['a11yr', 18:10:05 INFO - 'ts_paint', 18:10:05 INFO - 'tpaint', 18:10:05 INFO - 'sessionrestore', 18:10:05 INFO - 'sessionrestore_no_auto_restore']}, 18:10:05 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:10:05 INFO - 'tests': ['a11yr', 18:10:05 INFO - 'ts_paint', 18:10:05 INFO - 'tpaint', 18:10:05 INFO - 'sessionrestore', 18:10:05 INFO - 'sessionrestore_no_auto_restore']}, 18:10:05 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:10:05 INFO - 'tests': ['a11yr', 18:10:05 INFO - 'ts_paint', 18:10:05 INFO - 'tpaint', 18:10:05 INFO - 'sessionrestore', 18:10:05 INFO - 'sessionrestore_no_auto_restore']}, 18:10:05 INFO - 'other_l64': {'tests': ['a11yr', 18:10:05 INFO - 'ts_paint', 18:10:05 INFO - 'tpaint', 18:10:05 INFO - 'sessionrestore', 18:10:05 INFO - 'sessionrestore_no_auto_restore']}, 18:10:05 INFO - 'other_nol64': {'tests': ['a11yr', 18:10:05 INFO - 'ts_paint', 18:10:05 INFO - 'tpaint', 18:10:05 INFO - 'sessionrestore', 18:10:05 INFO - 'sessionrestore_no_auto_restore']}, 18:10:05 INFO - 'svgr': {'tests': ['tsvgx', 18:10:05 INFO - 'tsvgr_opacity', 18:10:05 INFO - 'tart', 18:10:05 INFO - 'tscrollx', 18:10:05 INFO - 'cart']}, 18:10:05 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:10:05 INFO - 'tests': ['tsvgx', 18:10:05 INFO - 'tsvgr_opacity', 18:10:05 INFO - 'tart', 18:10:05 INFO - 'tscrollx', 18:10:05 INFO - 'cart']}, 18:10:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:10:05 INFO - 'tests': ['tp5o']}, 18:10:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:10:05 INFO - 'talos_options': ['--e10s'], 18:10:05 INFO - 'tests': ['tp5o']}, 18:10:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:10:05 INFO - 'talos_options': ['--xperf_path', 18:10:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:10:05 INFO - 'tests': ['tp5n']}, 18:10:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:10:05 INFO - 'talos_options': ['--e10s', 18:10:05 INFO - '--xperf_path', 18:10:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:10:05 INFO - 'tests': ['tp5n']}}, 18:10:05 INFO - 'talos.zip': {'path': '', 18:10:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:10:05 INFO - Running post-action listener: _resource_record_post_action 18:10:05 INFO - ##### 18:10:05 INFO - ##### Running create-virtualenv step. 18:10:05 INFO - ##### 18:10:05 INFO - Running pre-action listener: _resource_record_pre_action 18:10:05 INFO - Running main action method: create_virtualenv 18:10:05 INFO - Creating virtualenv /builds/slave/test/build/venv 18:10:05 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 18:10:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:10:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:10:06 INFO - Using real prefix '/usr' 18:10:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:10:06 INFO - Installing distribute.............................................................................................................................................................................................done. 18:10:08 INFO - Installing pip.................done. 18:10:08 INFO - Return code: 0 18:10:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:10: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')]} 18:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10: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')]} 18:10:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a21030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c52210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce4220>, '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 0x1c502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c50740>, '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': '0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217', '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 18:10:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:10:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:10:08 INFO - Using env: {'DISPLAY': ':0', 18:10:08 INFO - 'HOME': '/home/cltbld', 18:10:08 INFO - 'LANG': 'en_US.UTF-8', 18:10:08 INFO - 'LANGUAGE': 'en_US:en', 18:10:08 INFO - 'LOGNAME': 'cltbld', 18:10:08 INFO - 'MAIL': '/var/mail/cltbld', 18:10:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:08 INFO - 'MOZ_NO_REMOTE': '1', 18:10:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:08 INFO - 'NO_EM_RESTART': '1', 18:10:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:08 INFO - 'PWD': '/builds/slave/test', 18:10:08 INFO - 'SHELL': '/bin/bash', 18:10:08 INFO - 'SHLVL': '1', 18:10:08 INFO - 'TERM': 'linux', 18:10:08 INFO - 'TMOUT': '86400', 18:10:08 INFO - 'USER': 'cltbld', 18:10:08 INFO - 'XDG_SESSION_COOKIE': '0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217', 18:10:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:08 INFO - '_': '/tools/buildbot/bin/python'} 18:10:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:08 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 18:10:08 INFO - Cleaning up... 18:10:08 INFO - Return code: 0 18:10:08 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:10: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')]} 18:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10: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')]} 18:10:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1a21030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c52210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce4220>, '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 0x1c502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c50740>, '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': '0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217', '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 18:10:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 18:10:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 18:10:08 INFO - Using env: {'DISPLAY': ':0', 18:10:08 INFO - 'HOME': '/home/cltbld', 18:10:08 INFO - 'LANG': 'en_US.UTF-8', 18:10:08 INFO - 'LANGUAGE': 'en_US:en', 18:10:08 INFO - 'LOGNAME': 'cltbld', 18:10:08 INFO - 'MAIL': '/var/mail/cltbld', 18:10:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:08 INFO - 'MOZ_NO_REMOTE': '1', 18:10:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:08 INFO - 'NO_EM_RESTART': '1', 18:10:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:08 INFO - 'PWD': '/builds/slave/test', 18:10:08 INFO - 'SHELL': '/bin/bash', 18:10:08 INFO - 'SHLVL': '1', 18:10:08 INFO - 'TERM': 'linux', 18:10:08 INFO - 'TMOUT': '86400', 18:10:08 INFO - 'USER': 'cltbld', 18:10:08 INFO - 'XDG_SESSION_COOKIE': '0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217', 18:10:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:08 INFO - '_': '/tools/buildbot/bin/python'} 18:10:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:08 INFO - Downloading/unpacking psutil>=0.7.1 18:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:10:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:10:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:10:10 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 18:10:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:10:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:10:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:10:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:10:10 INFO - Installing collected packages: psutil 18:10:10 INFO - Running setup.py install for psutil 18:10:10 INFO - building 'psutil._psutil_linux' extension 18:10:10 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 18:10:10 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 18:10:11 INFO - building 'psutil._psutil_posix' extension 18:10:11 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 18:10:11 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 18:10:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:10:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:10:11 INFO - Successfully installed psutil 18:10:11 INFO - Cleaning up... 18:10:11 INFO - Return code: 0 18:10:11 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:10:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:11 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 0x1a21030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c52210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce4220>, '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 0x1c502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c50740>, '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': '0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217', '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 18:10:11 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 18:10:11 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 18:10:11 INFO - Using env: {'DISPLAY': ':0', 18:10:11 INFO - 'HOME': '/home/cltbld', 18:10:11 INFO - 'LANG': 'en_US.UTF-8', 18:10:11 INFO - 'LANGUAGE': 'en_US:en', 18:10:11 INFO - 'LOGNAME': 'cltbld', 18:10:11 INFO - 'MAIL': '/var/mail/cltbld', 18:10:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:11 INFO - 'MOZ_NO_REMOTE': '1', 18:10:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:11 INFO - 'NO_EM_RESTART': '1', 18:10:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:11 INFO - 'PWD': '/builds/slave/test', 18:10:11 INFO - 'SHELL': '/bin/bash', 18:10:11 INFO - 'SHLVL': '1', 18:10:11 INFO - 'TERM': 'linux', 18:10:11 INFO - 'TMOUT': '86400', 18:10:11 INFO - 'USER': 'cltbld', 18:10:11 INFO - 'XDG_SESSION_COOKIE': '0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217', 18:10:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:11 INFO - '_': '/tools/buildbot/bin/python'} 18:10:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:11 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:10:11 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 18:10:11 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 18:10:11 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 18:10:11 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 18:10:11 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 18:10:11 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 18:10:13 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:10:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:10:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:10:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:10:13 INFO - Installing collected packages: mozsystemmonitor 18:10:13 INFO - Running setup.py install for mozsystemmonitor 18:10:13 INFO - Successfully installed mozsystemmonitor 18:10:13 INFO - Cleaning up... 18:10:13 INFO - Return code: 0 18:10:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:10:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:13 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 0x1a21030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c52210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce4220>, '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 0x1c502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c50740>, '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': '0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217', '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 18:10:13 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 18:10:13 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 18:10:13 INFO - Using env: {'DISPLAY': ':0', 18:10:13 INFO - 'HOME': '/home/cltbld', 18:10:13 INFO - 'LANG': 'en_US.UTF-8', 18:10:13 INFO - 'LANGUAGE': 'en_US:en', 18:10:13 INFO - 'LOGNAME': 'cltbld', 18:10:13 INFO - 'MAIL': '/var/mail/cltbld', 18:10:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:13 INFO - 'MOZ_NO_REMOTE': '1', 18:10:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:13 INFO - 'NO_EM_RESTART': '1', 18:10:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:13 INFO - 'PWD': '/builds/slave/test', 18:10:13 INFO - 'SHELL': '/bin/bash', 18:10:13 INFO - 'SHLVL': '1', 18:10:13 INFO - 'TERM': 'linux', 18:10:13 INFO - 'TMOUT': '86400', 18:10:13 INFO - 'USER': 'cltbld', 18:10:13 INFO - 'XDG_SESSION_COOKIE': '0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217', 18:10:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:13 INFO - '_': '/tools/buildbot/bin/python'} 18:10:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:13 INFO - Downloading/unpacking blobuploader==1.2.4 18:10:13 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 18:10:13 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 18:10:13 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 18:10:13 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 18:10:13 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 18:10:13 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 18:10:15 INFO - Downloading blobuploader-1.2.4.tar.gz 18:10:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:10:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:10:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:10:15 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 18:10:15 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 18:10:15 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 18:10:15 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 18:10:15 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 18:10:15 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 18:10:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:10:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:10:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:10:15 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 18:10:15 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 18:10:15 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 18:10:15 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 18:10:15 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 18:10:15 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 18:10:15 INFO - Downloading docopt-0.6.1.tar.gz 18:10:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:10:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:10:15 INFO - Installing collected packages: blobuploader, requests, docopt 18:10:15 INFO - Running setup.py install for blobuploader 18:10:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:10:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:10:16 INFO - Running setup.py install for requests 18:10:16 INFO - Running setup.py install for docopt 18:10:16 INFO - Successfully installed blobuploader requests docopt 18:10:16 INFO - Cleaning up... 18:10:16 INFO - Return code: 0 18:10:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:10:16 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')]} 18:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:16 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')]} 18:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:16 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 0x1a21030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c52210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce4220>, '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 0x1c502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c50740>, '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': '0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217', '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 18:10:16 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 18:10:16 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 18:10:16 INFO - Using env: {'DISPLAY': ':0', 18:10:16 INFO - 'HOME': '/home/cltbld', 18:10:16 INFO - 'LANG': 'en_US.UTF-8', 18:10:16 INFO - 'LANGUAGE': 'en_US:en', 18:10:16 INFO - 'LOGNAME': 'cltbld', 18:10:16 INFO - 'MAIL': '/var/mail/cltbld', 18:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:16 INFO - 'MOZ_NO_REMOTE': '1', 18:10:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:16 INFO - 'NO_EM_RESTART': '1', 18:10:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:16 INFO - 'PWD': '/builds/slave/test', 18:10:16 INFO - 'SHELL': '/bin/bash', 18:10:16 INFO - 'SHLVL': '1', 18:10:16 INFO - 'TERM': 'linux', 18:10:16 INFO - 'TMOUT': '86400', 18:10:16 INFO - 'USER': 'cltbld', 18:10:16 INFO - 'XDG_SESSION_COOKIE': '0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217', 18:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:16 INFO - '_': '/tools/buildbot/bin/python'} 18:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:10:16 INFO - Running setup.py (path:/tmp/pip-U6udLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:10:16 INFO - Running setup.py (path:/tmp/pip-a0bBkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:10:16 INFO - Running setup.py (path:/tmp/pip-QqFBn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:10:17 INFO - Running setup.py (path:/tmp/pip-LWbWz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:10:17 INFO - Running setup.py (path:/tmp/pip-RDmEF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:10:17 INFO - Running setup.py (path:/tmp/pip-LXUjS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:10:17 INFO - Running setup.py (path:/tmp/pip-hlqWO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:10:17 INFO - Running setup.py (path:/tmp/pip-a5OAzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:10:17 INFO - Running setup.py (path:/tmp/pip-iPBm8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:10:17 INFO - Running setup.py (path:/tmp/pip-buJikT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:10:17 INFO - Running setup.py (path:/tmp/pip-cWtuc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:10:17 INFO - Running setup.py (path:/tmp/pip-ZJmLjc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:10:17 INFO - Running setup.py (path:/tmp/pip-VAgDYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:10:17 INFO - Running setup.py (path:/tmp/pip-UAprer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:10:17 INFO - Running setup.py (path:/tmp/pip-weadR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:10:17 INFO - Running setup.py (path:/tmp/pip-cu3QXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:10:17 INFO - Running setup.py (path:/tmp/pip-tRhKlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:10:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:10:18 INFO - Running setup.py install for manifestparser 18:10:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:10:18 INFO - Running setup.py install for mozcrash 18:10:18 INFO - Running setup.py install for mozdebug 18:10:18 INFO - Running setup.py install for mozdevice 18:10:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:10:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:10:18 INFO - Running setup.py install for mozfile 18:10:18 INFO - Running setup.py install for mozhttpd 18:10:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:10:18 INFO - Running setup.py install for mozinfo 18:10:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:10:18 INFO - Running setup.py install for mozInstall 18:10:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:10:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:10:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:10:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:10:18 INFO - Running setup.py install for mozleak 18:10:18 INFO - Running setup.py install for mozlog 18:10:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:10:19 INFO - Running setup.py install for moznetwork 18:10:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:10:19 INFO - Running setup.py install for mozprocess 18:10:19 INFO - Running setup.py install for mozprofile 18:10:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:10:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:10:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:10:19 INFO - Running setup.py install for mozrunner 18:10:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:10:19 INFO - Running setup.py install for mozscreenshot 18:10:19 INFO - Running setup.py install for moztest 18:10:19 INFO - Running setup.py install for mozversion 18:10:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:10:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:10:19 INFO - Cleaning up... 18:10:19 INFO - Return code: 0 18:10:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:10:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:19 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 0x1a21030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c52210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce4220>, '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 0x1c502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c50740>, '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': '0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217', '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 18:10:19 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 18:10:19 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 18:10:19 INFO - Using env: {'DISPLAY': ':0', 18:10:19 INFO - 'HOME': '/home/cltbld', 18:10:19 INFO - 'LANG': 'en_US.UTF-8', 18:10:19 INFO - 'LANGUAGE': 'en_US:en', 18:10:19 INFO - 'LOGNAME': 'cltbld', 18:10:19 INFO - 'MAIL': '/var/mail/cltbld', 18:10:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:19 INFO - 'MOZ_NO_REMOTE': '1', 18:10:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:19 INFO - 'NO_EM_RESTART': '1', 18:10:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:19 INFO - 'PWD': '/builds/slave/test', 18:10:19 INFO - 'SHELL': '/bin/bash', 18:10:19 INFO - 'SHLVL': '1', 18:10:19 INFO - 'TERM': 'linux', 18:10:19 INFO - 'TMOUT': '86400', 18:10:19 INFO - 'USER': 'cltbld', 18:10:19 INFO - 'XDG_SESSION_COOKIE': '0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217', 18:10:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:19 INFO - '_': '/tools/buildbot/bin/python'} 18:10:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:10:20 INFO - Running setup.py (path:/tmp/pip-d4HHjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:10:20 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)) 18:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:10:20 INFO - Running setup.py (path:/tmp/pip-6XEmKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:10:20 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)) 18:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:10:20 INFO - Running setup.py (path:/tmp/pip-ticMyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:10:20 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)) 18:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:10:20 INFO - Running setup.py (path:/tmp/pip-Sl5qWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:10:20 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)) 18:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:10:20 INFO - Running setup.py (path:/tmp/pip-4qboos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:10:20 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)) 18:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:10:20 INFO - Running setup.py (path:/tmp/pip-MPthxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:10:20 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)) 18:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:10:20 INFO - Running setup.py (path:/tmp/pip-KGAsD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:10:20 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)) 18:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:10:20 INFO - Running setup.py (path:/tmp/pip-JZvAtp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:10:20 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)) 18:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:10:20 INFO - Running setup.py (path:/tmp/pip-3_2zyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:10:20 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)) 18:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:10:20 INFO - Running setup.py (path:/tmp/pip-FOtGWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:10:20 INFO - Running setup.py (path:/tmp/pip-Ay6WPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:10:20 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)) 18:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:10:20 INFO - Running setup.py (path:/tmp/pip-PRrw3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:10:20 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)) 18:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:10:20 INFO - Running setup.py (path:/tmp/pip-w9tUvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:10:20 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)) 18:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:10:20 INFO - Running setup.py (path:/tmp/pip-DFlAsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:10:21 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)) 18:10:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:10:21 INFO - Running setup.py (path:/tmp/pip-UFppJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:10:21 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)) 18:10:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:10:21 INFO - Running setup.py (path:/tmp/pip-GygEz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:10:21 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)) 18:10:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:10:21 INFO - Running setup.py (path:/tmp/pip-_sJwh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:10:21 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)) 18:10:21 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)) 18:10:21 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)) 18:10:21 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)) 18:10:21 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)) 18:10:21 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)) 18:10:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:10:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:10:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:10:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:10:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:10:25 INFO - Downloading blessings-1.5.1.tar.gz 18:10:25 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 18:10:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:10:25 INFO - Installing collected packages: blessings 18:10:25 INFO - Running setup.py install for blessings 18:10:26 INFO - Successfully installed blessings 18:10:26 INFO - Cleaning up... 18:10:26 INFO - Return code: 0 18:10:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:10:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:10:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:10:26 INFO - Reading from file tmpfile_stdout 18:10:26 INFO - Current package versions: 18:10:26 INFO - argparse == 1.2.1 18:10:26 INFO - blessings == 1.5.1 18:10:26 INFO - blobuploader == 1.2.4 18:10:26 INFO - docopt == 0.6.1 18:10:26 INFO - manifestparser == 1.1 18:10:26 INFO - mozInstall == 1.12 18:10:26 INFO - mozcrash == 0.16 18:10:26 INFO - mozdebug == 0.1 18:10:26 INFO - mozdevice == 0.47 18:10:26 INFO - mozfile == 1.2 18:10:26 INFO - mozhttpd == 0.7 18:10:26 INFO - mozinfo == 0.9 18:10:26 INFO - mozleak == 0.1 18:10:26 INFO - mozlog == 3.1 18:10:26 INFO - moznetwork == 0.27 18:10:26 INFO - mozprocess == 0.22 18:10:26 INFO - mozprofile == 0.27 18:10:26 INFO - mozrunner == 6.11 18:10:26 INFO - mozscreenshot == 0.1 18:10:26 INFO - mozsystemmonitor == 0.0 18:10:26 INFO - moztest == 0.7 18:10:26 INFO - mozversion == 1.4 18:10:26 INFO - psutil == 3.1.1 18:10:26 INFO - requests == 1.2.3 18:10:26 INFO - wsgiref == 0.1.2 18:10:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:10:26 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')]} 18:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:26 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')]} 18:10:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:26 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 0x1a21030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c52210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce4220>, '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 0x1c502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c50740>, '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': '0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217', '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 18:10:26 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 18:10:26 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 18:10:26 INFO - Using env: {'DISPLAY': ':0', 18:10:26 INFO - 'HOME': '/home/cltbld', 18:10:26 INFO - 'LANG': 'en_US.UTF-8', 18:10:26 INFO - 'LANGUAGE': 'en_US:en', 18:10:26 INFO - 'LOGNAME': 'cltbld', 18:10:26 INFO - 'MAIL': '/var/mail/cltbld', 18:10:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:26 INFO - 'MOZ_NO_REMOTE': '1', 18:10:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:26 INFO - 'NO_EM_RESTART': '1', 18:10:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:26 INFO - 'PWD': '/builds/slave/test', 18:10:26 INFO - 'SHELL': '/bin/bash', 18:10:26 INFO - 'SHLVL': '1', 18:10:26 INFO - 'TERM': 'linux', 18:10:26 INFO - 'TMOUT': '86400', 18:10:26 INFO - 'USER': 'cltbld', 18:10:26 INFO - 'XDG_SESSION_COOKIE': '0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217', 18:10:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:26 INFO - '_': '/tools/buildbot/bin/python'} 18:10:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:26 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)) 18:10:26 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)) 18:10:26 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)) 18:10:26 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)) 18:10:26 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)) 18:10:26 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)) 18:10:26 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)) 18:10:26 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)) 18:10:26 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)) 18:10:26 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)) 18:10:26 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)) 18:10:26 INFO - Cleaning up... 18:10:26 INFO - Return code: 0 18:10:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:10:26 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')]} 18:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:26 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')]} 18:10:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a21030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c52210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce4220>, '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 0x1c502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c50740>, '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': '0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217', '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 18:10:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:10:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:10:26 INFO - Using env: {'DISPLAY': ':0', 18:10:26 INFO - 'HOME': '/home/cltbld', 18:10:26 INFO - 'LANG': 'en_US.UTF-8', 18:10:26 INFO - 'LANGUAGE': 'en_US:en', 18:10:26 INFO - 'LOGNAME': 'cltbld', 18:10:26 INFO - 'MAIL': '/var/mail/cltbld', 18:10:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:26 INFO - 'MOZ_NO_REMOTE': '1', 18:10:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:26 INFO - 'NO_EM_RESTART': '1', 18:10:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:26 INFO - 'PWD': '/builds/slave/test', 18:10:26 INFO - 'SHELL': '/bin/bash', 18:10:26 INFO - 'SHLVL': '1', 18:10:26 INFO - 'TERM': 'linux', 18:10:26 INFO - 'TMOUT': '86400', 18:10:26 INFO - 'USER': 'cltbld', 18:10:26 INFO - 'XDG_SESSION_COOKIE': '0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217', 18:10:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:26 INFO - '_': '/tools/buildbot/bin/python'} 18:10:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:26 INFO - Downloading/unpacking jsonschema 18:10:26 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 18:10:26 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 18:10:26 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 18:10:26 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 18:10:26 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 18:10:26 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 18:10:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:10:28 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 18:10:28 INFO - Downloading/unpacking functools32 (from jsonschema) 18:10:28 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 18:10:28 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 18:10:28 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 18:10:28 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 18:10:28 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 18:10:28 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 18:10:28 INFO - Downloading functools32-3.2.3-2.tar.gz 18:10:28 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 18:10:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:10:28 INFO - warning: no files found matching '*.txt' 18:10:28 INFO - no previously-included directories found matching 'build' 18:10:28 INFO - no previously-included directories found matching 'dist' 18:10:28 INFO - no previously-included directories found matching '.git*' 18:10:28 INFO - Installing collected packages: jsonschema, functools32 18:10:28 INFO - Running setup.py install for functools32 18:10:28 INFO - warning: no files found matching '*.txt' 18:10:28 INFO - no previously-included directories found matching 'build' 18:10:28 INFO - no previously-included directories found matching 'dist' 18:10:28 INFO - no previously-included directories found matching '.git*' 18:10:28 INFO - Successfully installed jsonschema functools32 18:10:28 INFO - Cleaning up... 18:10:28 INFO - Return code: 0 18:10:28 INFO - Running post-action listener: _resource_record_post_action 18:10:28 INFO - Running post-action listener: _start_resource_monitoring 18:10:28 INFO - Starting resource monitoring. 18:10:28 INFO - ##### 18:10:28 INFO - ##### Running install step. 18:10:28 INFO - ##### 18:10:28 INFO - Running pre-action listener: _resource_record_pre_action 18:10:28 INFO - Running main action method: install 18:10:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:10:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:10:29 INFO - Reading from file tmpfile_stdout 18:10:29 INFO - Detecting whether we're running mozinstall >=1.0... 18:10:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:10:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:10:29 INFO - Reading from file tmpfile_stdout 18:10:29 INFO - Output received: 18:10:29 INFO - Usage: mozinstall [options] installer 18:10:29 INFO - Options: 18:10:29 INFO - -h, --help show this help message and exit 18:10:29 INFO - -d DEST, --destination=DEST 18:10:29 INFO - Directory to install application into. [default: 18:10:29 INFO - "/builds/slave/test"] 18:10:29 INFO - --app=APP Application being installed. [default: firefox] 18:10:29 INFO - mkdir: /builds/slave/test/build/application 18:10:29 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'] 18:10:29 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 18:10:43 INFO - Reading from file tmpfile_stdout 18:10:43 INFO - Output received: 18:10:43 INFO - /builds/slave/test/build/application/firefox/firefox 18:10:43 INFO - Running post-action listener: _resource_record_post_action 18:10:43 INFO - ##### 18:10:43 INFO - ##### Running run-tests step. 18:10:43 INFO - ##### 18:10:43 INFO - Running pre-action listener: _resource_record_pre_action 18:10:43 INFO - Running main action method: run_tests 18:10:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:10:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:10:43 INFO - Python 2.7.3 18:10:43 INFO - Return code: 0 18:10:43 INFO - grabbing minidump binary from tooltool 18:10:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:43 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 0x1ce4220>, '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 0x1c502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c50740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:10:43 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 18:10:43 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 18:10:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:10:43 INFO - Return code: 0 18:10:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:10:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:10:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:10:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:10:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:10:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost'] in /builds/slave/test/build 18:10:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-044 --webServer localhost 18:10:43 INFO - Using env: {'DISPLAY': ':0', 18:10:43 INFO - 'HOME': '/home/cltbld', 18:10:43 INFO - 'LANG': 'en_US.UTF-8', 18:10:43 INFO - 'LANGUAGE': 'en_US:en', 18:10:43 INFO - 'LOGNAME': 'cltbld', 18:10:43 INFO - 'MAIL': '/var/mail/cltbld', 18:10:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:10:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:10:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:43 INFO - 'MOZ_NO_REMOTE': '1', 18:10:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:10:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:43 INFO - 'NO_EM_RESTART': '1', 18:10:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:43 INFO - 'PWD': '/builds/slave/test', 18:10:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:10:43 INFO - 'SHELL': '/bin/bash', 18:10:43 INFO - 'SHLVL': '1', 18:10:43 INFO - 'TERM': 'linux', 18:10:43 INFO - 'TMOUT': '86400', 18:10:43 INFO - 'USER': 'cltbld', 18:10:43 INFO - 'XDG_SESSION_COOKIE': '0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217', 18:10:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:43 INFO - '_': '/tools/buildbot/bin/python'} 18:10:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost'] with output_timeout 3600 18:10:43 INFO - mozversion INFO | application_buildid: 20151124165331 18:10:43 INFO - mozversion INFO | application_changeset: 099f695d31326c39595264c34988a0f4b7cbc698 18:10:43 INFO - mozversion INFO | application_display_name: Nightly 18:10:43 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:10:43 INFO - mozversion INFO | application_name: Firefox 18:10:43 INFO - mozversion INFO | application_remotingname: firefox 18:10:43 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 18:10:43 INFO - mozversion INFO | application_vendor: Mozilla 18:10:43 INFO - mozversion INFO | application_version: 45.0a1 18:10:43 INFO - mozversion INFO | platform_buildid: 20151124165331 18:10:43 INFO - mozversion INFO | platform_changeset: 099f695d31326c39595264c34988a0f4b7cbc698 18:10:43 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 18:10:43 INFO - mozversion INFO | platform_version: 45.0a1 18:10:43 INFO - 2015-11-24 18:10:43,820 DEBUG : using testdate: 1448417443 18:10:43 INFO - 2015-11-24 18:10:43,820 DEBUG : actual date: 1448417443 18:10:43 INFO - 2015-11-24 18:10:43,908 INFO : starting webserver on 'localhost:45684' 18:10:43 INFO - 2015-11-24 18:10:43,909 INFO : Starting test suite talos-linux64-ix-044 18:10:43 INFO - 2015-11-24 18:10:43,910 INFO : Starting test dromaeo_css 18:10:43 INFO - 2015-11-24 18:10:43,910 DEBUG : operating with platform_type : linux_ 18:10:43 INFO - 2015-11-24 18:10:43,910 INFO : Initialising browser for dromaeo_css test... 18:10:43 INFO - 2015-11-24 18:10:43,925 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZZHhb1/profile http://localhost:45684/getInfo.html 18:10:48 INFO - 2015-11-24 18:10:48,260 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:10:48 INFO - 2015-11-24 18:10:48,261 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:10:48 INFO - 2015-11-24 18:10:48,261 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:10:48 INFO - 2015-11-24 18:10:48,261 DEBUG : BROWSER_OUTPUT: __metrics 18:10:48 INFO - 2015-11-24 18:10:48,438 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:10:49 INFO - 2015-11-24 18:10:49,695 INFO : Browser initialized. 18:10:49 INFO - 2015-11-24 18:10:49,696 INFO : Running cycle 1/1 for dromaeo_css test... 18:10:49 INFO - 2015-11-24 18:10:49,696 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZZHhb1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:11:01 INFO - 2015-11-24 18:11:01,636 DEBUG : BROWSER_OUTPUT: RSS: Main: 139100160 18:11:01 INFO - 2015-11-24 18:11:01,637 DEBUG : BROWSER_OUTPUT: 18:11:52 INFO - 2015-11-24 18:11:52,781 DEBUG : BROWSER_OUTPUT: 1448417512776 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] 18:14:04 INFO - 2015-11-24 18:14:04,598 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45684/tests/dromaeo/cssquery-ext.html) 18:14:05 INFO - 2015-11-24 18:14:05,751 DEBUG : BROWSER_OUTPUT: RSS: Main: 241795072 18:14:05 INFO - 2015-11-24 18:14:05,751 DEBUG : BROWSER_OUTPUT: 18:17:08 INFO - 2015-11-24 18:17:08,375 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/dromaeo/cssquery-ext.html (next: http://localhost:45684/tests/dromaeo/cssquery-jquery.html) 18:17:08 INFO - 2015-11-24 18:17:08,957 DEBUG : BROWSER_OUTPUT: RSS: Main: 221364224 18:17:08 INFO - 2015-11-24 18:17:08,957 DEBUG : BROWSER_OUTPUT: 18:20:10 INFO - 2015-11-24 18:20:10,998 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45684/tests/dromaeo/cssquery-mootools.html) 18:20:11 INFO - 2015-11-24 18:20:11,571 DEBUG : BROWSER_OUTPUT: RSS: Main: 213020672 18:20:11 INFO - 2015-11-24 18:20:11,571 DEBUG : BROWSER_OUTPUT: 18:23:13 INFO - 2015-11-24 18:23:13,615 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45684/tests/dromaeo/cssquery-prototype.html) 18:23:14 INFO - 2015-11-24 18:23:14,184 DEBUG : BROWSER_OUTPUT: RSS: Main: 199725056 18:23:14 INFO - 2015-11-24 18:23:14,185 DEBUG : BROWSER_OUTPUT: 18:26:16 INFO - 2015-11-24 18:26:16,869 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45684/tests/dromaeo/cssquery-yui.html) 18:26:17 INFO - 2015-11-24 18:26:17,456 DEBUG : BROWSER_OUTPUT: RSS: Main: 206962688 18:26:17 INFO - 2015-11-24 18:26:17,456 DEBUG : BROWSER_OUTPUT: 18:29:19 INFO - 2015-11-24 18:29:19,879 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/dromaeo/cssquery-yui.html (next: http://localhost:45684/tests/dromaeo/cssquery-dojo.html) 18:29:20 INFO - 2015-11-24 18:29:20,129 DEBUG : BROWSER_OUTPUT: RSS: Main: 252350464 18:29:20 INFO - 2015-11-24 18:29:20,129 DEBUG : BROWSER_OUTPUT: 18:29:20 INFO - 2015-11-24 18:29:20,131 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:29:20 INFO - 2015-11-24 18:29:20,131 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:29:20 INFO - 2015-11-24 18:29:20,131 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:29:20 INFO - 2015-11-24 18:29:20,131 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:29:20 INFO - 2015-11-24 18:29:20,131 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2562.2057162279693;2673.9598906016413;2674.893004279829;2683.0072873036975;2685.4763321152373;4504.549545045494;4563.201439748039;4589.059242855215;4633.050224703936;4634.281686338623;4552.339910712944;4557.430321209848;4616.169089563878;4642.07158568286;4654.092451971858;4250.8087136078875;4276.743395396271;4282.828686852522;4337.436133302682;4345.152695224432;4327.069680018795;4339.804708788112;4370.2133615949115;4372.12557488502;4405.801738921757;4123.505179378464;4151.252774500589;4217.924429270516;4316.158349121938;4356.172327257811;4254.7021708480415;4283.764392958386;4295.140971805651;4301.5698430157045;4325.956740432618;4269.252880745857;4280.486341638992;4282.96943231441;4292.141571685675;4322.546132656089;4312.05134870328;4435.356873253392;4452.554744525554;4590.036092420596;4613.630909527264;4805.038992201538;4843.58829499493;4864.537868902448;4866.902661946741;4900.828471003498;4909.607231421478;4915.434725006634;4923.261510773377;4924.285978533093;4948.505149485059;4354.259775838115;4443.555644435531;4454.086912182995;4563.247064234397;4604.769761511944;4976.477469865685;5068.366454193226;5071.670341427795;5104.974475127675;5121.231815227746;4869.829555965524;4895.412550493964;4897.804087836518;4899.951000490021;4945.55490005894;4708.293755936568;4802.375691160127;4803.03939212159;4961.280614310905;4987.975060124676;4799.256115302133;4846.660733748674;4865.70805751656;4867.148249056472;4916.360873086476;4895.975520122448;4908.2637604358915;4910.288008238786;4911.533404326583;4922.483139270396;3923.7449565778134;4005.7396269242404;4007.499062617173;4097.508299004138;4125.66994640428;3524.7356448266482;3550.2899420115564;3607.9278414431565;3617.5839778425293;3638.599754027055;4251.851185208503;4281.100968796525;4290.2706539888295;4315.784210789448;4319.3952846601505;4750.8337208197545;4950.9009819803405;4960.727160006234;4966.602671786249;5006.549410553068;4194.30793919006;4201.159768046403;4261;4271.5941985511945;4296.484421869333;4310.43964284641;4343.761093139908;4391.494978077498;4391.802368893407;4433.7561434121435;4498.662600304925;4733.5266473352385;4735.692180008288;4736.076465089274;4736.076465089274;4834.7824347903725;4834.903301934012;4855.465898751064;4886.973735515581;4919.975400122977;4611.446626404853;4629.884252893705;4630.490646029001;4633.467151277646;4675.275332323496;4650.86047418578;4804.927926081182;4806.47128815823;4808.711477311233;4849.151398505177;4845.854624361275;4878.243872199815;4885.926711099265;4912.582430493305;4945.085159245551;4772.093302272696;4806.615470762261;4808.447028591757;4812.989272252726;4851.708897466164;4590.609798167193;4783.306420569068;4822.9758851205515;4826.975865120652;4866.026794641015;4594.517575654641;4652.581267685924;4665.066986602626;4690.554397332385;4695.3191787191345;4834.5890599298045;4868.707877527359;4879.072976134665;4884.023195360871;4884.194107972145;4742;4762.571368576845;4770.737609431376;4781.354517140142;4794.616430685606 18:29:20 INFO - 2015-11-24 18:29:20,134 DEBUG : BROWSER_OUTPUT: |1;ext.html;551.0769461152571;557.9944200557993;578.0721941284239;580.5297708855826;587.9617824841386;28045.859770701143;28701.856490717542;36499.27001459972;37019.259614807685;37078;40695.168717407716;41180.38229426561;41256.38115428271;41266;41283.79358103213;6936.965315173417;7885.960570197141;8044.356451483867;8047.798805029892;8049.718259860906;7050.330218629222;7140.428765698732;7141.571505709648;7157.105361829771;10110.24173187014;10005.949970250138;10052.888893332409;10086.193104551656;10105.646302379419;10108.039736225046;10048.397096174216;10129.290949633529;10130.848037279447;10138.9493052535;10158.796824063515;7111.53775004623;7321.341079302888;10068.14420774235;10365.585376584928;10378.636747713794;31486.89795857134;31522.684773152203;33196;33222.3355532888;34081.48877766835;2892.363679990399;2900.5214139666928;2957;3496.930061398758;3526.559180102487;28632.14103576896;28669.85665071682;28896.133116006553;29404.411911761857;29408.852955735085;3352.5306457096026;3424.9657503424896;3509.0700964244497;3522.172289511963;3564.8930532084078;219456;220207.9999999984;220872.9999999984;221409;221984;50956.49043509518;52216.73891630518;61862.69068654718;62743.6862815683;63057;56789;56914.146287805714;57027;57069.14396283976;57100;13431.194128352136;13586;13632.522861699794;13647.454101836014;13657.931710341583;9288.767780805534;9337.972822989466;9362.95318523403;9432.905670943202;9457.716268511957;18938.14778334978;19268.710969335472;19317.130729117223;19325.226990920488;19358.612827743367;7514.6618402172035;7666.65500052499;7766.145723970359;7796.883046754303;7802.726904558313;816.6733306677257;851.2294427590597;868.8566386546149;925.9444433334022;956.6460409648336;30100.548491772643;30438.84780576083;30987;31096.22259443532;31276;885.9689910853218;914.8947870994921;916.7662246127328;926.1710768861831;934.8270569944445;13604.619191641423;13620;13711.45154193841;13756.105853119516;13819.309034548232;334114;339420;340123;342244;342792;46427.76786116048;46902.296465553045;46919.5308046922;46950.5304946953;46995;1035.803197392493;1123.9887601123885;1127.616610352484;1143.274020996673;1230.7169351049129;25316.873415632803;26607;26880.32799179958;26946.191614251602;27188.864055679594;37493;38009.61990380061;38074.80962595169;38083.238335232585;38106.809465952494;223395;227237;347889;355326;357129;29339.41321173522;29476.705232947395;29504;29530.852345738134;29530.96641617533;37967.430488542144;39037.609623903394;39241.411378829915;39400.40899386454;39504.60495395009;32409.277704256063;32735.18162045968;33027.504587431686;33360;33713.66286337105;33206.16984575405;33327.50008749822;33556.825511106996;33666;33763.15592110217;32714.34571308608;32777.672223278416;32856.178595534344;33098.83450582732;33184.33631327312;28264.85867570649;29854.70145298519;32394.514082289257;35073.29853402867;35133.297334053685;23572.780612372128;23721.406964826016;23743.881280593487;23808;24186.75813241845 18:29:20 INFO - 2015-11-24 18:29:20,135 DEBUG : BROWSER_OUTPUT: |2;jquery.html;327.56433942856006;334.78239144556034;335.98075565849246;337.2327953904866;347.24127780798955;12428.689282767935;12684.175528590635;14832.629184270385;15091.773623395658;15715.764263536043;12592.81110783339;15094.54716358511;15478.380864765395;15857.841421585781;15872.523824285261;4881.292212629175;4885.291632713254;5070.8732281693065;5076.060928728179;5085.847424577269;3620.8732694355704;3752.8686495972647;4999.900001999958;5609.831705048855;5739.885202295952;5629.60592758507;5646.124850648135;5661.631993920402;5698.914516282259;5707.571932105108;5530.972345138269;5773.162891380726;5789.247397838254;5799.217105690741;5816.534677225812;5102.719350435762;5108.310378098946;5111.335526381584;5125.769340379692;5128.410232823236;19617.9019104905;19886.90056549708;20203.797962020337;20237.291694790758;20523.794762052337;1843.446965910235;1906.494778883597;1927.026851440028;1954.0718158874422;1973.753280839895;13255.801162982467;13318.134321269086;13344.466221351135;13431.261280629562;13641.249731264776;1860.5348662834292;1865.5615930256379;1878.549148204421;1898.2881419467699;1943.893085880276;54676.726616366665;55691.72154139244;55971.44028559703;62477.37522624761;62947.05579416313;14992.70014599702;15098.094114352954;15099.849001510063;15133.924330378277;15139.243037848064;22443.21448749286;22588.887055564617;22625.547489050456;22725.43186420353;22740.65889011666;7768.067831860213;7798.8050298741855;7807.258310460497;7832.255935686215;7832.451728378959;6348.904766428507;6393.648349340738;6394.1687580614225;6439.742410303629;6444.033394990789;7148.46386521013;7278.745243916544;7285.635718214068;7289.214142535006;7295.781126566211;1740.8781385302907;1748.2395158106265;1766.222861940742;2124.447643612672;2825.307799589114;377.4395023390263;381.16144482139407;845.9915400846036;950.7480517662686;964.8745663063897;9726.076022777825;10020.34867733595;14825.332860021324;15659.451919183002;15859.52421427359;858.9484630922165;870.1907226279494;886.8049029213691;906.419891269588;927.5084205371164;7561.243875612395;8661.653533858702;8992.100789920956;9061.77345566353;9063.728088157366;448529;459951;473054;478749;482541;69763.3023669767;70303;70532;70983;71002.64498677576;1039.443897514826;1091.661584908665;1102.8566286382718;1105.1876870500175;1116.7319843237565;13360.732785344433;13660.590182294547;14041.719165616427;14225.359858806585;14545.781813272597;30719.846400768747;31849.044528664177;31961.68038319587;32074.83962580172;32840.50739238961;42734.358984614635;42950.78524607357;43025.7848710767;44465.55534444614;45216.32175517304;8493.131511067555;8828.161324674145;9008.144226298491;9014.954925225333;9080.364374493722;10270.229732770222;10324.174066074846;10380.688579342632;11890.524379024742;15862.127582982584;12365.443555040225;12385.275438491866;12604.054695897661;12922.095453318178;13157.27634980047;12388.699186585278;12430.627081187578;13219.603411897659;13274.137181083199;13308.201507909556;9063.68277110298;9086.545672716205;9471.10024547667;9476.810463790824;9974.750631234278;11648.068154547786;11713.648590542298;11723.35521546323;13015.088943773844;13500.32498375097;15072.24638768079;15096.773548396563;15467.767983480031;15643.765343520085;15680.92159539195 18:29:20 INFO - 2015-11-24 18:29:20,138 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1325.952497526953;1380.2408675228621;1398.7692030814917;1552.953411397658;1626.3819748495566;5828.650280983139;6126.356732543086;6599.406053455188;6854.520183587151;6876.724931002754;6858.211305699846;6874.175098988116;6877.105976223096;6918.066061081752;6919.273476284993;1729.4725108841799;2023.8077382648626;2059.680749483832;2060.9072591733407;2061.021015017862;1779.9288028478845;2045.8670186437907;2090.341542414135;2092.550101728128;2092.665173572229;1999.720039194514;2028.1279050008563;2029.7056926745613;2034.6337659221335;2039.826614737749;2019.3437132931856;2042.5097976485686;2043.5606344635826;2043.6014977079476;2047.744031996008;2033.644112280345;2081.7918208179212;2082.3856962196187;2082.9479263018393;2083.656196727538;6826.93173068268;6870.106886104774;6901;6902.102726645554;6925.792226233221;4061.3704875744306;4098.344264917599;4126.66986641068;4127.979360103181;4139.482564679415;2731.8634068296706;2748.7663548598393;2754.2838861895852;2771.4041481081663;2786.0527420676863;851.5146366571057;886.406107907697;889.2397000564554;891.0599317719802;899.2581120575551;8914.28685705142;8962.37263391563;8979.326550508738;8984.550772461416;8989.056149104315;3730.7015438764834;3773.584905660375;3835.060410199519;3958.6041395860184;3961.603839616015;7880.881786773203;7907.299930514851;7950.204979502004;7962.840743185104;7970.561619111005;4194.6853985951175;4208.347706105497;4210.873673789792;4211.052513184571;4222.746635201836;3834.2906562286066;3862.246861861966;3867.16855875988;3882.4176373544196;3888.572257051722;2751.6285301484045;2784.276088217038;3364.7139993100373;3397.2356219850335;3404.557407537004;1062.6227689170387;1062.9750325629689;1073.8711354637337;1080.8756992945755;1083.285031878956;739.5230076600562;739.7041183526633;740.5667684404576;758.9165191828895;761.5392687424139;6342.809715708444;6412.39082287182;6421.1652485177565;6422.710978006001;6429.067785171124;996.5067291690659;997.4165113408737;1015.0864222200079;1015.9847602285971;1016.5781200801732;4852.95147048525;4932.309476673268;4986.376702912136;4987.800487980513;4992.425871024879;7678.500897441649;7805.258500442449;7894.802629934183;7899;7942.920570794218;7768.65041073153;7836.098848632366;7901.130875603679;7921.960390198012;7925.445218834626;6108.083787431832;6144.723487443076;6152.477039451696;6154;6154.692265386713;5941.494972927349;6134.447899689049;6334.778282760081;6341.429271365415;6359.55483116196;7263.963680181777;7326.816829579303;7398.630068496446;7464.216257293123;7482.625868706652;7361.558306501627;7529.962350188214;7569.735059273119;7589.316961473494;7597.088349398107;5906.143609176559;5977.790777322773;5981.700914954322;5995.970020149872;5997.190379298913;5328.813491527778;5640.2529688150125;6446.548741588043;6491.448226900767;6516.967415163083;5488.69812160335;5664.660120392789;5814.331351894586;6123.050927106306;6137.693115344304;5901.704914754331;5910.408959104056;5932.584719069624;6000.069989151689;6002.849928751816;5916.674582897982;5924.7037648118285;5928.110783382527;5944.910826337695;5945.227120474311;5522.972385138049;5523.944760552343;5526.640768350045;5537.446255374431;5549.389567147691;5441.727913604224;5523.8895222094525;5677.517411019944;5712.485876271156;5718.656880587178 18:29:20 INFO - 2015-11-24 18:29:20,139 DEBUG : BROWSER_OUTPUT: |4;prototype.html;304.89023951377504;309.1421305876193;311.7895420591099;312.8717225937366;313.5610145795884;5225.477452254778;5299.60252981026;5303.310569625948;5303.787848486066;5306.389765177006;5037.622178336621;5122.897542049166;5140.306058682077;5254.921176182351;5264.947350526493;4125.979370103145;4140.296149654566;4142.64787493062;4153.896152596187;4167.187398457302;3902.2975864344407;4149.419081328617;4158.397032430295;4161.583841615829;4163.20899029185;3949.111449923773;3997.860074897379;4069.1861627674584;4076.9592304077023;4088.2232375848644;4112.09533902541;4112.321466957948;4119.567445418248;4120.608542188503;4121.093359460914;3657.65252301031;3660.5973342932257;3660.963390366089;3662.487251784752;3669.101070237782;5038;5044.722540260284;5049.6212784041345;5052.216906379517;5056.2162864755655;4527.071950250191;4654.674172807905;4656.161890859644;4679.74261415622;4732.455767586737;5500.779968801243;5508.476694713996;5508.779648814043;5509.752061157257;5511.53151982082;3864.188520410717;3865.690744740414;3867.806609669505;3868.32304346741;3869.729118961674;5728.341240757324;5789.102689083199;5793.797217097382;5796.13058041293;5801.767929282866;3910.5145413870246;3967.3652215645934;3994.400839874042;4008.6392224699916;4015.8996025099605;4051.716379853441;4057.6145266199665;4058.7564746115327;4059.086705491241;4061.411095391211;4634.559716826896;4635.95364046362;4637.976810115928;4639.396878405853;4640.257558790578;5529.612927095065;5533.3083364579425;5533.474319939599;5540.196671482612;5543.944560554423;1387.7085811979598;1412.5621057472217;1436.6408397900525;1440.1359184489222;1440.8415074341815;953.2755106119399;960.1358777100526;966.6085235479642;967.3132076225818;967.5307475874196;4744.691595046311;4807.807687692453;4809.591184749265;4811.566958973709;4815.6388270879825;5636.943630563642;5638.4925356718095;5643.915341269884;5645.842602266525;5650.208970744099;4724.811007559659;4784.282357646381;4795.424549054136;4844.733539655353;4928.556429921325;3083.2137804859617;3089.7682673799554;3096.2723759916403;3117.9376412471624;3119.939220664985;5486.341639003345;5489.149181876815;5492.862678433071;5495.917561236584;5495.945040549544;5778.508826749773;5935.703214839241;5935.821925342247;5940.881182376328;5947.04847224442;5491.478309560585;5503.477169668875;5511.0080185566185;5513.889722205614;5514.751836167382;5717.313922329347;5765.884682306414;5783.537317014713;5790.334111577223;5791.160281759206;5659.603827732184;5661.830145095653;5670.4329567044615;5670.829875103754;5671.120976248688;5366.812161574326;5449.945500545103;5492.203630473479;5495.175723641326;5500.972495137498;3978.542467616261;3982.183652351203;3988.062805240824;3990.620891015349;3992.0818211812025;5748;5750.856228594319;5754.108113242454;5754.7122643866805;5759.164921086335;5578.581606379456;5579.13523403873;5580.469855363734;5585.273914391103;5586.301712285965;5390.541803946709;5521.779128834802;5580.38615752275;5606.607537472338;5607.719614019365;5447.128459446628;5458.754356054066;5463.754131064191;5470.097433923358;5491.945080549143;5635.802746903839;5636.464535869086;5638.097904335286;5638.097904335286;5642.5485961122195;5380.246765452762;5492.5605951524585;5500.28496295495;5502.119660854404;5502.202180683858 18:29:20 INFO - 2015-11-24 18:29:20,141 DEBUG : BROWSER_OUTPUT: |5;yui.html;634.8825467288555;650.3074225949364;697.0972590495309;762.192076399017;773.4083426178972;11674.416279186038;11913.285202887833;11931.821022684666;11933.16467847251;11939.58211462599;11470.25443346182;12539.31033793141;12717.682057948556;12720.109592328541;12722.173058751174;600.9879802403949;756.8183635927365;771.3058247577197;771.5872008475467;771.6836097200149;607.5716619783065;758.5183408535592;773.8490994256123;776.770852598482;777.6461709921972;790.411143697943;804.3324041045947;804.5092493578942;804.7867315161458;809.8299357135008;626.8683576448951;635.7997230872261;777.7511196417149;791.5369508837337;797.0475282037967;583.0292562882795;583.1777194156261;585.0259318235134;586.1676419484343;587.3363099697358;11814.113941454425;11833.704157396134;11875.0499960003;11910.821337679941;11934.820977685342;10467.738306542322;10990.395528245943;11218.719532011683;11343.716407089889;11466.713332166679;9287;9293.210077143383;9612.903870961341;9756.339438896412;9887.505624718808;3167.8099314041233;3223.6239901301333;3234.951475727866;3246.318273162639;3327.8169700666454;41465.378019329735;41927.790361048;42222.57777422217;42310;42594.78702606498;9016.504092274989;9116.4074335168;9139.63441462334;9220;9249.213816825511;15856.682866342839;15974.68050639004;16043.278052487665;16063.919680401523;16087;6290.937090629035;6453.967730161319;6520.706568204442;6571.934280657228;6573.3098024706715;3551.058969373118;3575.4636804479014;3624.7643903146213;3672.2839046385698;3703.9814800925824;2655.269800804794;2685.650865387526;2686;2687.87904544296;2691.6231727558156;478.62906247658344;497.25908396489694;501.4408934038521;506.76435457512434;508.8829569199105;476.39973633221695;498.0810404803132;514.9021685879671;529.282821776495;539.1535289595377;7417.517861338995;7429.591372474566;7456.888146677804;7481.102267727908;7718.498297610638;366.3753300622432;439.94500687414074;512.1421618788514;529.933758280215;558.1460365640577;3381.746369022333;3436.467347561132;3485.8605655773486;3619.6561326673923;3890.630390112935;43196.78401607972;43455.56544434578;43696.78151609222;43918;43934;42704.35943460851;43101.56898431038;43129.13741725148;43129.784351078044;43168.13663726708;39851.60148398479;40263.39604905929;40466.39300410437;40909.1818163635;41726.79136604358;7538.811529711801;7606.391488680782;7761.223877612194;7792.571408572583;8074.1522140176785;31612.841935790173;31897.202569935936;32767;32934.670653293164;32936.835315823264;38786.80606596949;39794.403083953184;40455;40818;40960.1807963845;9377.624895004123;9557.665481708107;9585;9710.271729620448;9797.510124493605;10091.949540252252;10147.543360548942;10370.014848589371;10445;10679.57281708723;9723.270754693285;9762.75593110178;9803.068708472683;9968.252381071594;10465.58137674515;10090.596376144871;10093.293469457067;10281.588736450458;10571.154307655523;10669.199810014436;9863.654772082942;9981;10007.749806254611;10032.448215348226;10055.145312648214;8654.567271636519;8687.435316704394;8927.866082008644;9008.459492430475;9025.954870225607;8799.516026618598;8940.73177804667;9012.86480702803;9053.244137176447;9103.726888193365 18:29:20 INFO - 2015-11-24 18:29:20,141 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:29:20 INFO - 2015-11-24 18:29:20,141 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:29:20 INFO - 2015-11-24 18:29:20,141 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,899 18:29:20 INFO - 2015-11-24 18:29:20,141 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:29:20 INFO - 2015-11-24 18:29:20,141 DEBUG : BROWSER_OUTPUT: __startTimestamp1448418560129__endTimestamp 18:29:20 INFO - 2015-11-24 18:29:20,141 DEBUG : BROWSER_OUTPUT: 18:29:20 INFO - 2015-11-24 18:29:20,141 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:29:20 INFO - 2015-11-24 18:29:20,141 DEBUG : BROWSER_OUTPUT: Number of tests: 6 18:29:20 INFO - 2015-11-24 18:29:20,141 DEBUG : BROWSER_OUTPUT: 18:29:20 INFO - 2015-11-24 18:29:20,142 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4541.59 Median:4652.58 stddev:464.79 (10.0%) stddev-sans-first:439.52 18:29:20 INFO - 2015-11-24 18:29:20,142 DEBUG : BROWSER_OUTPUT: Values: 2562.2 2674.0 2674.9 2683.0 2685.5 4504.5 4563.2 4589.1 4633.1 4634.3 4552.3 4557.4 4616.2 4642.1 4654.1 4250.8 4276.7 4282.8 4337.4 4345.2 4327.1 4339.8 4370.2 4372.1 4405.8 4123.5 4151.3 4217.9 4316.2 4356.2 4254.7 4283.8 4295.1 4301.6 4326.0 4269.3 4280.5 4283.0 4292.1 4322.5 4312.1 4435.4 4452.6 4590.0 4613.6 4805.0 4843.6 4864.5 4866.9 4900.8 4909.6 4915.4 4923.3 4924.3 4948.5 4354.3 4443.6 4454.1 4563.2 4604.8 4976.5 5068.4 5071.7 5105.0 5121.2 4869.8 4895.4 4897.8 4900.0 4945.6 4708.3 4802.4 4803.0 4961.3 4988.0 4799.3 4846.7 4865.7 4867.1 4916.4 4896.0 4908.3 4910.3 4911.5 4922.5 3923.7 4005.7 4007.5 4097.5 4125.7 3524.7 3550.3 3607.9 3617.6 3638.6 4251.9 4281.1 4290.3 4315.8 4319.4 4750.8 4950.9 4960.7 4966.6 5006.5 4194.3 4201.2 4261.0 4271.6 4296.5 4310.4 4343.8 4391.5 4391.8 4433.8 4498.7 4733.5 4735.7 4736.1 4736.1 4834.8 4834.9 4855.5 4887.0 4920.0 4611.4 4629.9 4630.5 4633.5 4675.3 4650.9 4804.9 4806.5 4808.7 4849.2 4845.9 4878.2 4885.9 4912.6 4945.1 4772.1 4806.6 4808.4 4813.0 4851.7 4590.6 4783.3 4823.0 4827.0 4866.0 4594.5 4652.6 4665.1 4690.6 4695.3 4834.6 4868.7 4879.1 4884.0 4884.2 4742.0 4762.6 4770.7 4781.4 4794.6 18:29:20 INFO - 2015-11-24 18:29:20,142 DEBUG : BROWSER_OUTPUT: 18:29:20 INFO - 2015-11-24 18:29:20,142 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:44410.58 Median:18454.68 stddev:77272.75 (418.7%) stddev-sans-first:77419.35 18:29:20 INFO - 2015-11-24 18:29:20,142 DEBUG : BROWSER_OUTPUT: Values: 551.1 558.0 578.1 580.5 588.0 28045.9 28701.9 36499.3 37019.3 37078.0 40695.2 41180.4 41256.4 41266.0 41283.8 6937.0 7886.0 8044.4 8047.8 8049.7 7050.3 7140.4 7141.6 7157.1 10110.2 10005.9 10052.9 10086.2 10105.6 10108.0 10048.4 10129.3 10130.8 10138.9 10158.8 7111.5 7321.3 10068.1 10365.6 10378.6 31486.9 31522.7 33196.0 33222.3 34081.5 2892.4 2900.5 2957.0 3496.9 3526.6 28632.1 28669.9 28896.1 29404.4 29408.9 3352.5 3425.0 3509.1 3522.2 3564.9 219456.0 220208.0 220873.0 221409.0 221984.0 50956.5 52216.7 61862.7 62743.7 63057.0 56789.0 56914.1 57027.0 57069.1 57100.0 13431.2 13586.0 13632.5 13647.5 13657.9 9288.8 9338.0 9363.0 9432.9 9457.7 18938.1 19268.7 19317.1 19325.2 19358.6 7514.7 7666.7 7766.1 7796.9 7802.7 816.7 851.2 868.9 925.9 956.6 30100.5 30438.8 30987.0 31096.2 31276.0 886.0 914.9 916.8 926.2 934.8 13604.6 13620.0 13711.5 13756.1 13819.3 334114.0 339420.0 340123.0 342244.0 342792.0 46427.8 46902.3 46919.5 46950.5 46995.0 1035.8 1124.0 1127.6 1143.3 1230.7 25316.9 26607.0 26880.3 26946.2 27188.9 37493.0 38009.6 38074.8 38083.2 38106.8 223395.0 227237.0 347889.0 355326.0 357129.0 29339.4 29476.7 29504.0 29530.9 29531.0 37967.4 39037.6 39241.4 39400.4 39504.6 32409.3 32735.2 33027.5 33360.0 33713.7 33206.2 33327.5 33556.8 33666.0 33763.2 32714.3 32777.7 32856.2 33098.8 33184.3 28264.9 29854.7 32394.5 35073.3 35133.3 23572.8 23721.4 23743.9 23808.0 24186.8 18:29:20 INFO - 2015-11-24 18:29:20,142 DEBUG : BROWSER_OUTPUT: 18:29:20 INFO - 2015-11-24 18:29:20,142 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26366.06 Median:335.38 stddev:76505.98 (22811.6%) stddev-sans-first:76695.61 18:29:20 INFO - 2015-11-24 18:29:20,143 DEBUG : BROWSER_OUTPUT: Values: 327.6 334.8 336.0 337.2 347.2 12428.7 12684.2 14832.6 15091.8 15715.8 12592.8 15094.5 15478.4 15857.8 15872.5 4881.3 4885.3 5070.9 5076.1 5085.8 3620.9 3752.9 4999.9 5609.8 5739.9 5629.6 5646.1 5661.6 5698.9 5707.6 5531.0 5773.2 5789.2 5799.2 5816.5 5102.7 5108.3 5111.3 5125.8 5128.4 19617.9 19886.9 20203.8 20237.3 20523.8 1843.4 1906.5 1927.0 1954.1 1973.8 13255.8 13318.1 13344.5 13431.3 13641.2 1860.5 1865.6 1878.5 1898.3 1943.9 54676.7 55691.7 55971.4 62477.4 62947.1 14992.7 15098.1 15099.8 15133.9 15139.2 22443.2 22588.9 22625.5 22725.4 22740.7 7768.1 7798.8 7807.3 7832.3 7832.5 6348.9 6393.6 6394.2 6439.7 6444.0 7148.5 7278.7 7285.6 7289.2 7295.8 1740.9 1748.2 1766.2 2124.4 2825.3 377.4 381.2 846.0 950.7 964.9 9726.1 10020.3 14825.3 15659.5 15859.5 858.9 870.2 886.8 906.4 927.5 7561.2 8661.7 8992.1 9061.8 9063.7 448529.0 459951.0 473054.0 478749.0 482541.0 69763.3 70303.0 70532.0 70983.0 71002.6 1039.4 1091.7 1102.9 1105.2 1116.7 13360.7 13660.6 14041.7 14225.4 14545.8 30719.8 31849.0 31961.7 32074.8 32840.5 42734.4 42950.8 43025.8 44465.6 45216.3 8493.1 8828.2 9008.1 9015.0 9080.4 10270.2 10324.2 10380.7 11890.5 15862.1 12365.4 12385.3 12604.1 12922.1 13157.3 12388.7 12430.6 13219.6 13274.1 13308.2 9063.7 9086.5 9471.1 9476.8 9974.8 11648.1 11713.6 11723.4 13015.1 13500.3 15072.2 15096.8 15467.8 15643.8 15680.9 18:29:20 INFO - 2015-11-24 18:29:20,143 DEBUG : BROWSER_OUTPUT: 18:29:20 INFO - 2015-11-24 18:29:20,143 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4713.02 Median:5908.28 stddev:2395.15 (40.5%) stddev-sans-first:2388.33 18:29:20 INFO - 2015-11-24 18:29:20,144 DEBUG : BROWSER_OUTPUT: Values: 1326.0 1380.2 1398.8 1553.0 1626.4 5828.7 6126.4 6599.4 6854.5 6876.7 6858.2 6874.2 6877.1 6918.1 6919.3 1729.5 2023.8 2059.7 2060.9 2061.0 1779.9 2045.9 2090.3 2092.6 2092.7 1999.7 2028.1 2029.7 2034.6 2039.8 2019.3 2042.5 2043.6 2043.6 2047.7 2033.6 2081.8 2082.4 2082.9 2083.7 6826.9 6870.1 6901.0 6902.1 6925.8 4061.4 4098.3 4126.7 4128.0 4139.5 2731.9 2748.8 2754.3 2771.4 2786.1 851.5 886.4 889.2 891.1 899.3 8914.3 8962.4 8979.3 8984.6 8989.1 3730.7 3773.6 3835.1 3958.6 3961.6 7880.9 7907.3 7950.2 7962.8 7970.6 4194.7 4208.3 4210.9 4211.1 4222.7 3834.3 3862.2 3867.2 3882.4 3888.6 2751.6 2784.3 3364.7 3397.2 3404.6 1062.6 1063.0 1073.9 1080.9 1083.3 739.5 739.7 740.6 758.9 761.5 6342.8 6412.4 6421.2 6422.7 6429.1 996.5 997.4 1015.1 1016.0 1016.6 4853.0 4932.3 4986.4 4987.8 4992.4 7678.5 7805.3 7894.8 7899.0 7942.9 7768.7 7836.1 7901.1 7922.0 7925.4 6108.1 6144.7 6152.5 6154.0 6154.7 5941.5 6134.4 6334.8 6341.4 6359.6 7264.0 7326.8 7398.6 7464.2 7482.6 7361.6 7530.0 7569.7 7589.3 7597.1 5906.1 5977.8 5981.7 5996.0 5997.2 5328.8 5640.3 6446.5 6491.4 6517.0 5488.7 5664.7 5814.3 6123.1 6137.7 5901.7 5910.4 5932.6 6000.1 6002.8 5916.7 5924.7 5928.1 5944.9 5945.2 5523.0 5523.9 5526.6 5537.4 5549.4 5441.7 5523.9 5677.5 5712.5 5718.7 18:29:20 INFO - 2015-11-24 18:29:20,144 DEBUG : BROWSER_OUTPUT: 18:29:20 INFO - 2015-11-24 18:29:20,144 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4608.01 Median:5282.27 stddev:1348.53 (25.5%) stddev-sans-first:1313.07 18:29:20 INFO - 2015-11-24 18:29:20,144 DEBUG : BROWSER_OUTPUT: Values: 304.9 309.1 311.8 312.9 313.6 5225.5 5299.6 5303.3 5303.8 5306.4 5037.6 5122.9 5140.3 5254.9 5264.9 4126.0 4140.3 4142.6 4153.9 4167.2 3902.3 4149.4 4158.4 4161.6 4163.2 3949.1 3997.9 4069.2 4077.0 4088.2 4112.1 4112.3 4119.6 4120.6 4121.1 3657.7 3660.6 3661.0 3662.5 3669.1 5038.0 5044.7 5049.6 5052.2 5056.2 4527.1 4654.7 4656.2 4679.7 4732.5 5500.8 5508.5 5508.8 5509.8 5511.5 3864.2 3865.7 3867.8 3868.3 3869.7 5728.3 5789.1 5793.8 5796.1 5801.8 3910.5 3967.4 3994.4 4008.6 4015.9 4051.7 4057.6 4058.8 4059.1 4061.4 4634.6 4636.0 4638.0 4639.4 4640.3 5529.6 5533.3 5533.5 5540.2 5543.9 1387.7 1412.6 1436.6 1440.1 1440.8 953.3 960.1 966.6 967.3 967.5 4744.7 4807.8 4809.6 4811.6 4815.6 5636.9 5638.5 5643.9 5645.8 5650.2 4724.8 4784.3 4795.4 4844.7 4928.6 3083.2 3089.8 3096.3 3117.9 3119.9 5486.3 5489.1 5492.9 5495.9 5495.9 5778.5 5935.7 5935.8 5940.9 5947.0 5491.5 5503.5 5511.0 5513.9 5514.8 5717.3 5765.9 5783.5 5790.3 5791.2 5659.6 5661.8 5670.4 5670.8 5671.1 5366.8 5449.9 5492.2 5495.2 5501.0 3978.5 3982.2 3988.1 3990.6 3992.1 5748.0 5750.9 5754.1 5754.7 5759.2 5578.6 5579.1 5580.5 5585.3 5586.3 5390.5 5521.8 5580.4 5606.6 5607.7 5447.1 5458.8 5463.8 5470.1 5491.9 5635.8 5636.5 5638.1 5638.1 5642.5 5380.2 5492.6 5500.3 5502.1 5502.2 18:29:20 INFO - 2015-11-24 18:29:20,144 DEBUG : BROWSER_OUTPUT: 18:29:20 INFO - 2015-11-24 18:29:20,145 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12020.27 Median:22205.20 stddev:13483.78 (60.7%) stddev-sans-first:13494.50 18:29:20 INFO - 2015-11-24 18:29:20,146 DEBUG : BROWSER_OUTPUT: Values: 634.9 650.3 697.1 762.2 773.4 11674.4 11913.3 11931.8 11933.2 11939.6 11470.3 12539.3 12717.7 12720.1 12722.2 601.0 756.8 771.3 771.6 771.7 607.6 758.5 773.8 776.8 777.6 790.4 804.3 804.5 804.8 809.8 626.9 635.8 777.8 791.5 797.0 583.0 583.2 585.0 586.2 587.3 11814.1 11833.7 11875.0 11910.8 11934.8 10467.7 10990.4 11218.7 11343.7 11466.7 9287.0 9293.2 9612.9 9756.3 9887.5 3167.8 3223.6 3235.0 3246.3 3327.8 41465.4 41927.8 42222.6 42310.0 42594.8 9016.5 9116.4 9139.6 9220.0 9249.2 15856.7 15974.7 16043.3 16063.9 16087.0 6290.9 6454.0 6520.7 6571.9 6573.3 3551.1 3575.5 3624.8 3672.3 3704.0 2655.3 2685.7 2686.0 2687.9 2691.6 478.6 497.3 501.4 506.8 508.9 476.4 498.1 514.9 529.3 539.2 7417.5 7429.6 7456.9 7481.1 7718.5 366.4 439.9 512.1 529.9 558.1 3381.7 3436.5 3485.9 3619.7 3890.6 43196.8 43455.6 43696.8 43918.0 43934.0 42704.4 43101.6 43129.1 43129.8 43168.1 39851.6 40263.4 40466.4 40909.2 41726.8 7538.8 7606.4 7761.2 7792.6 8074.2 31612.8 31897.2 32767.0 32934.7 32936.8 38786.8 39794.4 40455.0 40818.0 40960.2 9377.6 9557.7 9585.0 9710.3 9797.5 10091.9 10147.5 10370.0 10445.0 10679.6 9723.3 9762.8 9803.1 9968.3 10465.6 10090.6 10093.3 10281.6 10571.2 10669.2 9863.7 9981.0 10007.7 10032.4 10055.1 8654.6 8687.4 8927.9 9008.5 9026.0 8799.5 8940.7 9012.9 9053.2 9103.7 18:29:20 INFO - 2015-11-24 18:29:20,146 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:29:20 INFO - 2015-11-24 18:29:20,146 DEBUG : BROWSER_OUTPUT: 18:29:20 INFO - 2015-11-24 18:29:20,768 INFO : Browser exited with error code: 0 18:29:20 INFO - 2015-11-24 18:29:20,792 INFO : Completed test dromaeo_css (00:18:36) 18:29:20 INFO - 2015-11-24 18:29:20,792 INFO : Starting test kraken 18:29:20 INFO - 2015-11-24 18:29:20,792 DEBUG : operating with platform_type : linux_ 18:29:20 INFO - 2015-11-24 18:29:20,792 INFO : Initialising browser for kraken test... 18:29:20 INFO - 2015-11-24 18:29:20,798 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphkblWy/profile http://localhost:45684/getInfo.html 18:29:25 INFO - 2015-11-24 18:29:25,082 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:29:25 INFO - 2015-11-24 18:29:25,082 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:29:25 INFO - 2015-11-24 18:29:25,082 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:29:25 INFO - 2015-11-24 18:29:25,082 DEBUG : BROWSER_OUTPUT: __metrics 18:29:25 INFO - 2015-11-24 18:29:25,199 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:29:26 INFO - 2015-11-24 18:29:26,518 INFO : Browser initialized. 18:29:26 INFO - 2015-11-24 18:29:26,519 INFO : Running cycle 1/1 for kraken test... 18:29:26 INFO - 2015-11-24 18:29:26,519 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphkblWy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:29:38 INFO - 2015-11-24 18:29:38,388 DEBUG : BROWSER_OUTPUT: RSS: Main: 139595776 18:29:38 INFO - 2015-11-24 18:29:38,388 DEBUG : BROWSER_OUTPUT: 18:29:40 INFO - 2015-11-24 18:29:40,969 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45684/tests/kraken/driver.html?testName=audio-beat-detection) 18:29:41 INFO - 2015-11-24 18:29:41,550 DEBUG : BROWSER_OUTPUT: RSS: Main: 177115136 18:29:41 INFO - 2015-11-24 18:29:41,550 DEBUG : BROWSER_OUTPUT: 18:29:44 INFO - 2015-11-24 18:29:44,268 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45684/tests/kraken/driver.html?testName=audio-dft) 18:29:44 INFO - 2015-11-24 18:29:44,842 DEBUG : BROWSER_OUTPUT: RSS: Main: 166395904 18:29:44 INFO - 2015-11-24 18:29:44,842 DEBUG : BROWSER_OUTPUT: 18:29:47 INFO - 2015-11-24 18:29:47,855 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45684/tests/kraken/driver.html?testName=audio-fft) 18:29:48 INFO - 2015-11-24 18:29:48,417 DEBUG : BROWSER_OUTPUT: RSS: Main: 150667264 18:29:48 INFO - 2015-11-24 18:29:48,417 DEBUG : BROWSER_OUTPUT: 18:29:50 INFO - 2015-11-24 18:29:50,462 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45684/tests/kraken/driver.html?testName=audio-oscillator) 18:29:51 INFO - 2015-11-24 18:29:51,036 DEBUG : BROWSER_OUTPUT: RSS: Main: 152215552 18:29:51 INFO - 2015-11-24 18:29:51,036 DEBUG : BROWSER_OUTPUT: 18:29:53 INFO - 2015-11-24 18:29:53,250 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45684/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:29:53 INFO - 2015-11-24 18:29:53,832 DEBUG : BROWSER_OUTPUT: RSS: Main: 154595328 18:29:53 INFO - 2015-11-24 18:29:53,832 DEBUG : BROWSER_OUTPUT: 18:29:56 INFO - 2015-11-24 18:29:56,831 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45684/tests/kraken/driver.html?testName=imaging-darkroom) 18:29:57 INFO - 2015-11-24 18:29:57,404 DEBUG : BROWSER_OUTPUT: RSS: Main: 154890240 18:29:57 INFO - 2015-11-24 18:29:57,405 DEBUG : BROWSER_OUTPUT: 18:30:00 INFO - 2015-11-24 18:30:00,846 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45684/tests/kraken/driver.html?testName=imaging-desaturate) 18:30:01 INFO - 2015-11-24 18:30:01,429 DEBUG : BROWSER_OUTPUT: RSS: Main: 155324416 18:30:01 INFO - 2015-11-24 18:30:01,429 DEBUG : BROWSER_OUTPUT: 18:30:04 INFO - 2015-11-24 18:30:04,253 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45684/tests/kraken/driver.html?testName=json-parse-financial) 18:30:04 INFO - 2015-11-24 18:30:04,825 DEBUG : BROWSER_OUTPUT: RSS: Main: 155660288 18:30:04 INFO - 2015-11-24 18:30:04,825 DEBUG : BROWSER_OUTPUT: 18:30:06 INFO - 2015-11-24 18:30:06,545 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45684/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:30:07 INFO - 2015-11-24 18:30:07,112 DEBUG : BROWSER_OUTPUT: RSS: Main: 164265984 18:30:07 INFO - 2015-11-24 18:30:07,112 DEBUG : BROWSER_OUTPUT: 18:30:08 INFO - 2015-11-24 18:30:08,969 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45684/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:30:09 INFO - 2015-11-24 18:30:09,577 DEBUG : BROWSER_OUTPUT: RSS: Main: 165167104 18:30:09 INFO - 2015-11-24 18:30:09,577 DEBUG : BROWSER_OUTPUT: 18:30:11 INFO - 2015-11-24 18:30:11,698 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45684/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:30:12 INFO - 2015-11-24 18:30:12,279 DEBUG : BROWSER_OUTPUT: RSS: Main: 172875776 18:30:12 INFO - 2015-11-24 18:30:12,280 DEBUG : BROWSER_OUTPUT: 18:30:14 INFO - 2015-11-24 18:30:14,822 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45684/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:30:15 INFO - 2015-11-24 18:30:15,424 DEBUG : BROWSER_OUTPUT: RSS: Main: 179384320 18:30:15 INFO - 2015-11-24 18:30:15,424 DEBUG : BROWSER_OUTPUT: 18:30:18 INFO - 2015-11-24 18:30:18,043 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45684/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:30:18 INFO - 2015-11-24 18:30:18,624 DEBUG : BROWSER_OUTPUT: RSS: Main: 178561024 18:30:18 INFO - 2015-11-24 18:30:18,624 DEBUG : BROWSER_OUTPUT: 18:30:20 INFO - 2015-11-24 18:30:20,414 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45684/tests/kraken/driver.html?testName=ai-astar) 18:30:20 INFO - 2015-11-24 18:30:20,660 DEBUG : BROWSER_OUTPUT: RSS: Main: 238178304 18:30:20 INFO - 2015-11-24 18:30:20,661 DEBUG : BROWSER_OUTPUT: 18:30:20 INFO - 2015-11-24 18:30:20,664 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:30:20 INFO - 2015-11-24 18:30:20,664 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:30:20 INFO - 2015-11-24 18:30:20,664 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:30:20 INFO - 2015-11-24 18:30:20,664 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:30:20 INFO - 2015-11-24 18:30:20,665 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;114;106;112;111;113;102;111;111;104 18:30:20 INFO - 2015-11-24 18:30:20,665 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;152;130;158;178;139;155;149;131;166;130 18:30:20 INFO - 2015-11-24 18:30:20,665 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;162;162;161;160;161;160;164;161;163 18:30:20 INFO - 2015-11-24 18:30:20,665 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;82;92;72;73;73;95;91;72 18:30:20 INFO - 2015-11-24 18:30:20,665 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;94;129;94;94;93;135;95;94;138 18:30:20 INFO - 2015-11-24 18:30:20,665 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;109;110;109;109;110;109;110 18:30:20 INFO - 2015-11-24 18:30:20,665 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;147;147;147;148;149;149;149;147;148 18:30:20 INFO - 2015-11-24 18:30:20,666 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;96;96;96;95;95;95;95 18:30:20 INFO - 2015-11-24 18:30:20,666 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;71;70;70;70;71;70;71;71;71;70 18:30:20 INFO - 2015-11-24 18:30:20,666 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;69;68;68;69;68;68;68;68;68 18:30:20 INFO - 2015-11-24 18:30:20,666 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;82;82;84;82;83;92;84;84;83 18:30:20 INFO - 2015-11-24 18:30:20,666 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;127;128;122;119;125;124;128;121;126;121 18:30:20 INFO - 2015-11-24 18:30:20,666 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;151;138;148;147;150;148;149;148;152;137 18:30:20 INFO - 2015-11-24 18:30:20,666 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;71;69;68;67;68;85;71;67;68;68 18:30:20 INFO - 2015-11-24 18:30:20,666 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:30:20 INFO - 2015-11-24 18:30:20,667 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:30:20 INFO - 2015-11-24 18:30:20,667 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1004 18:30:20 INFO - 2015-11-24 18:30:20,667 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:30:20 INFO - 2015-11-24 18:30:20,667 DEBUG : BROWSER_OUTPUT: __startTimestamp1448418620662__endTimestamp 18:30:20 INFO - 2015-11-24 18:30:20,667 DEBUG : BROWSER_OUTPUT: 18:30:20 INFO - 2015-11-24 18:30:20,671 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:30:20 INFO - 2015-11-24 18:30:20,671 DEBUG : BROWSER_OUTPUT: Number of tests: 14 18:30:20 INFO - 2015-11-24 18:30:20,671 DEBUG : BROWSER_OUTPUT: 18:30:20 INFO - 2015-11-24 18:30:20,671 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:109.50 Median:111.00 stddev:4.03 (3.6%) stddev-sans-first:4.24 18:30:20 INFO - 2015-11-24 18:30:20,671 DEBUG : BROWSER_OUTPUT: Values: 111.0 114.0 106.0 112.0 111.0 113.0 102.0 111.0 111.0 104.0 18:30:20 INFO - 2015-11-24 18:30:20,671 DEBUG : BROWSER_OUTPUT: 18:30:20 INFO - 2015-11-24 18:30:20,671 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:148.80 Median:153.50 stddev:16.34 (10.6%) stddev-sans-first:17.29 18:30:20 INFO - 2015-11-24 18:30:20,671 DEBUG : BROWSER_OUTPUT: Values: 152.0 130.0 158.0 178.0 139.0 155.0 149.0 131.0 166.0 130.0 18:30:20 INFO - 2015-11-24 18:30:20,671 DEBUG : BROWSER_OUTPUT: 18:30:20 INFO - 2015-11-24 18:30:20,671 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:1.26 (0.8%) stddev-sans-first:1.33 18:30:20 INFO - 2015-11-24 18:30:20,671 DEBUG : BROWSER_OUTPUT: Values: 162.0 162.0 162.0 161.0 160.0 161.0 160.0 164.0 161.0 163.0 18:30:20 INFO - 2015-11-24 18:30:20,671 DEBUG : BROWSER_OUTPUT: 18:30:20 INFO - 2015-11-24 18:30:20,671 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.40 Median:77.50 stddev:9.69 (12.5%) stddev-sans-first:9.90 18:30:20 INFO - 2015-11-24 18:30:20,671 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 82.0 92.0 72.0 73.0 73.0 95.0 91.0 72.0 18:30:20 INFO - 2015-11-24 18:30:20,671 DEBUG : BROWSER_OUTPUT: 18:30:20 INFO - 2015-11-24 18:30:20,672 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.00 Median:94.00 stddev:19.45 (20.7%) stddev-sans-first:20.14 18:30:20 INFO - 2015-11-24 18:30:20,672 DEBUG : BROWSER_OUTPUT: Values: 94.0 94.0 129.0 94.0 94.0 93.0 135.0 95.0 94.0 138.0 18:30:20 INFO - 2015-11-24 18:30:20,672 DEBUG : BROWSER_OUTPUT: 18:30:20 INFO - 2015-11-24 18:30:20,672 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.60 Median:110.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 18:30:20 INFO - 2015-11-24 18:30:20,672 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 109.0 110.0 109.0 109.0 110.0 109.0 110.0 18:30:20 INFO - 2015-11-24 18:30:20,672 DEBUG : BROWSER_OUTPUT: 18:30:20 INFO - 2015-11-24 18:30:20,672 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 18:30:20 INFO - 2015-11-24 18:30:20,672 DEBUG : BROWSER_OUTPUT: Values: 147.0 147.0 147.0 147.0 148.0 149.0 149.0 149.0 147.0 148.0 18:30:20 INFO - 2015-11-24 18:30:20,672 DEBUG : BROWSER_OUTPUT: 18:30:20 INFO - 2015-11-24 18:30:20,672 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 18:30:20 INFO - 2015-11-24 18:30:20,672 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 96.0 96.0 96.0 95.0 95.0 95.0 95.0 18:30:20 INFO - 2015-11-24 18:30:20,672 DEBUG : BROWSER_OUTPUT: 18:30:20 INFO - 2015-11-24 18:30:20,672 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:70.50 Median:71.00 stddev:0.53 (0.7%) stddev-sans-first:0.53 18:30:20 INFO - 2015-11-24 18:30:20,672 DEBUG : BROWSER_OUTPUT: Values: 71.0 70.0 70.0 70.0 71.0 70.0 71.0 71.0 71.0 70.0 18:30:20 INFO - 2015-11-24 18:30:20,672 DEBUG : BROWSER_OUTPUT: 18:30:20 INFO - 2015-11-24 18:30:20,673 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.20 Median:68.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 18:30:20 INFO - 2015-11-24 18:30:20,673 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 68.0 68.0 69.0 68.0 68.0 68.0 68.0 68.0 18:30:20 INFO - 2015-11-24 18:30:20,673 DEBUG : BROWSER_OUTPUT: 18:30:20 INFO - 2015-11-24 18:30:20,673 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:83.50 stddev:2.96 (3.5%) stddev-sans-first:3.12 18:30:20 INFO - 2015-11-24 18:30:20,673 DEBUG : BROWSER_OUTPUT: Values: 83.0 82.0 82.0 84.0 82.0 83.0 92.0 84.0 84.0 83.0 18:30:20 INFO - 2015-11-24 18:30:20,673 DEBUG : BROWSER_OUTPUT: 18:30:20 INFO - 2015-11-24 18:30:20,673 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:124.10 Median:125.50 stddev:3.21 (2.6%) stddev-sans-first:3.23 18:30:20 INFO - 2015-11-24 18:30:20,673 DEBUG : BROWSER_OUTPUT: Values: 127.0 128.0 122.0 119.0 125.0 124.0 128.0 121.0 126.0 121.0 18:30:20 INFO - 2015-11-24 18:30:20,673 DEBUG : BROWSER_OUTPUT: 18:30:20 INFO - 2015-11-24 18:30:20,673 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.80 Median:148.50 stddev:5.14 (3.5%) stddev-sans-first:5.22 18:30:20 INFO - 2015-11-24 18:30:20,673 DEBUG : BROWSER_OUTPUT: Values: 151.0 138.0 148.0 147.0 150.0 148.0 149.0 148.0 152.0 137.0 18:30:20 INFO - 2015-11-24 18:30:20,673 DEBUG : BROWSER_OUTPUT: 18:30:20 INFO - 2015-11-24 18:30:20,674 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:68.50 stddev:5.39 (7.9%) stddev-sans-first:5.71 18:30:20 INFO - 2015-11-24 18:30:20,674 DEBUG : BROWSER_OUTPUT: Values: 71.0 69.0 68.0 67.0 68.0 85.0 71.0 67.0 68.0 68.0 18:30:20 INFO - 2015-11-24 18:30:20,674 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:30:20 INFO - 2015-11-24 18:30:20,674 DEBUG : BROWSER_OUTPUT: 18:30:21 INFO - 2015-11-24 18:30:21,560 INFO : Browser exited with error code: 0 18:30:21 INFO - 2015-11-24 18:30:21,608 INFO : Completed test kraken (00:01:00) 18:30:21 INFO - 2015-11-24 18:30:21,609 INFO : Starting test v8_7 18:30:21 INFO - 2015-11-24 18:30:21,609 DEBUG : operating with platform_type : linux_ 18:30:21 INFO - 2015-11-24 18:30:21,609 INFO : Initialising browser for v8_7 test... 18:30:21 INFO - 2015-11-24 18:30:21,615 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJN6Nfa/profile http://localhost:45684/getInfo.html 18:30:25 INFO - 2015-11-24 18:30:25,935 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:30:25 INFO - 2015-11-24 18:30:25,935 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:30:25 INFO - 2015-11-24 18:30:25,935 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:30:25 INFO - 2015-11-24 18:30:25,935 DEBUG : BROWSER_OUTPUT: __metrics 18:30:26 INFO - 2015-11-24 18:30:26,133 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:30:27 INFO - 2015-11-24 18:30:27,386 INFO : Browser initialized. 18:30:27 INFO - 2015-11-24 18:30:27,386 INFO : Running cycle 1/1 for v8_7 test... 18:30:27 INFO - 2015-11-24 18:30:27,386 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJN6Nfa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:30:39 INFO - 2015-11-24 18:30:39,288 DEBUG : BROWSER_OUTPUT: RSS: Main: 139177984 18:30:39 INFO - 2015-11-24 18:30:39,288 DEBUG : BROWSER_OUTPUT: 18:31:00 INFO - 2015-11-24 18:31:00,763 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45684/tests/v8_7/run.html (next: http://localhost:45684/tests/v8_7/run.html) 18:31:01 INFO - 2015-11-24 18:31:01,012 DEBUG : BROWSER_OUTPUT: RSS: Main: 439111680 18:31:01 INFO - 2015-11-24 18:31:01,013 DEBUG : BROWSER_OUTPUT: 18:31:01 INFO - 2015-11-24 18:31:01,014 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:31:01 INFO - 2015-11-24 18:31:01,014 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:31:01 INFO - 2015-11-24 18:31:01,014 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:31:01 INFO - 2015-11-24 18:31:01,015 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:31:01 INFO - 2015-11-24 18:31:01,016 DEBUG : BROWSER_OUTPUT: |0;Richards;166.55562958027983 18:31:01 INFO - 2015-11-24 18:31:01,016 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;191.35093761959433 18:31:01 INFO - 2015-11-24 18:31:01,016 DEBUG : BROWSER_OUTPUT: |2;Encrypt;276.7783005812344 18:31:01 INFO - 2015-11-24 18:31:01,016 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5122.448979591837 18:31:01 INFO - 2015-11-24 18:31:01,016 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1962.7450980392157 18:31:01 INFO - 2015-11-24 18:31:01,017 DEBUG : BROWSER_OUTPUT: |5;Earley;802.5682182985554 18:31:01 INFO - 2015-11-24 18:31:01,017 DEBUG : BROWSER_OUTPUT: |6;Boyer;12231.707317073171 18:31:01 INFO - 2015-11-24 18:31:01,017 DEBUG : BROWSER_OUTPUT: |7;RegExp;26500 18:31:01 INFO - 2015-11-24 18:31:01,017 DEBUG : BROWSER_OUTPUT: |8;Splay;599.1661703394877 18:31:01 INFO - 2015-11-24 18:31:01,017 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 18:31:01 INFO - 2015-11-24 18:31:01,017 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:31:01 INFO - 2015-11-24 18:31:01,018 DEBUG : BROWSER_OUTPUT: __startTimestamp1448418661014__endTimestamp 18:31:01 INFO - 2015-11-24 18:31:01,018 DEBUG : BROWSER_OUTPUT: 18:31:01 INFO - 2015-11-24 18:31:01,018 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:31:01 INFO - 2015-11-24 18:31:01,018 DEBUG : BROWSER_OUTPUT: Number of tests: 10 18:31:01 INFO - 2015-11-24 18:31:01,019 DEBUG : BROWSER_OUTPUT: 18:31:01 INFO - 2015-11-24 18:31:01,019 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.56 Median:166.56 stddev:0.00 (0.0%) 18:31:01 INFO - 2015-11-24 18:31:01,019 DEBUG : BROWSER_OUTPUT: Values: 166.6 18:31:01 INFO - 2015-11-24 18:31:01,019 DEBUG : BROWSER_OUTPUT: 18:31:01 INFO - 2015-11-24 18:31:01,019 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:191.35 Median:191.35 stddev:0.00 (0.0%) 18:31:01 INFO - 2015-11-24 18:31:01,019 DEBUG : BROWSER_OUTPUT: Values: 191.4 18:31:01 INFO - 2015-11-24 18:31:01,020 DEBUG : BROWSER_OUTPUT: 18:31:01 INFO - 2015-11-24 18:31:01,021 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:276.78 Median:276.78 stddev:0.00 (0.0%) 18:31:01 INFO - 2015-11-24 18:31:01,021 DEBUG : BROWSER_OUTPUT: Values: 276.8 18:31:01 INFO - 2015-11-24 18:31:01,021 DEBUG : BROWSER_OUTPUT: 18:31:01 INFO - 2015-11-24 18:31:01,021 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5122.45 Median:5122.45 stddev:0.00 (0.0%) 18:31:01 INFO - 2015-11-24 18:31:01,022 DEBUG : BROWSER_OUTPUT: Values: 5122.4 18:31:01 INFO - 2015-11-24 18:31:01,022 DEBUG : BROWSER_OUTPUT: 18:31:01 INFO - 2015-11-24 18:31:01,022 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1962.75 Median:1962.75 stddev:0.00 (0.0%) 18:31:01 INFO - 2015-11-24 18:31:01,022 DEBUG : BROWSER_OUTPUT: Values: 1962.7 18:31:01 INFO - 2015-11-24 18:31:01,022 DEBUG : BROWSER_OUTPUT: 18:31:01 INFO - 2015-11-24 18:31:01,022 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:802.57 Median:802.57 stddev:0.00 (0.0%) 18:31:01 INFO - 2015-11-24 18:31:01,022 DEBUG : BROWSER_OUTPUT: Values: 802.6 18:31:01 INFO - 2015-11-24 18:31:01,023 DEBUG : BROWSER_OUTPUT: 18:31:01 INFO - 2015-11-24 18:31:01,023 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12231.71 Median:12231.71 stddev:0.00 (0.0%) 18:31:01 INFO - 2015-11-24 18:31:01,023 DEBUG : BROWSER_OUTPUT: Values: 12231.7 18:31:01 INFO - 2015-11-24 18:31:01,023 DEBUG : BROWSER_OUTPUT: 18:31:01 INFO - 2015-11-24 18:31:01,023 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26500.00 Median:26500.00 stddev:0.00 (0.0%) 18:31:01 INFO - 2015-11-24 18:31:01,023 DEBUG : BROWSER_OUTPUT: Values: 26500.0 18:31:01 INFO - 2015-11-24 18:31:01,023 DEBUG : BROWSER_OUTPUT: 18:31:01 INFO - 2015-11-24 18:31:01,023 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:599.17 Median:599.17 stddev:0.00 (0.0%) 18:31:01 INFO - 2015-11-24 18:31:01,024 DEBUG : BROWSER_OUTPUT: Values: 599.2 18:31:01 INFO - 2015-11-24 18:31:01,024 DEBUG : BROWSER_OUTPUT: 18:31:01 INFO - 2015-11-24 18:31:01,024 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 18:31:01 INFO - 2015-11-24 18:31:01,024 DEBUG : BROWSER_OUTPUT: Values: 4830.9 18:31:01 INFO - 2015-11-24 18:31:01,024 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:31:01 INFO - 2015-11-24 18:31:01,024 DEBUG : BROWSER_OUTPUT: 18:31:01 INFO - 2015-11-24 18:31:01,924 INFO : Browser exited with error code: 0 18:31:01 INFO - 2015-11-24 18:31:01,938 INFO : Completed test v8_7 (00:00:40) 18:31:01 INFO - 2015-11-24 18:31:01,982 INFO : Completed test suite (00:20:18) 18:31:01 INFO - 2015-11-24 18:31:01,982 DEBUG : Working with test: dromaeo_css 18:31:01 INFO - 2015-11-24 18:31:01,982 DEBUG : Generating results file: dromaeo_css 18:31:01 INFO - 2015-11-24 18:31:01,983 DEBUG : Working with test: kraken 18:31:01 INFO - 2015-11-24 18:31:01,983 DEBUG : Generating results file: kraken 18:31:01 INFO - 2015-11-24 18:31:01,984 INFO : javascript benchmark 18:31:01 INFO - 2015-11-24 18:31:01,984 DEBUG : Working with test: v8_7 18:31:01 INFO - 2015-11-24 18:31:01,984 DEBUG : Generating results file: v8_7 18:31:01 INFO - 2015-11-24 18:31:01,984 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:31:02 INFO - 2015-11-24 18:31:02,136 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,94,35]] 18:31:02 INFO - 2015-11-24 18:31:02,136 DEBUG : process_Request line: dromaeo_css 6277.50 graph.html#tests=[[72,94,35]] 18:31:02 INFO - 2015-11-24 18:31:02,137 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:31:02 INFO - 2015-11-24 18:31:02,279 DEBUG : process_Request line: kraken 1522.00 graph.html#tests=[[232,94,35]] 18:31:02 INFO - 2015-11-24 18:31:02,279 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:31:02 INFO - 2015-11-24 18:31:02,311 DEBUG : process_Request line: v8_7 19812.68 graph.html#tests=[[230,94,35]] 18:31:02 INFO - 2015-11-24 18:31:02,316 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2562.2057162279693, 2673.9598906016413, 2674.893004279829, 2683.0072873036975, 2685.4763321152373, 4504.549545045494, 4563.201439748039, 4589.059242855215, 4633.050224703936, 4634.281686338623, 4552.339910712944, 4557.430321209848, 4616.169089563878, 4642.07158568286, 4654.092451971858, 4250.8087136078875, 4276.743395396271, 4282.828686852522, 4337.436133302682, 4345.152695224432, 4327.069680018795, 4339.804708788112, 4370.2133615949115, 4372.12557488502, 4405.801738921757, 4123.505179378464, 4151.252774500589, 4217.924429270516, 4316.158349121938, 4356.172327257811, 4254.7021708480415, 4283.764392958386, 4295.140971805651, 4301.5698430157045, 4325.956740432618, 4269.252880745857, 4280.486341638992, 4282.96943231441, 4292.141571685675, 4322.546132656089, 4312.05134870328, 4435.356873253392, 4452.554744525554, 4590.036092420596, 4613.630909527264, 4805.038992201538, 4843.58829499493, 4864.537868902448, 4866.902661946741, 4900.828471003498, 4909.607231421478, 4915.434725006634, 4923.261510773377, 4924.285978533093, 4948.505149485059, 4354.259775838115, 4443.555644435531, 4454.086912182995, 4563.247064234397, 4604.769761511944, 4976.477469865685, 5068.366454193226, 5071.670341427795, 5104.974475127675, 5121.231815227746, 4869.829555965524, 4895.412550493964, 4897.804087836518, 4899.951000490021, 4945.55490005894, 4708.293755936568, 4802.375691160127, 4803.03939212159, 4961.280614310905, 4987.975060124676, 4799.256115302133, 4846.660733748674, 4865.70805751656, 4867.148249056472, 4916.360873086476, 4895.975520122448, 4908.2637604358915, 4910.288008238786, 4911.533404326583, 4922.483139270396, 3923.7449565778134, 4005.7396269242404, 4007.499062617173, 4097.508299004138, 4125.66994640428, 3524.7356448266482, 3550.2899420115564, 3607.9278414431565, 3617.5839778425293, 3638.599754027055, 4251.851185208503, 4281.100968796525, 4290.2706539888295, 4315.784210789448, 4319.3952846601505, 4750.8337208197545, 4950.9009819803405, 4960.727160006234, 4966.602671786249, 5006.549410553068, 4194.30793919006, 4201.159768046403, 4261.0, 4271.5941985511945, 4296.484421869333, 4310.43964284641, 4343.761093139908, 4391.494978077498, 4391.802368893407, 4433.7561434121435, 4498.662600304925, 4733.5266473352385, 4735.692180008288, 4736.076465089274, 4736.076465089274, 4834.7824347903725, 4834.903301934012, 4855.465898751064, 4886.973735515581, 4919.975400122977, 4611.446626404853, 4629.884252893705, 4630.490646029001, 4633.467151277646, 4675.275332323496, 4650.86047418578, 4804.927926081182, 4806.47128815823, 4808.711477311233, 4849.151398505177, 4845.854624361275, 4878.243872199815, 4885.926711099265, 4912.582430493305, 4945.085159245551, 4772.093302272696, 4806.615470762261, 4808.447028591757, 4812.989272252726, 4851.708897466164, 4590.609798167193, 4783.306420569068, 4822.9758851205515, 4826.975865120652, 4866.026794641015, 4594.517575654641, 4652.581267685924, 4665.066986602626, 4690.554397332385, 4695.3191787191345, 4834.5890599298045, 4868.707877527359, 4879.072976134665, 4884.023195360871, 4884.194107972145, 4742.0, 4762.571368576845, 4770.737609431376, 4781.354517140142, 4794.616430685606], "unit": "ms", "name": "dojo.html", "value": 4513.205355488514}, {"lowerIsBetter": false, "replicates": [551.0769461152571, 557.9944200557993, 578.0721941284239, 580.5297708855826, 587.9617824841386, 28045.859770701143, 28701.856490717542, 36499.27001459972, 37019.259614807685, 37078.0, 40695.168717407716, 41180.38229426561, 41256.38115428271, 41266.0, 41283.79358103213, 6936.965315173417, 7885.960570197141, 8044.356451483867, 8047.798805029892, 8049.718259860906, 7050.330218629222, 7140.428765698732, 7141.571505709648, 7157.105361829771, 10110.24173187014, 10005.949970250138, 10052.888893332409, 10086.193104551656, 10105.646302379419, 10108.039736225046, 10048.397096174216, 10129.290949633529, 10130.848037279447, 10138.9493052535, 10158.796824063515, 7111.53775004623, 7321.341079302888, 10068.14420774235, 10365.585376584928, 10378.636747713794, 31486.89795857134, 31522.684773152203, 33196.0, 33222.3355532888, 34081.48877766835, 2892.363679990399, 2900.5214139666928, 2957.0, 3496.930061398758, 3526.559180102487, 28632.14103576896, 28669.85665071682, 28896.133116006553, 29404.411911761857, 29408.852955735085, 3352.5306457096026, 3424.9657503424896, 3509.0700964244497, 3522.172289511963, 3564.8930532084078, 219456.0, 220207.9999999984, 220872.9999999984, 221409.0, 221984.0, 50956.49043509518, 52216.73891630518, 61862.69068654718, 62743.6862815683, 63057.0, 56789.0, 56914.146287805714, 57027.0, 57069.14396283976, 57100.0, 13431.194128352136, 13586.0, 13632.522861699794, 13647.454101836014, 13657.931710341583, 9288.767780805534, 9337.972822989466, 9362.95318523403, 9432.905670943202, 9457.716268511957, 18938.14778334978, 19268.710969335472, 19317.130729117223, 19325.226990920488, 19358.612827743367, 7514.6618402172035, 7666.65500052499, 7766.145723970359, 7796.883046754303, 7802.726904558313, 816.6733306677257, 851.2294427590597, 868.8566386546149, 925.9444433334022, 956.6460409648336, 30100.548491772643, 30438.84780576083, 30987.0, 31096.22259443532, 31276.0, 885.9689910853218, 914.8947870994921, 916.7662246127328, 926.1710768861831, 934.8270569944445, 13604.619191641423, 13620.0, 13711.45154193841, 13756.105853119516, 13819.309034548232, 334114.0, 339420.0, 340123.0, 342244.0, 342792.0, 46427.76786116048, 46902.296465553045, 46919.5308046922, 46950.5304946953, 46995.0, 1035.803197392493, 1123.9887601123885, 1127.616610352484, 1143.274020996673, 1230.7169351049129, 25316.873415632803, 26607.0, 26880.32799179958, 26946.191614251602, 27188.864055679594, 37493.0, 38009.61990380061, 38074.80962595169, 38083.238335232585, 38106.809465952494, 223395.0, 227237.0, 347889.0, 355326.0, 357129.0, 29339.41321173522, 29476.705232947395, 29504.0, 29530.852345738134, 29530.96641617533, 37967.430488542144, 39037.609623903394, 39241.411378829915, 39400.40899386454, 39504.60495395009, 32409.277704256063, 32735.18162045968, 33027.504587431686, 33360.0, 33713.66286337105, 33206.16984575405, 33327.50008749822, 33556.825511106996, 33666.0, 33763.15592110217, 32714.34571308608, 32777.672223278416, 32856.178595534344, 33098.83450582732, 33184.33631327312, 28264.85867570649, 29854.70145298519, 32394.514082289257, 35073.29853402867, 35133.297334053685, 23572.780612372128, 23721.406964826016, 23743.881280593487, 23808.0, 24186.75813241845], "unit": "ms", "name": "ext.html", "value": 17310.758786180413}, {"lowerIsBetter": false, "replicates": [327.56433942856006, 334.78239144556034, 335.98075565849246, 337.2327953904866, 347.24127780798955, 12428.689282767935, 12684.175528590635, 14832.629184270385, 15091.773623395658, 15715.764263536043, 12592.81110783339, 15094.54716358511, 15478.380864765395, 15857.841421585781, 15872.523824285261, 4881.292212629175, 4885.291632713254, 5070.8732281693065, 5076.060928728179, 5085.847424577269, 3620.8732694355704, 3752.8686495972647, 4999.900001999958, 5609.831705048855, 5739.885202295952, 5629.60592758507, 5646.124850648135, 5661.631993920402, 5698.914516282259, 5707.571932105108, 5530.972345138269, 5773.162891380726, 5789.247397838254, 5799.217105690741, 5816.534677225812, 5102.719350435762, 5108.310378098946, 5111.335526381584, 5125.769340379692, 5128.410232823236, 19617.9019104905, 19886.90056549708, 20203.797962020337, 20237.291694790758, 20523.794762052337, 1843.446965910235, 1906.494778883597, 1927.026851440028, 1954.0718158874422, 1973.753280839895, 13255.801162982467, 13318.134321269086, 13344.466221351135, 13431.261280629562, 13641.249731264776, 1860.5348662834292, 1865.5615930256379, 1878.549148204421, 1898.2881419467699, 1943.893085880276, 54676.726616366665, 55691.72154139244, 55971.44028559703, 62477.37522624761, 62947.05579416313, 14992.70014599702, 15098.094114352954, 15099.849001510063, 15133.924330378277, 15139.243037848064, 22443.21448749286, 22588.887055564617, 22625.547489050456, 22725.43186420353, 22740.65889011666, 7768.067831860213, 7798.8050298741855, 7807.258310460497, 7832.255935686215, 7832.451728378959, 6348.904766428507, 6393.648349340738, 6394.1687580614225, 6439.742410303629, 6444.033394990789, 7148.46386521013, 7278.745243916544, 7285.635718214068, 7289.214142535006, 7295.781126566211, 1740.8781385302907, 1748.2395158106265, 1766.222861940742, 2124.447643612672, 2825.307799589114, 377.4395023390263, 381.16144482139407, 845.9915400846036, 950.7480517662686, 964.8745663063897, 9726.076022777825, 10020.34867733595, 14825.332860021324, 15659.451919183002, 15859.52421427359, 858.9484630922165, 870.1907226279494, 886.8049029213691, 906.419891269588, 927.5084205371164, 7561.243875612395, 8661.653533858702, 8992.100789920956, 9061.77345566353, 9063.728088157366, 448529.0, 459951.0, 473054.0, 478749.0, 482541.0, 69763.3023669767, 70303.0, 70532.0, 70983.0, 71002.64498677576, 1039.443897514826, 1091.661584908665, 1102.8566286382718, 1105.1876870500175, 1116.7319843237565, 13360.732785344433, 13660.590182294547, 14041.719165616427, 14225.359858806585, 14545.781813272597, 30719.846400768747, 31849.044528664177, 31961.68038319587, 32074.83962580172, 32840.50739238961, 42734.358984614635, 42950.78524607357, 43025.7848710767, 44465.55534444614, 45216.32175517304, 8493.131511067555, 8828.161324674145, 9008.144226298491, 9014.954925225333, 9080.364374493722, 10270.229732770222, 10324.174066074846, 10380.688579342632, 11890.524379024742, 15862.127582982584, 12365.443555040225, 12385.275438491866, 12604.054695897661, 12922.095453318178, 13157.27634980047, 12388.699186585278, 12430.627081187578, 13219.603411897659, 13274.137181083199, 13308.201507909556, 9063.68277110298, 9086.545672716205, 9471.10024547667, 9476.810463790824, 9974.750631234278, 11648.068154547786, 11713.648590542298, 11723.35521546323, 13015.088943773844, 13500.32498375097, 15072.24638768079, 15096.773548396563, 15467.767983480031, 15643.765343520085, 15680.92159539195], "unit": "ms", "name": "jquery.html", "value": 8686.761368484253}, {"lowerIsBetter": false, "replicates": [1325.952497526953, 1380.2408675228621, 1398.7692030814917, 1552.953411397658, 1626.3819748495566, 5828.650280983139, 6126.356732543086, 6599.406053455188, 6854.520183587151, 6876.724931002754, 6858.211305699846, 6874.175098988116, 6877.105976223096, 6918.066061081752, 6919.273476284993, 1729.4725108841799, 2023.8077382648626, 2059.680749483832, 2060.9072591733407, 2061.021015017862, 1779.9288028478845, 2045.8670186437907, 2090.341542414135, 2092.550101728128, 2092.665173572229, 1999.720039194514, 2028.1279050008563, 2029.7056926745613, 2034.6337659221335, 2039.826614737749, 2019.3437132931856, 2042.5097976485686, 2043.5606344635826, 2043.6014977079476, 2047.744031996008, 2033.644112280345, 2081.7918208179212, 2082.3856962196187, 2082.9479263018393, 2083.656196727538, 6826.93173068268, 6870.106886104774, 6901.0, 6902.102726645554, 6925.792226233221, 4061.3704875744306, 4098.344264917599, 4126.66986641068, 4127.979360103181, 4139.482564679415, 2731.8634068296706, 2748.7663548598393, 2754.2838861895852, 2771.4041481081663, 2786.0527420676863, 851.5146366571057, 886.406107907697, 889.2397000564554, 891.0599317719802, 899.2581120575551, 8914.28685705142, 8962.37263391563, 8979.326550508738, 8984.550772461416, 8989.056149104315, 3730.7015438764834, 3773.584905660375, 3835.060410199519, 3958.6041395860184, 3961.603839616015, 7880.881786773203, 7907.299930514851, 7950.204979502004, 7962.840743185104, 7970.561619111005, 4194.6853985951175, 4208.347706105497, 4210.873673789792, 4211.052513184571, 4222.746635201836, 3834.2906562286066, 3862.246861861966, 3867.16855875988, 3882.4176373544196, 3888.572257051722, 2751.6285301484045, 2784.276088217038, 3364.7139993100373, 3397.2356219850335, 3404.557407537004, 1062.6227689170387, 1062.9750325629689, 1073.8711354637337, 1080.8756992945755, 1083.285031878956, 739.5230076600562, 739.7041183526633, 740.5667684404576, 758.9165191828895, 761.5392687424139, 6342.809715708444, 6412.39082287182, 6421.1652485177565, 6422.710978006001, 6429.067785171124, 996.5067291690659, 997.4165113408737, 1015.0864222200079, 1015.9847602285971, 1016.5781200801732, 4852.95147048525, 4932.309476673268, 4986.376702912136, 4987.800487980513, 4992.425871024879, 7678.500897441649, 7805.258500442449, 7894.802629934183, 7899.0, 7942.920570794218, 7768.65041073153, 7836.098848632366, 7901.130875603679, 7921.960390198012, 7925.445218834626, 6108.083787431832, 6144.723487443076, 6152.477039451696, 6154.0, 6154.692265386713, 5941.494972927349, 6134.447899689049, 6334.778282760081, 6341.429271365415, 6359.55483116196, 7263.963680181777, 7326.816829579303, 7398.630068496446, 7464.216257293123, 7482.625868706652, 7361.558306501627, 7529.962350188214, 7569.735059273119, 7589.316961473494, 7597.088349398107, 5906.143609176559, 5977.790777322773, 5981.700914954322, 5995.970020149872, 5997.190379298913, 5328.813491527778, 5640.2529688150125, 6446.548741588043, 6491.448226900767, 6516.967415163083, 5488.69812160335, 5664.660120392789, 5814.331351894586, 6123.050927106306, 6137.693115344304, 5901.704914754331, 5910.408959104056, 5932.584719069624, 6000.069989151689, 6002.849928751816, 5916.674582897982, 5924.7037648118285, 5928.110783382527, 5944.910826337695, 5945.227120474311, 5522.972385138049, 5523.944760552343, 5526.640768350045, 5537.446255374431, 5549.389567147691, 5441.727913604224, 5523.8895222094525, 5677.517411019944, 5712.485876271156, 5718.656880587178], "unit": "ms", "name": "mootools.html", "value": 3887.684868784}, {"lowerIsBetter": false, "replicates": [304.89023951377504, 309.1421305876193, 311.7895420591099, 312.8717225937366, 313.5610145795884, 5225.477452254778, 5299.60252981026, 5303.310569625948, 5303.787848486066, 5306.389765177006, 5037.622178336621, 5122.897542049166, 5140.306058682077, 5254.921176182351, 5264.947350526493, 4125.979370103145, 4140.296149654566, 4142.64787493062, 4153.896152596187, 4167.187398457302, 3902.2975864344407, 4149.419081328617, 4158.397032430295, 4161.583841615829, 4163.20899029185, 3949.111449923773, 3997.860074897379, 4069.1861627674584, 4076.9592304077023, 4088.2232375848644, 4112.09533902541, 4112.321466957948, 4119.567445418248, 4120.608542188503, 4121.093359460914, 3657.65252301031, 3660.5973342932257, 3660.963390366089, 3662.487251784752, 3669.101070237782, 5038.0, 5044.722540260284, 5049.6212784041345, 5052.216906379517, 5056.2162864755655, 4527.071950250191, 4654.674172807905, 4656.161890859644, 4679.74261415622, 4732.455767586737, 5500.779968801243, 5508.476694713996, 5508.779648814043, 5509.752061157257, 5511.53151982082, 3864.188520410717, 3865.690744740414, 3867.806609669505, 3868.32304346741, 3869.729118961674, 5728.341240757324, 5789.102689083199, 5793.797217097382, 5796.13058041293, 5801.767929282866, 3910.5145413870246, 3967.3652215645934, 3994.400839874042, 4008.6392224699916, 4015.8996025099605, 4051.716379853441, 4057.6145266199665, 4058.7564746115327, 4059.086705491241, 4061.411095391211, 4634.559716826896, 4635.95364046362, 4637.976810115928, 4639.396878405853, 4640.257558790578, 5529.612927095065, 5533.3083364579425, 5533.474319939599, 5540.196671482612, 5543.944560554423, 1387.7085811979598, 1412.5621057472217, 1436.6408397900525, 1440.1359184489222, 1440.8415074341815, 953.2755106119399, 960.1358777100526, 966.6085235479642, 967.3132076225818, 967.5307475874196, 4744.691595046311, 4807.807687692453, 4809.591184749265, 4811.566958973709, 4815.6388270879825, 5636.943630563642, 5638.4925356718095, 5643.915341269884, 5645.842602266525, 5650.208970744099, 4724.811007559659, 4784.282357646381, 4795.424549054136, 4844.733539655353, 4928.556429921325, 3083.2137804859617, 3089.7682673799554, 3096.2723759916403, 3117.9376412471624, 3119.939220664985, 5486.341639003345, 5489.149181876815, 5492.862678433071, 5495.917561236584, 5495.945040549544, 5778.508826749773, 5935.703214839241, 5935.821925342247, 5940.881182376328, 5947.04847224442, 5491.478309560585, 5503.477169668875, 5511.0080185566185, 5513.889722205614, 5514.751836167382, 5717.313922329347, 5765.884682306414, 5783.537317014713, 5790.334111577223, 5791.160281759206, 5659.603827732184, 5661.830145095653, 5670.4329567044615, 5670.829875103754, 5671.120976248688, 5366.812161574326, 5449.945500545103, 5492.203630473479, 5495.175723641326, 5500.972495137498, 3978.542467616261, 3982.183652351203, 3988.062805240824, 3990.620891015349, 3992.0818211812025, 5748.0, 5750.856228594319, 5754.108113242454, 5754.7122643866805, 5759.164921086335, 5578.581606379456, 5579.13523403873, 5580.469855363734, 5585.273914391103, 5586.301712285965, 5390.541803946709, 5521.779128834802, 5580.38615752275, 5606.607537472338, 5607.719614019365, 5447.128459446628, 5458.754356054066, 5463.754131064191, 5470.097433923358, 5491.945080549143, 5635.802746903839, 5636.464535869086, 5638.097904335286, 5638.097904335286, 5642.5485961122195, 5380.246765452762, 5492.5605951524585, 5500.28496295495, 5502.119660854404, 5502.202180683858], "unit": "ms", "name": "prototype.html", "value": 4176.559234592995}, {"lowerIsBetter": false, "replicates": [634.8825467288555, 650.3074225949364, 697.0972590495309, 762.192076399017, 773.4083426178972, 11674.416279186038, 11913.285202887833, 11931.821022684666, 11933.16467847251, 11939.58211462599, 11470.25443346182, 12539.31033793141, 12717.682057948556, 12720.109592328541, 12722.173058751174, 600.9879802403949, 756.8183635927365, 771.3058247577197, 771.5872008475467, 771.6836097200149, 607.5716619783065, 758.5183408535592, 773.8490994256123, 776.770852598482, 777.6461709921972, 790.411143697943, 804.3324041045947, 804.5092493578942, 804.7867315161458, 809.8299357135008, 626.8683576448951, 635.7997230872261, 777.7511196417149, 791.5369508837337, 797.0475282037967, 583.0292562882795, 583.1777194156261, 585.0259318235134, 586.1676419484343, 587.3363099697358, 11814.113941454425, 11833.704157396134, 11875.0499960003, 11910.821337679941, 11934.820977685342, 10467.738306542322, 10990.395528245943, 11218.719532011683, 11343.716407089889, 11466.713332166679, 9287.0, 9293.210077143383, 9612.903870961341, 9756.339438896412, 9887.505624718808, 3167.8099314041233, 3223.6239901301333, 3234.951475727866, 3246.318273162639, 3327.8169700666454, 41465.378019329735, 41927.790361048, 42222.57777422217, 42310.0, 42594.78702606498, 9016.504092274989, 9116.4074335168, 9139.63441462334, 9220.0, 9249.213816825511, 15856.682866342839, 15974.68050639004, 16043.278052487665, 16063.919680401523, 16087.0, 6290.937090629035, 6453.967730161319, 6520.706568204442, 6571.934280657228, 6573.3098024706715, 3551.058969373118, 3575.4636804479014, 3624.7643903146213, 3672.2839046385698, 3703.9814800925824, 2655.269800804794, 2685.650865387526, 2686.0, 2687.87904544296, 2691.6231727558156, 478.62906247658344, 497.25908396489694, 501.4408934038521, 506.76435457512434, 508.8829569199105, 476.39973633221695, 498.0810404803132, 514.9021685879671, 529.282821776495, 539.1535289595377, 7417.517861338995, 7429.591372474566, 7456.888146677804, 7481.102267727908, 7718.498297610638, 366.3753300622432, 439.94500687414074, 512.1421618788514, 529.933758280215, 558.1460365640577, 3381.746369022333, 3436.467347561132, 3485.8605655773486, 3619.6561326673923, 3890.630390112935, 43196.78401607972, 43455.56544434578, 43696.78151609222, 43918.0, 43934.0, 42704.35943460851, 43101.56898431038, 43129.13741725148, 43129.784351078044, 43168.13663726708, 39851.60148398479, 40263.39604905929, 40466.39300410437, 40909.1818163635, 41726.79136604358, 7538.811529711801, 7606.391488680782, 7761.223877612194, 7792.571408572583, 8074.1522140176785, 31612.841935790173, 31897.202569935936, 32767.0, 32934.670653293164, 32936.835315823264, 38786.80606596949, 39794.403083953184, 40455.0, 40818.0, 40960.1807963845, 9377.624895004123, 9557.665481708107, 9585.0, 9710.271729620448, 9797.510124493605, 10091.949540252252, 10147.543360548942, 10370.014848589371, 10445.0, 10679.57281708723, 9723.270754693285, 9762.75593110178, 9803.068708472683, 9968.252381071594, 10465.58137674515, 10090.596376144871, 10093.293469457067, 10281.588736450458, 10571.154307655523, 10669.199810014436, 9863.654772082942, 9981.0, 10007.749806254611, 10032.448215348226, 10055.145312648214, 8654.567271636519, 8687.435316704394, 8927.866082008644, 9008.459492430475, 9025.954870225607, 8799.516026618598, 8940.73177804667, 9012.86480702803, 9053.244137176447, 9103.726888193365], "unit": "ms", "name": "yui.html", "value": 5552.665780462432}], "name": "dromaeo_css"}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 114.0, 106.0, 112.0, 111.0, 113.0, 102.0, 111.0, 111.0, 104.0], "unit": "ms", "name": "ai-astar", "value": 109.5}, {"lowerIsBetter": true, "replicates": [152.0, 130.0, 158.0, 178.0, 139.0, 155.0, 149.0, 131.0, 166.0, 130.0], "unit": "ms", "name": "audio-beat-detection", "value": 148.8}, {"lowerIsBetter": true, "replicates": [162.0, 162.0, 162.0, 161.0, 160.0, 161.0, 160.0, 164.0, 161.0, 163.0], "unit": "ms", "name": "audio-dft", "value": 161.6}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 82.0, 92.0, 72.0, 73.0, 73.0, 95.0, 91.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 79.4}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 129.0, 94.0, 94.0, 93.0, 135.0, 95.0, 94.0, 138.0], "unit": "ms", "name": "audio-oscillator", "value": 106.0}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 109.0, 110.0, 109.0, 109.0, 110.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.6}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 147.0, 147.0, 148.0, 149.0, 149.0, 149.0, 147.0, 148.0], "unit": "ms", "name": "imaging-darkroom", "value": 147.8}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [71.0, 70.0, 70.0, 70.0, 71.0, 70.0, 71.0, 71.0, 71.0, 70.0], "unit": "ms", "name": "json-parse-financial", "value": 70.5}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 68.0, 68.0, 69.0, 68.0, 68.0, 68.0, 68.0, 68.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.2}, {"lowerIsBetter": true, "replicates": [83.0, 82.0, 82.0, 84.0, 82.0, 83.0, 92.0, 84.0, 84.0, 83.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 83.9}, {"lowerIsBetter": true, "replicates": [127.0, 128.0, 122.0, 119.0, 125.0, 124.0, 128.0, 121.0, 126.0, 121.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 124.1}, {"lowerIsBetter": true, "replicates": [151.0, 138.0, 148.0, 147.0, 150.0, 148.0, 149.0, 148.0, 152.0, 137.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 146.8}, {"lowerIsBetter": true, "replicates": [71.0, 69.0, 68.0, 67.0, 68.0, 85.0, 71.0, 67.0, 68.0, 68.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 70.2}], "name": "kraken"}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.55562958027983], "unit": "score", "name": "Richards", "value": 211.95320800000005}, {"lowerIsBetter": false, "replicates": [191.35093761959433], "unit": "score", "name": "DeltaBlue", "value": 345.53266799999994}, {"lowerIsBetter": false, "replicates": [276.7783005812344], "unit": "score", "name": "Encrypt", "value": 961.711953}, {"lowerIsBetter": false, "replicates": [5122.448979591837], "unit": "score", "name": "Decrypt", "value": 51.96362151394425}, {"lowerIsBetter": false, "replicates": [1962.7450980392157], "unit": "score", "name": "RayTrace", "value": 377.0173726273725}, {"lowerIsBetter": false, "replicates": [802.5682182985554], "unit": "score", "name": "Earley", "value": 830.4128980000002}, {"lowerIsBetter": false, "replicates": [12231.707317073171], "unit": "score", "name": "Boyer", "value": 54.4865064805583}, {"lowerIsBetter": false, "replicates": [26500.0], "unit": "score", "name": "RegExp", "value": 34.37679245283018}, {"lowerIsBetter": false, "replicates": [599.1661703394877], "unit": "score", "name": "Splay", "value": 136.00734493041756}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7"}]} 18:31:02 INFO - RETURN: dromaeo_css: 6277.50 18:31:02 INFO - RETURN: kraken: 1522.00 18:31:02 INFO - RETURN: v8_7: 19812.68 18:31:02 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,35]]", "result": "19812.68"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,35]]", "result": "1522.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,35]]", "result": "6277.50"}}} 18:31:02 INFO - Return code: 0 18:31:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:31:02 INFO - # TBPL SUCCESS # 18:31:02 INFO - Running post-action listener: _resource_record_post_action 18:31:02 INFO - Running post-run listener: _resource_record_post_run 18:31:03 INFO - Total resource usage - Wall time: 1233s; CPU: 13.0%; Read bytes: 4096; Write bytes: 500744192; Read time: 8; Write time: 646520 18:31:03 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 228847616; Read time: 0; Write time: 411024 18:31:03 INFO - run-tests - Wall time: 1219s; CPU: 13.0%; Read bytes: 0; Write bytes: 271589376; Read time: 0; Write time: 235468 18:31:03 INFO - Running post-run listener: _upload_blobber_files 18:31:03 INFO - Blob upload gear active. 18:31:03 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 18:31:03 INFO - Copying logs to upload dir... 18:31:03 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1279.189513 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2015-11-24 18:31:03.418147) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 18:31:03.421822) ========= 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=0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009971 build_url: 'https://queue.taskcluster.net/v1/task/IHvf9MQ9RqG6zxIqhP_B4A/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-24 18:31:03.473543) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:31:03.473906) ========= 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=0cff312cb257a7829adc5a5d000001df-1448416264.95922-612265217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004725 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:31:03.523237) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 18:31:03.523551) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 18:31:03.523854) ========= ========= Total master_lag: 0.27 =========