builder: b2g-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-065 starttime: 1448548100.33 results: success (0) buildid: 20151126053016 builduid: cd8e510c7d4e4910a2120e54bc3f6aa5 revision: 2b1a10c958156968bc4842471ffa9c919ee80e92 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:20.333632) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:20.334115) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:20.334402) ========= bash -c pwd in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.004804 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:20.350371) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:20.350666) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:20.360597) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:20.360895) ========= rm -rf properties in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004036 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:20.408980) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:20.409293) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:20.409658) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:20.409929) ========= 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-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-26 06:28:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.8M=0s 2015-11-26 06:28:20 (43.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.100026 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:20.524004) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:20.524327) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020739 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:20.575318) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:20.575828) ========= bash -c 'python archiver_client.py mozharness --repo integration/b2g-inbound --rev 2b1a10c958156968bc4842471ffa9c919ee80e92 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/b2g-inbound --rev 2b1a10c958156968bc4842471ffa9c919ee80e92 --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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-26 06:28:20,762 truncating revision to first 12 chars 2015-11-26 06:28:20,762 Setting DEBUG logging. 2015-11-26 06:28:20,762 attempt 1/10 2015-11-26 06:28:20,763 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/2b1a10c95815?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-26 06:28:21,015 unpacking tar archive at: b2g-inbound-2b1a10c95815/testing/mozharness/ program finished with exit code 0 elapsedTime=0.551551 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:21.143117) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:21.143670) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:21.152865) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:21.153192) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-26 06:28:21.153613) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2015-11-26 06:28:21.153899) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'B2G-Inbound', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'B2G-Inbound'] 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-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:28:21 INFO - MultiFileLogger online at 20151126 06:28:21 in /builds/slave/test-pgo 06:28:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound 06:28:21 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 06:28:21 INFO - {'append_to_log': False, 06:28:21 INFO - 'base_work_dir': '/builds/slave/test-pgo', 06:28:21 INFO - 'blob_upload_branch': 'B2G-Inbound', 06:28:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 06:28:21 INFO - 'branch': 'B2G-Inbound', 06:28:21 INFO - 'buildbot_json_path': 'buildprops.json', 06:28:21 INFO - 'config_files': ('talos/linux_config.py',), 06:28:21 INFO - 'default_actions': ('clobber', 06:28:21 INFO - 'read-buildbot-config', 06:28:21 INFO - 'download-and-extract', 06:28:21 INFO - 'populate-webroot', 06:28:21 INFO - 'create-virtualenv', 06:28:21 INFO - 'install', 06:28:21 INFO - 'run-tests'), 06:28:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:28:21 INFO - 'download_minidump_stackwalk': True, 06:28:21 INFO - 'download_symbols': 'ondemand', 06:28:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:28:21 INFO - 'tooltool.py': '/tools/tooltool.py', 06:28:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:28:21 INFO - '/tools/misc-python/virtualenv.py')}, 06:28:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:28:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:28:21 INFO - 'installer_path': 'installer.exe', 06:28:21 INFO - 'log_level': 'info', 06:28:21 INFO - 'log_name': 'talos', 06:28:21 INFO - 'log_to_console': True, 06:28:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:28:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:28:21 INFO - 'opt_config_files': (), 06:28:21 INFO - 'pip_index': False, 06:28:21 INFO - 'sps_profile': False, 06:28:21 INFO - 'sps_profile_interval': 0, 06:28:21 INFO - 'suite': 'dromaeojs', 06:28:21 INFO - 'system_bits': '64', 06:28:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:28:21 INFO - 'title': 'talos-linux64-ix-065', 06:28:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:28:21 INFO - 'use_talos_json': True, 06:28:21 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 06:28:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:28:21 INFO - 'work_dir': 'build'} 06:28:21 INFO - ##### 06:28:21 INFO - ##### Running clobber step. 06:28:21 INFO - ##### 06:28:21 INFO - Running pre-action listener: _resource_record_pre_action 06:28:21 INFO - Running main action method: clobber 06:28:21 INFO - rmtree: /builds/slave/test-pgo/build 06:28:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 06:28:22 INFO - Running post-action listener: _resource_record_post_action 06:28:22 INFO - ##### 06:28:22 INFO - ##### Running read-buildbot-config step. 06:28:22 INFO - ##### 06:28:22 INFO - Running pre-action listener: _resource_record_pre_action 06:28:22 INFO - Running main action method: read_buildbot_config 06:28:22 INFO - Using buildbot properties: 06:28:22 INFO - { 06:28:22 INFO - "properties": { 06:28:22 INFO - "buildnumber": 56, 06:28:22 INFO - "product": "firefox", 06:28:22 INFO - "script_repo_revision": "production", 06:28:22 INFO - "builddir": "b2g-inbound_ubuntu64_hw_test-dromaeojs", 06:28:22 INFO - "repository": "", 06:28:22 INFO - "buildername": "Ubuntu HW 12.04 x64 b2g-inbound pgo talos dromaeojs", 06:28:22 INFO - "buildid": "20151126053016", 06:28:22 INFO - "slavename": "talos-linux64-ix-065", 06:28:22 INFO - "pgo_build": "True", 06:28:22 INFO - "basedir": "/builds/slave/test-pgo", 06:28:22 INFO - "project": "", 06:28:22 INFO - "platform": "ubuntu64_hw", 06:28:22 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 06:28:22 INFO - "slavebuilddir": "test", 06:28:22 INFO - "scheduler": "tests-b2g-inbound-linux64-pgo-talos", 06:28:22 INFO - "branch": "b2g-inbound", 06:28:22 INFO - "repo_path": "integration/b2g-inbound", 06:28:22 INFO - "stage_platform": "linux64-pgo", 06:28:22 INFO - "builduid": "cd8e510c7d4e4910a2120e54bc3f6aa5", 06:28:22 INFO - "revision": "2b1a10c958156968bc4842471ffa9c919ee80e92" 06:28:22 INFO - }, 06:28:22 INFO - "sourcestamp": { 06:28:22 INFO - "repository": "", 06:28:22 INFO - "hasPatch": false, 06:28:22 INFO - "project": "", 06:28:22 INFO - "branch": "b2g-inbound-linux64-pgo-talos", 06:28:22 INFO - "changes": [ 06:28:22 INFO - { 06:28:22 INFO - "category": null, 06:28:22 INFO - "files": [ 06:28:22 INFO - { 06:28:22 INFO - "url": null, 06:28:22 INFO - "name": "https://queue.taskcluster.net/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 06:28:22 INFO - } 06:28:22 INFO - ], 06:28:22 INFO - "repository": "", 06:28:22 INFO - "rev": "2b1a10c958156968bc4842471ffa9c919ee80e92", 06:28:22 INFO - "who": "sendchange", 06:28:22 INFO - "when": 1448548045, 06:28:22 INFO - "number": 6710962, 06:28:22 INFO - "comments": "", 06:28:22 INFO - "project": "", 06:28:22 INFO - "at": "Thu 26 Nov 2015 06:27:25", 06:28:22 INFO - "branch": "b2g-inbound-linux64-pgo-talos", 06:28:22 INFO - "revlink": "", 06:28:22 INFO - "properties": [ 06:28:22 INFO - [ 06:28:22 INFO - "buildid", 06:28:22 INFO - "20151126053016", 06:28:22 INFO - "Change" 06:28:22 INFO - ], 06:28:22 INFO - [ 06:28:22 INFO - "builduid", 06:28:22 INFO - "cd8e510c7d4e4910a2120e54bc3f6aa5", 06:28:22 INFO - "Change" 06:28:22 INFO - ], 06:28:22 INFO - [ 06:28:22 INFO - "pgo_build", 06:28:22 INFO - "True", 06:28:22 INFO - "Change" 06:28:22 INFO - ] 06:28:22 INFO - ], 06:28:22 INFO - "revision": "2b1a10c958156968bc4842471ffa9c919ee80e92" 06:28:22 INFO - } 06:28:22 INFO - ], 06:28:22 INFO - "revision": "2b1a10c958156968bc4842471ffa9c919ee80e92" 06:28:22 INFO - } 06:28:22 INFO - } 06:28:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 06:28:22 INFO - Running post-action listener: _resource_record_post_action 06:28:22 INFO - ##### 06:28:22 INFO - ##### Running download-and-extract step. 06:28:22 INFO - ##### 06:28:22 INFO - Running pre-action listener: _resource_record_pre_action 06:28:22 INFO - Running main action method: download_and_extract 06:28:22 INFO - mkdir: /builds/slave/test-pgo/build/tests 06:28:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:28:22 INFO - https://queue.taskcluster.net/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 06:28:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/test_packages.json 06:28:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/test_packages.json 06:28:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 06:28:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 06:28:22 INFO - Downloaded 1302 bytes. 06:28:22 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 06:28:22 INFO - Using the following test package requirements: 06:28:22 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 06:28:22 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:28:22 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 06:28:22 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:28:22 INFO - u'jsshell-linux-x86_64.zip'], 06:28:22 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:28:22 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 06:28:22 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 06:28:22 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:28:22 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 06:28:22 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:28:22 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 06:28:22 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:28:22 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 06:28:22 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 06:28:22 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:28:22 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 06:28:22 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:28:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:28:22 INFO - https://queue.taskcluster.net/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:28:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:28:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:28:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:28:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:28:24 INFO - Downloaded 22754009 bytes. 06:28:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 06:28:24 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:28:24 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 06:28:25 INFO - Return code: 0 06:28:25 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 06:28:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:28:25 INFO - https://queue.taskcluster.net/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:28:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:28:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:28:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:28:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:28:26 INFO - Downloaded 22754009 bytes. 06:28:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 06:28:26 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:28:26 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 06:28:27 INFO - Return code: 0 06:28:27 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')]} 06:28:27 INFO - https://queue.taskcluster.net/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:28:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 06:28:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 06:28:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 06:28:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 06:28:28 INFO - Downloaded 11131717 bytes. 06:28:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 06:28:28 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 06:28:28 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 06:28:28 INFO - Return code: 0 06:28:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:28:28 INFO - https://queue.taskcluster.net/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:28:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 06:28:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 06:28:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 06:28:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 06:28:30 INFO - Downloaded 58392267 bytes. 06:28:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 06:28:30 INFO - mkdir: /builds/slave/test-pgo/properties 06:28:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 06:28:30 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 06:28:30 INFO - Contents: 06:28:30 INFO - build_url:https://queue.taskcluster.net/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 06:28:30 INFO - Running post-action listener: _resource_record_post_action 06:28:30 INFO - Running post-action listener: set_extra_try_arguments 06:28:30 INFO - ##### 06:28:30 INFO - ##### Running populate-webroot step. 06:28:30 INFO - ##### 06:28:30 INFO - Running pre-action listener: _resource_record_pre_action 06:28:30 INFO - Running main action method: populate_webroot 06:28:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:28:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:28:30 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 06:28:30 INFO - '../fennec_ids.txt'], 06:28:30 INFO - 'tests': ['tcheck2']}, 06:28:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:28:30 INFO - '--tppagecycles', 06:28:30 INFO - '7'], 06:28:30 INFO - 'tests': ['tsvgm']}}, 06:28:30 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 06:28:30 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 06:28:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:28:30 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 06:28:30 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 06:28:30 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 06:28:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:28:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:30 INFO - 'talos_options': ['--e10s'], 06:28:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:28:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:30 INFO - 'tests': ['damp', 'tps']}, 06:28:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:30 INFO - 'talos_options': ['--e10s'], 06:28:30 INFO - 'tests': ['damp', 'tps']}, 06:28:30 INFO - 'g3': {'tests': ['dromaeo_dom']}, 06:28:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:28:30 INFO - 'other': {'tests': ['a11yr', 06:28:30 INFO - 'ts_paint', 06:28:30 INFO - 'tpaint', 06:28:30 INFO - 'sessionrestore', 06:28:30 INFO - 'sessionrestore_no_auto_restore']}, 06:28:30 INFO - 'other-e10s': {'talos_options': ['--e10s'], 06:28:30 INFO - 'tests': ['a11yr', 06:28:30 INFO - 'ts_paint', 06:28:30 INFO - 'tpaint', 06:28:30 INFO - 'sessionrestore', 06:28:30 INFO - 'sessionrestore_no_auto_restore']}, 06:28:30 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 06:28:30 INFO - 'tests': ['a11yr', 06:28:30 INFO - 'ts_paint', 06:28:30 INFO - 'tpaint', 06:28:30 INFO - 'sessionrestore', 06:28:30 INFO - 'sessionrestore_no_auto_restore']}, 06:28:30 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 06:28:30 INFO - 'tests': ['a11yr', 06:28:30 INFO - 'ts_paint', 06:28:30 INFO - 'tpaint', 06:28:30 INFO - 'sessionrestore', 06:28:30 INFO - 'sessionrestore_no_auto_restore']}, 06:28:30 INFO - 'other_l64': {'tests': ['a11yr', 06:28:30 INFO - 'ts_paint', 06:28:30 INFO - 'tpaint', 06:28:30 INFO - 'sessionrestore', 06:28:30 INFO - 'sessionrestore_no_auto_restore']}, 06:28:30 INFO - 'other_nol64': {'tests': ['a11yr', 06:28:30 INFO - 'ts_paint', 06:28:30 INFO - 'tpaint', 06:28:30 INFO - 'sessionrestore', 06:28:30 INFO - 'sessionrestore_no_auto_restore']}, 06:28:30 INFO - 'svgr': {'tests': ['tsvgx', 06:28:30 INFO - 'tsvgr_opacity', 06:28:30 INFO - 'tart', 06:28:30 INFO - 'tscrollx', 06:28:30 INFO - 'cart']}, 06:28:30 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 06:28:30 INFO - 'tests': ['tsvgx', 06:28:30 INFO - 'tsvgr_opacity', 06:28:30 INFO - 'tart', 06:28:30 INFO - 'tscrollx', 06:28:30 INFO - 'cart']}, 06:28:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:30 INFO - 'tests': ['tp5o']}, 06:28:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:30 INFO - 'talos_options': ['--e10s'], 06:28:30 INFO - 'tests': ['tp5o']}, 06:28:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:30 INFO - 'talos_options': ['--xperf_path', 06:28:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:28:30 INFO - 'tests': ['tp5n']}, 06:28:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:30 INFO - 'talos_options': ['--e10s', 06:28:30 INFO - '--xperf_path', 06:28:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:28:30 INFO - 'tests': ['tp5n']}}, 06:28:30 INFO - 'talos.zip': {'path': '', 06:28:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:28:30 INFO - Running post-action listener: _resource_record_post_action 06:28:30 INFO - ##### 06:28:30 INFO - ##### Running create-virtualenv step. 06:28:30 INFO - ##### 06:28:30 INFO - Running pre-action listener: _resource_record_pre_action 06:28:30 INFO - Running main action method: create_virtualenv 06:28:30 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 06:28:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 06:28:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 06:28:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:28:30 INFO - Using real prefix '/usr' 06:28:30 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 06:28:30 INFO - Installing distribute.............................................................................................................................................................................................done. 06:28:32 INFO - Installing pip.................done. 06:28:32 INFO - Return code: 0 06:28:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 06:28:32 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')]} 06:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:32 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')]} 06:28:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:32 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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 0x1cbb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee87d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f77640>, '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 0x1f78540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f789d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 06:28:32 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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-pgo/build 06:28:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/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 06:28:32 INFO - Using env: {'DISPLAY': ':0', 06:28:32 INFO - 'HOME': '/home/cltbld', 06:28:32 INFO - 'LANG': 'en_US.UTF-8', 06:28:32 INFO - 'LANGUAGE': 'en_US:en', 06:28:32 INFO - 'LOGNAME': 'cltbld', 06:28:32 INFO - 'MAIL': '/var/mail/cltbld', 06:28:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:32 INFO - 'MOZ_NO_REMOTE': '1', 06:28:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:28:32 INFO - 'NO_EM_RESTART': '1', 06:28:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:28:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:28:32 INFO - 'PWD': '/builds/slave/test-pgo', 06:28:32 INFO - 'SHELL': '/bin/bash', 06:28:32 INFO - 'SHLVL': '1', 06:28:32 INFO - 'TERM': 'linux', 06:28:32 INFO - 'TMOUT': '86400', 06:28:32 INFO - 'USER': 'cltbld', 06:28:32 INFO - 'XDG_SESSION_COOKIE': '20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683', 06:28:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:32 INFO - '_': '/tools/buildbot/bin/python'} 06:28:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:32 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 06:28:32 INFO - Cleaning up... 06:28:32 INFO - Return code: 0 06:28:33 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 06:28:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:28:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:33 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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 0x1cbb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee87d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f77640>, '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 0x1f78540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f789d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 06:28:33 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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-pgo/build 06:28:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/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 06:28:33 INFO - Using env: {'DISPLAY': ':0', 06:28:33 INFO - 'HOME': '/home/cltbld', 06:28:33 INFO - 'LANG': 'en_US.UTF-8', 06:28:33 INFO - 'LANGUAGE': 'en_US:en', 06:28:33 INFO - 'LOGNAME': 'cltbld', 06:28:33 INFO - 'MAIL': '/var/mail/cltbld', 06:28:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:33 INFO - 'MOZ_NO_REMOTE': '1', 06:28:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:28:33 INFO - 'NO_EM_RESTART': '1', 06:28:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:28:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:28:33 INFO - 'PWD': '/builds/slave/test-pgo', 06:28:33 INFO - 'SHELL': '/bin/bash', 06:28:33 INFO - 'SHLVL': '1', 06:28:33 INFO - 'TERM': 'linux', 06:28:33 INFO - 'TMOUT': '86400', 06:28:33 INFO - 'USER': 'cltbld', 06:28:33 INFO - 'XDG_SESSION_COOKIE': '20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683', 06:28:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:33 INFO - '_': '/tools/buildbot/bin/python'} 06:28:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:33 INFO - Downloading/unpacking psutil>=0.7.1 06:28:33 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 06:28:33 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 06:28:33 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 06:28:33 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 06:28:33 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 06:28:33 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 06:28:34 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 06:28:34 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 06:28:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 06:28:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:28:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:28:35 INFO - Installing collected packages: psutil 06:28:35 INFO - Running setup.py install for psutil 06:28:35 INFO - building 'psutil._psutil_linux' extension 06:28:35 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 06:28:35 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 06:28:35 INFO - building 'psutil._psutil_posix' extension 06:28:35 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 06:28:35 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 06:28:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:28:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:28:35 INFO - Successfully installed psutil 06:28:35 INFO - Cleaning up... 06:28:35 INFO - Return code: 0 06:28:35 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 06:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:28:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:35 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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 0x1cbb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee87d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f77640>, '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 0x1f78540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f789d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 06:28:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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-pgo/build 06:28:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/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 06:28:35 INFO - Using env: {'DISPLAY': ':0', 06:28:35 INFO - 'HOME': '/home/cltbld', 06:28:35 INFO - 'LANG': 'en_US.UTF-8', 06:28:35 INFO - 'LANGUAGE': 'en_US:en', 06:28:35 INFO - 'LOGNAME': 'cltbld', 06:28:35 INFO - 'MAIL': '/var/mail/cltbld', 06:28:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:35 INFO - 'MOZ_NO_REMOTE': '1', 06:28:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:28:35 INFO - 'NO_EM_RESTART': '1', 06:28:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:28:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:28:35 INFO - 'PWD': '/builds/slave/test-pgo', 06:28:35 INFO - 'SHELL': '/bin/bash', 06:28:35 INFO - 'SHLVL': '1', 06:28:35 INFO - 'TERM': 'linux', 06:28:35 INFO - 'TMOUT': '86400', 06:28:35 INFO - 'USER': 'cltbld', 06:28:35 INFO - 'XDG_SESSION_COOKIE': '20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683', 06:28:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:35 INFO - '_': '/tools/buildbot/bin/python'} 06:28:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:28:35 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 06:28:35 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 06:28:35 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 06:28:35 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 06:28:35 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 06:28:35 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 06:28:37 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:28:37 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 06:28:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:28:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 06:28:37 INFO - Installing collected packages: mozsystemmonitor 06:28:37 INFO - Running setup.py install for mozsystemmonitor 06:28:37 INFO - Successfully installed mozsystemmonitor 06:28:37 INFO - Cleaning up... 06:28:37 INFO - Return code: 0 06:28:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 06:28:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:28:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:28:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:37 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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 0x1cbb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee87d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f77640>, '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 0x1f78540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f789d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 06:28:37 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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-pgo/build 06:28:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/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 06:28:37 INFO - Using env: {'DISPLAY': ':0', 06:28:37 INFO - 'HOME': '/home/cltbld', 06:28:37 INFO - 'LANG': 'en_US.UTF-8', 06:28:37 INFO - 'LANGUAGE': 'en_US:en', 06:28:37 INFO - 'LOGNAME': 'cltbld', 06:28:37 INFO - 'MAIL': '/var/mail/cltbld', 06:28:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:37 INFO - 'MOZ_NO_REMOTE': '1', 06:28:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:28:37 INFO - 'NO_EM_RESTART': '1', 06:28:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:28:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:28:37 INFO - 'PWD': '/builds/slave/test-pgo', 06:28:37 INFO - 'SHELL': '/bin/bash', 06:28:37 INFO - 'SHLVL': '1', 06:28:37 INFO - 'TERM': 'linux', 06:28:37 INFO - 'TMOUT': '86400', 06:28:37 INFO - 'USER': 'cltbld', 06:28:37 INFO - 'XDG_SESSION_COOKIE': '20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683', 06:28:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:37 INFO - '_': '/tools/buildbot/bin/python'} 06:28:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:37 INFO - Downloading/unpacking blobuploader==1.2.4 06:28:37 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 06:28:37 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 06:28:37 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 06:28:37 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 06:28:37 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 06:28:37 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 06:28:39 INFO - Downloading blobuploader-1.2.4.tar.gz 06:28:39 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 06:28:39 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:28:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:28:39 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 06:28:39 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 06:28:39 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 06:28:39 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 06:28:39 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 06:28:39 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 06:28:39 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 06:28:39 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 06:28:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:28:40 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 06:28:40 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 06:28:40 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 06:28:40 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 06:28:40 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 06:28:40 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 06:28:40 INFO - Downloading docopt-0.6.1.tar.gz 06:28:40 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 06:28:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 06:28:40 INFO - Installing collected packages: blobuploader, requests, docopt 06:28:40 INFO - Running setup.py install for blobuploader 06:28:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:28:40 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 06:28:40 INFO - Running setup.py install for requests 06:28:40 INFO - Running setup.py install for docopt 06:28:40 INFO - Successfully installed blobuploader requests docopt 06:28:40 INFO - Cleaning up... 06:28:40 INFO - Return code: 0 06:28:40 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:28:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:28:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:28:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:40 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x1cbb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee87d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f77640>, '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 0x1f78540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f789d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 06:28:40 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/config 06:28:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/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 06:28:40 INFO - Using env: {'DISPLAY': ':0', 06:28:40 INFO - 'HOME': '/home/cltbld', 06:28:40 INFO - 'LANG': 'en_US.UTF-8', 06:28:40 INFO - 'LANGUAGE': 'en_US:en', 06:28:40 INFO - 'LOGNAME': 'cltbld', 06:28:40 INFO - 'MAIL': '/var/mail/cltbld', 06:28:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:40 INFO - 'MOZ_NO_REMOTE': '1', 06:28:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:28:40 INFO - 'NO_EM_RESTART': '1', 06:28:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:28:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:28:40 INFO - 'PWD': '/builds/slave/test-pgo', 06:28:40 INFO - 'SHELL': '/bin/bash', 06:28:40 INFO - 'SHLVL': '1', 06:28:40 INFO - 'TERM': 'linux', 06:28:40 INFO - 'TMOUT': '86400', 06:28:40 INFO - 'USER': 'cltbld', 06:28:40 INFO - 'XDG_SESSION_COOKIE': '20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683', 06:28:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:40 INFO - '_': '/tools/buildbot/bin/python'} 06:28:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:28:41 INFO - Running setup.py (path:/tmp/pip-WBRdqV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:28:41 INFO - Running setup.py (path:/tmp/pip-WT0TjB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:28:41 INFO - Running setup.py (path:/tmp/pip-_3Vdqr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:28:41 INFO - Running setup.py (path:/tmp/pip-IGQcR0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 06:28:41 INFO - Running setup.py (path:/tmp/pip-LeJINb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 06:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:28:41 INFO - Running setup.py (path:/tmp/pip-mFuuzl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:28:41 INFO - Running setup.py (path:/tmp/pip-hpw81Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:28:41 INFO - Running setup.py (path:/tmp/pip-VhlMWy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 06:28:41 INFO - Running setup.py (path:/tmp/pip-tDCv9n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 06:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 06:28:41 INFO - Running setup.py (path:/tmp/pip-O2rj9U-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 06:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:28:41 INFO - Running setup.py (path:/tmp/pip-oOEgIv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:28:41 INFO - Running setup.py (path:/tmp/pip-ycruad-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:28:41 INFO - Running setup.py (path:/tmp/pip-Qea8xj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:28:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:28:42 INFO - Running setup.py (path:/tmp/pip-TCrPJT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:28:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:28:42 INFO - Running setup.py (path:/tmp/pip-tu6Sjb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:28:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 06:28:42 INFO - Running setup.py (path:/tmp/pip-EtkH9N-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 06:28:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 06:28:42 INFO - Running setup.py (path:/tmp/pip-seuyRE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 06:28:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:28:42 INFO - Running setup.py install for manifestparser 06:28:42 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 06:28:42 INFO - Running setup.py install for mozcrash 06:28:42 INFO - Running setup.py install for mozdebug 06:28:42 INFO - Running setup.py install for mozdevice 06:28:42 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 06:28:42 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 06:28:42 INFO - Running setup.py install for mozfile 06:28:42 INFO - Running setup.py install for mozhttpd 06:28:42 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 06:28:42 INFO - Running setup.py install for mozinfo 06:28:43 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 06:28:43 INFO - Running setup.py install for mozInstall 06:28:43 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 06:28:43 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 06:28:43 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 06:28:43 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 06:28:43 INFO - Running setup.py install for mozleak 06:28:43 INFO - Running setup.py install for mozlog 06:28:43 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 06:28:43 INFO - Running setup.py install for moznetwork 06:28:43 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 06:28:43 INFO - Running setup.py install for mozprocess 06:28:43 INFO - Running setup.py install for mozprofile 06:28:43 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 06:28:43 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 06:28:43 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 06:28:43 INFO - Running setup.py install for mozrunner 06:28:43 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 06:28:43 INFO - Running setup.py install for mozscreenshot 06:28:43 INFO - Running setup.py install for moztest 06:28:44 INFO - Running setup.py install for mozversion 06:28:44 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 06:28:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:28:44 INFO - Cleaning up... 06:28:44 INFO - Return code: 0 06:28:44 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:28:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:28:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:44 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x1cbb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee87d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f77640>, '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 0x1f78540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f789d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 06:28:44 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/config 06:28:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/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 06:28:44 INFO - Using env: {'DISPLAY': ':0', 06:28:44 INFO - 'HOME': '/home/cltbld', 06:28:44 INFO - 'LANG': 'en_US.UTF-8', 06:28:44 INFO - 'LANGUAGE': 'en_US:en', 06:28:44 INFO - 'LOGNAME': 'cltbld', 06:28:44 INFO - 'MAIL': '/var/mail/cltbld', 06:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:44 INFO - 'MOZ_NO_REMOTE': '1', 06:28:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:28:44 INFO - 'NO_EM_RESTART': '1', 06:28:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:28:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:28:44 INFO - 'PWD': '/builds/slave/test-pgo', 06:28:44 INFO - 'SHELL': '/bin/bash', 06:28:44 INFO - 'SHLVL': '1', 06:28:44 INFO - 'TERM': 'linux', 06:28:44 INFO - 'TMOUT': '86400', 06:28:44 INFO - 'USER': 'cltbld', 06:28:44 INFO - 'XDG_SESSION_COOKIE': '20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683', 06:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:44 INFO - '_': '/tools/buildbot/bin/python'} 06:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:28:44 INFO - Running setup.py (path:/tmp/pip-uZlfqx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 06:28:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:28:44 INFO - Running setup.py (path:/tmp/pip-sra2Mo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 06:28:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:28:44 INFO - Running setup.py (path:/tmp/pip-20_E0I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 06:28:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:28:44 INFO - Running setup.py (path:/tmp/pip-5vHh_M-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 06:28:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 06:28:44 INFO - Running setup.py (path:/tmp/pip-2V754H-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 06:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 06:28:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:28:44 INFO - Running setup.py (path:/tmp/pip-vHmQbi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 06:28:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:28:44 INFO - Running setup.py (path:/tmp/pip-7yX7AX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 06:28:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:28:44 INFO - Running setup.py (path:/tmp/pip-jLR0fH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 06:28:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 06:28:44 INFO - Running setup.py (path:/tmp/pip-ZVxOR6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 06:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 06:28:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 06:28:44 INFO - Running setup.py (path:/tmp/pip-yVvOqk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 06:28:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 06:28:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:28:45 INFO - Running setup.py (path:/tmp/pip-2q3MEp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:28:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 06:28:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:28:45 INFO - Running setup.py (path:/tmp/pip-H4p96e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:28:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 06:28:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:28:45 INFO - Running setup.py (path:/tmp/pip-CcfuHv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:28:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 06:28:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:28:45 INFO - Running setup.py (path:/tmp/pip-ycgpzS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:28:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 06:28:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:28:45 INFO - Running setup.py (path:/tmp/pip-IZrqXh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:28:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 06:28:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 06:28:45 INFO - Running setup.py (path:/tmp/pip-BOR909-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 06:28:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 06:28:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 06:28:45 INFO - Running setup.py (path:/tmp/pip-U4bMsZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 06:28:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 06:28:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 06:28:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 06:28:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 06:28:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 06:28:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 06:28:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 06:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:28:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:28:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:28:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:28:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:28:47 INFO - Downloading blessings-1.5.1.tar.gz 06:28:47 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 06:28:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 06:28:47 INFO - Installing collected packages: blessings 06:28:47 INFO - Running setup.py install for blessings 06:28:47 INFO - Successfully installed blessings 06:28:47 INFO - Cleaning up... 06:28:47 INFO - Return code: 0 06:28:47 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 06:28:47 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 06:28:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 06:28:47 INFO - Reading from file tmpfile_stdout 06:28:47 INFO - Current package versions: 06:28:47 INFO - argparse == 1.2.1 06:28:47 INFO - blessings == 1.5.1 06:28:47 INFO - blobuploader == 1.2.4 06:28:47 INFO - docopt == 0.6.1 06:28:47 INFO - manifestparser == 1.1 06:28:47 INFO - mozInstall == 1.12 06:28:47 INFO - mozcrash == 0.16 06:28:47 INFO - mozdebug == 0.1 06:28:47 INFO - mozdevice == 0.47 06:28:47 INFO - mozfile == 1.2 06:28:47 INFO - mozhttpd == 0.7 06:28:47 INFO - mozinfo == 0.9 06:28:47 INFO - mozleak == 0.1 06:28:47 INFO - mozlog == 3.1 06:28:47 INFO - moznetwork == 0.27 06:28:47 INFO - mozprocess == 0.22 06:28:47 INFO - mozprofile == 0.27 06:28:47 INFO - mozrunner == 6.11 06:28:47 INFO - mozscreenshot == 0.1 06:28:47 INFO - mozsystemmonitor == 0.0 06:28:47 INFO - moztest == 0.7 06:28:47 INFO - mozversion == 1.4 06:28:47 INFO - psutil == 3.1.1 06:28:47 INFO - requests == 1.2.3 06:28:47 INFO - wsgiref == 0.1.2 06:28:47 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:47 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x1cbb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee87d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f77640>, '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 0x1f78540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f789d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 06:28:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/talos 06:28:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/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 06:28:47 INFO - Using env: {'DISPLAY': ':0', 06:28:47 INFO - 'HOME': '/home/cltbld', 06:28:47 INFO - 'LANG': 'en_US.UTF-8', 06:28:47 INFO - 'LANGUAGE': 'en_US:en', 06:28:47 INFO - 'LOGNAME': 'cltbld', 06:28:47 INFO - 'MAIL': '/var/mail/cltbld', 06:28:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:47 INFO - 'MOZ_NO_REMOTE': '1', 06:28:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:28:47 INFO - 'NO_EM_RESTART': '1', 06:28:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:28:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:28:47 INFO - 'PWD': '/builds/slave/test-pgo', 06:28:47 INFO - 'SHELL': '/bin/bash', 06:28:47 INFO - 'SHLVL': '1', 06:28:47 INFO - 'TERM': 'linux', 06:28:47 INFO - 'TMOUT': '86400', 06:28:47 INFO - 'USER': 'cltbld', 06:28:47 INFO - 'XDG_SESSION_COOKIE': '20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683', 06:28:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:47 INFO - '_': '/tools/buildbot/bin/python'} 06:28:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 06:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 06:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 06:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 06:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 06:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 06:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 06:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 06:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 06:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 06:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 06:28:47 INFO - Cleaning up... 06:28:47 INFO - Return code: 0 06:28:47 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 06:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:47 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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 0x1cbb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee87d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f77640>, '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 0x1f78540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f789d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 06:28:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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-pgo/build 06:28:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/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 06:28:47 INFO - Using env: {'DISPLAY': ':0', 06:28:47 INFO - 'HOME': '/home/cltbld', 06:28:47 INFO - 'LANG': 'en_US.UTF-8', 06:28:47 INFO - 'LANGUAGE': 'en_US:en', 06:28:47 INFO - 'LOGNAME': 'cltbld', 06:28:47 INFO - 'MAIL': '/var/mail/cltbld', 06:28:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:47 INFO - 'MOZ_NO_REMOTE': '1', 06:28:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:28:47 INFO - 'NO_EM_RESTART': '1', 06:28:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:28:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:28:47 INFO - 'PWD': '/builds/slave/test-pgo', 06:28:47 INFO - 'SHELL': '/bin/bash', 06:28:47 INFO - 'SHLVL': '1', 06:28:47 INFO - 'TERM': 'linux', 06:28:47 INFO - 'TMOUT': '86400', 06:28:47 INFO - 'USER': 'cltbld', 06:28:47 INFO - 'XDG_SESSION_COOKIE': '20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683', 06:28:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:47 INFO - '_': '/tools/buildbot/bin/python'} 06:28:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:48 INFO - Downloading/unpacking jsonschema 06:28:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:28:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:28:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:28:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:28:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:28:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:28:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:28:49 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 06:28:49 INFO - Downloading/unpacking functools32 (from jsonschema) 06:28:49 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 06:28:49 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 06:28:49 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 06:28:49 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 06:28:49 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 06:28:49 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 06:28:49 INFO - Downloading functools32-3.2.3-2.tar.gz 06:28:49 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 06:28:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 06:28:50 INFO - warning: no files found matching '*.txt' 06:28:50 INFO - no previously-included directories found matching 'build' 06:28:50 INFO - no previously-included directories found matching 'dist' 06:28:50 INFO - no previously-included directories found matching '.git*' 06:28:50 INFO - Installing collected packages: jsonschema, functools32 06:28:50 INFO - Running setup.py install for functools32 06:28:50 INFO - warning: no files found matching '*.txt' 06:28:50 INFO - no previously-included directories found matching 'build' 06:28:50 INFO - no previously-included directories found matching 'dist' 06:28:50 INFO - no previously-included directories found matching '.git*' 06:28:50 INFO - Successfully installed jsonschema functools32 06:28:50 INFO - Cleaning up... 06:28:50 INFO - Return code: 0 06:28:50 INFO - Running post-action listener: _resource_record_post_action 06:28:50 INFO - Running post-action listener: _start_resource_monitoring 06:28:50 INFO - Starting resource monitoring. 06:28:50 INFO - ##### 06:28:50 INFO - ##### Running install step. 06:28:50 INFO - ##### 06:28:50 INFO - Running pre-action listener: _resource_record_pre_action 06:28:50 INFO - Running main action method: install 06:28:50 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 06:28:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 06:28:50 INFO - Reading from file tmpfile_stdout 06:28:50 INFO - Detecting whether we're running mozinstall >=1.0... 06:28:50 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 06:28:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 06:28:50 INFO - Reading from file tmpfile_stdout 06:28:50 INFO - Output received: 06:28:50 INFO - Usage: mozinstall [options] installer 06:28:50 INFO - Options: 06:28:50 INFO - -h, --help show this help message and exit 06:28:50 INFO - -d DEST, --destination=DEST 06:28:50 INFO - Directory to install application into. [default: 06:28:50 INFO - "/builds/slave/test-pgo"] 06:28:50 INFO - --app=APP Application being installed. [default: firefox] 06:28:50 INFO - mkdir: /builds/slave/test-pgo/build/application 06:28:50 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 06:28:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 06:29:05 INFO - Reading from file tmpfile_stdout 06:29:05 INFO - Output received: 06:29:05 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 06:29:05 INFO - Running post-action listener: _resource_record_post_action 06:29:05 INFO - ##### 06:29:05 INFO - ##### Running run-tests step. 06:29:05 INFO - ##### 06:29:05 INFO - Running pre-action listener: _resource_record_pre_action 06:29:05 INFO - Running main action method: run_tests 06:29:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 06:29:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 06:29:05 INFO - Python 2.7.3 06:29:05 INFO - Return code: 0 06:29:05 INFO - grabbing minidump binary from tooltool 06:29:05 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')]} 06:29:05 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-pgo/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 0x1f77640>, '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 0x1f78540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f789d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 06:29:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 06:29:05 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 06:29:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:29:05 INFO - Return code: 0 06:29:05 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 06:29:05 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 06:29:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 06:29:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 06:29:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 06:29:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'B2G-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 06:29:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName B2G-Inbound --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --webServer localhost 06:29:05 INFO - Using env: {'DISPLAY': ':0', 06:29:05 INFO - 'HOME': '/home/cltbld', 06:29:05 INFO - 'LANG': 'en_US.UTF-8', 06:29:05 INFO - 'LANGUAGE': 'en_US:en', 06:29:05 INFO - 'LOGNAME': 'cltbld', 06:29:05 INFO - 'MAIL': '/var/mail/cltbld', 06:29:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 06:29:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 06:29:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:05 INFO - 'MOZ_NO_REMOTE': '1', 06:29:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 06:29:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:29:05 INFO - 'NO_EM_RESTART': '1', 06:29:05 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:29:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:29:05 INFO - 'PWD': '/builds/slave/test-pgo', 06:29:05 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 06:29:05 INFO - 'SHELL': '/bin/bash', 06:29:05 INFO - 'SHLVL': '1', 06:29:05 INFO - 'TERM': 'linux', 06:29:05 INFO - 'TMOUT': '86400', 06:29:05 INFO - 'USER': 'cltbld', 06:29:05 INFO - 'XDG_SESSION_COOKIE': '20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683', 06:29:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:05 INFO - '_': '/tools/buildbot/bin/python'} 06:29:05 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'B2G-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost'] with output_timeout 3600 06:29:05 INFO - mozversion INFO | application_buildid: 20151126053016 06:29:05 INFO - mozversion INFO | application_changeset: 2b1a10c958156968bc4842471ffa9c919ee80e92 06:29:05 INFO - mozversion INFO | application_display_name: Nightly 06:29:05 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:29:05 INFO - mozversion INFO | application_name: Firefox 06:29:05 INFO - mozversion INFO | application_remotingname: firefox 06:29:05 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/b2g-inbound 06:29:05 INFO - mozversion INFO | application_vendor: Mozilla 06:29:05 INFO - mozversion INFO | application_version: 45.0a1 06:29:05 INFO - mozversion INFO | platform_buildid: 20151126053016 06:29:05 INFO - mozversion INFO | platform_changeset: 2b1a10c958156968bc4842471ffa9c919ee80e92 06:29:05 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/b2g-inbound 06:29:05 INFO - mozversion INFO | platform_version: 45.0a1 06:29:05 INFO - 2015-11-26 06:29:05,490 DEBUG : using testdate: 1448548145 06:29:05 INFO - 2015-11-26 06:29:05,490 DEBUG : actual date: 1448548145 06:29:05 INFO - 2015-11-26 06:29:05,498 INFO : starting webserver on 'localhost:36918' 06:29:05 INFO - 2015-11-26 06:29:05,499 INFO : Starting test suite talos-linux64-ix-065 06:29:05 INFO - 2015-11-26 06:29:05,499 INFO : Starting test dromaeo_css 06:29:05 INFO - 2015-11-26 06:29:05,500 DEBUG : operating with platform_type : linux_ 06:29:05 INFO - 2015-11-26 06:29:05,500 INFO : Initialising browser for dromaeo_css test... 06:29:05 INFO - 2015-11-26 06:29:05,514 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBEYEVO/profile http://localhost:36918/getInfo.html 06:29:09 INFO - 2015-11-26 06:29:09,957 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:29:09 INFO - 2015-11-26 06:29:09,957 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:29:09 INFO - 2015-11-26 06:29:09,958 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:29:09 INFO - 2015-11-26 06:29:09,958 DEBUG : BROWSER_OUTPUT: __metrics 06:29:10 INFO - 2015-11-26 06:29:10,140 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] 06:29:11 INFO - 2015-11-26 06:29:11,385 INFO : Browser initialized. 06:29:11 INFO - 2015-11-26 06:29:11,386 INFO : Running cycle 1/1 for dromaeo_css test... 06:29:11 INFO - 2015-11-26 06:29:11,386 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBEYEVO/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:29:23 INFO - 2015-11-26 06:29:23,082 DEBUG : BROWSER_OUTPUT: RSS: Main: 139198464 06:29:23 INFO - 2015-11-26 06:29:23,082 DEBUG : BROWSER_OUTPUT: 06:30:13 INFO - 2015-11-26 06:30:13,823 DEBUG : BROWSER_OUTPUT: 1448548213820 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] 06:32:23 INFO - 2015-11-26 06:32:23,836 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36918/tests/dromaeo/cssquery-ext.html) 06:32:24 INFO - 2015-11-26 06:32:24,986 DEBUG : BROWSER_OUTPUT: RSS: Main: 260886528 06:32:24 INFO - 2015-11-26 06:32:24,986 DEBUG : BROWSER_OUTPUT: 06:35:27 INFO - 2015-11-26 06:35:27,331 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/dromaeo/cssquery-ext.html (next: http://localhost:36918/tests/dromaeo/cssquery-jquery.html) 06:35:27 INFO - 2015-11-26 06:35:27,904 DEBUG : BROWSER_OUTPUT: RSS: Main: 223797248 06:35:27 INFO - 2015-11-26 06:35:27,904 DEBUG : BROWSER_OUTPUT: 06:38:29 INFO - 2015-11-26 06:38:29,879 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36918/tests/dromaeo/cssquery-mootools.html) 06:38:30 INFO - 2015-11-26 06:38:30,445 DEBUG : BROWSER_OUTPUT: RSS: Main: 214409216 06:38:30 INFO - 2015-11-26 06:38:30,445 DEBUG : BROWSER_OUTPUT: 06:41:32 INFO - 2015-11-26 06:41:32,382 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36918/tests/dromaeo/cssquery-prototype.html) 06:41:32 INFO - 2015-11-26 06:41:32,943 DEBUG : BROWSER_OUTPUT: RSS: Main: 201990144 06:41:32 INFO - 2015-11-26 06:41:32,943 DEBUG : BROWSER_OUTPUT: 06:44:35 INFO - 2015-11-26 06:44:35,423 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36918/tests/dromaeo/cssquery-yui.html) 06:44:36 INFO - 2015-11-26 06:44:36,012 DEBUG : BROWSER_OUTPUT: RSS: Main: 205127680 06:44:36 INFO - 2015-11-26 06:44:36,013 DEBUG : BROWSER_OUTPUT: 06:47:38 INFO - 2015-11-26 06:47:38,553 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/dromaeo/cssquery-yui.html (next: http://localhost:36918/tests/dromaeo/cssquery-dojo.html) 06:47:38 INFO - 2015-11-26 06:47:38,803 DEBUG : BROWSER_OUTPUT: RSS: Main: 282202112 06:47:38 INFO - 2015-11-26 06:47:38,803 DEBUG : BROWSER_OUTPUT: 06:47:38 INFO - 2015-11-26 06:47:38,803 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:47:38 INFO - 2015-11-26 06:47:38,804 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:47:38 INFO - 2015-11-26 06:47:38,804 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:47:38 INFO - 2015-11-26 06:47:38,804 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:47:38 INFO - 2015-11-26 06:47:38,805 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3165.7625678074146;3272.656371081035;3317;3318.971118953125;3338.0152854907806;5257.789688412468;5365.973170134144;5484.890302193954;5542.390337062925;5544.417836127209;5463.726813659321;5505.311836020497;5549.694766787825;5607.635503692257;5613.4386561343845;5029.597632189425;5079.212722028092;5101.770420331075;5117.385913690344;5133.460986596409;5131.948680513203;5138.768755405997;5214.165733482643;5216.452272511388;5222.686638801665;4936.30891675164;4982.950170498285;5045.915128247408;5120.539151476348;5123.615728820316;5050.015247026831;5052.040112378636;5057.317262169597;5060.165072762989;5092.898142037138;4883.34074899891;5048.066107770074;5049.394072711261;5068.087744206042;5075.923861142086;5287.550558202557;5299.152135658314;5369.060414427459;5386.703731294777;5388.595855310868;6006.099085137221;6022.939770602282;6030.12563178341;6036.124761909498;6037.72830222641;5717.056685646863;5862.267216597926;6021.849453763647;6024.367441418632;6034.607750496204;5567.777288908439;5580.302462192226;5662.745176467069;5674.517665998354;5688.203651488795;6144.17053697754;6144.600600960924;6145.692715364214;6149.754009839557;6149.7847575334645;5855.882882342329;5946.940530594725;5949.167116603679;5952;5955.970220148958;5683.602147849693;5920.2303700519515;5938.524918006549;5953.434423729739;6037.366076561898;5981.880362392728;6026.668533230714;6036.396360363929;6037.034074548052;6041.335453100155;5734.340550836624;6036.8188954331445;6040.093985902062;6048.727807248684;6058.575899687068;4461.821527138943;4467.888302792456;4526.298423744325;4546.5453454654935;4582.9541704582525;4084.1627466369323;4094.856680016245;4103.035786590149;4112.527059388209;4126.504819421689;5730.799422020209;5745.482906538348;5747.655140691489;5748.971255143698;5799.681017543991;5807.157962095473;5904.793332233351;5908;5964.671943043088;6053.092036194606;5706.743196556165;5707.8858422832145;5716.657000579979;5724.713764311768;5731.168980497804;5277.525022747971;5278.736063196825;5283.2075188722;5283.286756287852;5334.759935802898;5913.113033045078;6064.4541991221;6098.176746139215;6170.127795527211;6229.750809967551;5894.38108998549;5957.434043765836;5962.701864906824;5970.910436343545;6012.939870601238;6109.358517355614;6117.785877494266;6126.969365153146;6172.105044768394;6188.257409110755;5710;5921.792737254175;5946.345901950868;5947.583669143291;5959.880802383841;5914.58597898143;5980.222571065734;5987.191729116514;5987.970060149845;6030.7889223876955;5869.6771677558145;5893.970530147321;5894.528437724886;5901.970490147693;5907.70461476933;5636.577256705846;5730.398308177581;5739.512141468022;5847.181394604844;5870.589058765845;5706.144078388275;5756.769729210952;5761;5765.625234359753;5803.767849285981;5883.705814709162;5890.823275301748;5942.376050514634;5961.225040744675;6000.669963152069;5573.972130139324;5682.346530149086;5692.800751973661;5714.628549144292;5784.566157538286 06:47:38 INFO - 2015-11-26 06:47:38,805 DEBUG : BROWSER_OUTPUT: |1;ext.html;548.3620912405617;584.1238142785821;586.1881294407247;586.7623612436963;588.9440503152199;31192.68807311929;40037.79981100094;40358;40430.39354409683;40567;40621.796891015576;41329.17341653165;41398.58601413985;41399.79300103495;41433.378499322534;7552.3202911737935;7621.657025433841;7633.3893288537065;8642.91357086429;8859.734207973772;7596.582187979659;7672.117706463772;10146.746331341701;10300.793984120313;10317.071463568278;10055.145312648434;10149.594016239378;10151.644692435766;10154.390736555793;10171.491425428736;7766.961165194138;9966.152877005463;10049.045340692695;10064.396136231813;10068.194544436428;7616.428767842434;10396.376217426978;10652.627158049509;10675.359478431319;10691.625793097204;38635.22729545421;38958.80520597407;39055.804720976215;39136.41295380572;39206;3050.8932187373557;3218.8229647369385;3261.608606967179;3525.3301872644115;3675.4483439055593;28996.27509312263;29142.41715165685;29233.707662923312;29431.411371772443;29511.26221844471;3798.2783271178387;3890.8054597270307;3949.6050394960275;3964.86122985697;3969.0672691917375;261811;262807;262897.9999999981;263110;263690.68154659297;50012.249816252785;50319.49680503221;57710.422895770505;57903.42096579065;57950.710246449344;65653.67173164104;67258.66370668115;67344.32655673478;67363.32636673668;67525.66237168782;14825.629359266104;15083.849161508464;15095.094294342374;15158.393664253526;15167.696646067237;10429.322094063862;10431.165506759593;10434.947825260977;10439.530221140067;10477.004684554955;18623.44129676112;18747;18838.81161188398;18926;19013;7025.578465292099;7261.745838895719;7267.745628902963;7286.48994570375;7331.560106393634;853.7353420439559;878.872563478292;925.509479975614;937.634322614181;1068.931068931069;27537.17388478349;27748.86125569359;28119.297017574314;28165.859170704014;28469;996.8504724291414;1005.1154983614373;1018.9032041956002;1030.6907927621683;1117.3742704085575;14564.41742330295;14730.705385892223;14869.405223790927;14907.403703851942;14988.925055374653;313421;318471;319118;319142;319694;51211.7439412808;51386.48613513892;51489;51578.74210628923;51581.74209128931;1196.2284326609463;1208.7038675524377;1210.0803389424102;1219.0674134287278;1223.4922507159606;27233.31916702098;27434.5884811732;28256.15231543057;28347.574786378635;28501.144965650234;41732.3740143892;42071;43819;44582;44755.776221118685;307449;307772;310183;312649;312679;28701.425971480872;28857.422851543273;29042.709572904;29056.564151538165;29107.981220657177;42495;43340.34989475097;43421;44186.779066104464;44239;36292.45561316635;36505.63494365128;36938;37205.44191837179;37712.43431348587;34803;35605.64394356127;35804.64195358013;35951.46072808962;36688.08279793026;32764.34471310513;33076.66923330736;33359.83320083384;33369.66630333666;33468.33063338768;29943.70056299409;31605.051848444582;35512;35654.28691426209;35812.104697382776;25564.48871022606;25755.732801595215;25787.87106064458;25890.35274118162;25956 06:47:38 INFO - 2015-11-26 06:47:38,806 DEBUG : BROWSER_OUTPUT: |2;jquery.html;358.53569627332604;358.7363287983332;360.6429634661685;362.67540551206673;363.69994754327683;14004.579862604138;14054.297285135728;16487.340506379744;17681.381151659694;18831.798594238997;10046.799064018714;10281.280310378277;16449.506514804576;16760.41338553151;16769.161541922917;4697.741624210676;4802.807887684488;4804.615630749532;4811.254255590389;4812.350332705075;3638.108663377476;3730.3658378075793;4887.8778030549165;4929.679570827885;5198.8700282493055;5303.787848486056;5333.306670132877;5336.119540275849;5345.037893179227;5357.089294819871;5034.798608055673;5160.56135228506;5160.716160611165;5280.841574752763;5316.282301889234;4967.130752118364;4971;4974.502549744997;5000.474950130257;5004.649674522785;11181.105511559137;11337.829932551018;11354.375509346983;11367.147463940237;13650.590482285548;1967.2229469359522;2033.6034473277716;2045.6317862784692;2052.3022172461433;2113.704081428601;9294.07059294072;9625.71122866315;9627.663031793854;9640.325177237597;10136.341137826017;1773.9024353660543;1857.8792378495355;1949.5905859769466;1982.5241941933975;1987.9801201988084;59538.40461595372;60612.39387606156;62351.68824155896;63732.36267637311;63960.16239025627;10296;10310.896891031143;10341.74145646365;10358.844617330746;10359;13386.129901556367;13438.193708377334;13445.126066805626;13445.663858403617;13449.932750336186;7435.219301973323;7449.664765085584;7458.366038886756;7491.775246742606;7522.6614802334025;7053.682584283617;7095.609741464165;7095.609741464269;7164.247753985861;7288.344049035612;4417.734935903856;4469.195544801902;4490.4059059034225;4501.167284052461;4513.142502924499;1709.8119206887231;1716.5279548124365;1741.6690828742499;1765.7704498415123;1790.1675720789635;759.8708219602681;853.1298075962484;872.4154816273112;978.5987745024506;1019.5310157327564;16376.590585235226;16731.83268167327;16763.49709508717;16911.577210569834;16946.23741931616;784;836.431226765793;918.2241006349624;944.6127087893931;984.3306551544875;9094.317926155432;9238.630454781867;9266.907330926604;9374;9384.812303753886;496188.8620581039;504802;534718;572395;581918;74836.62581687166;78448;81027.68181659185;84817;85790.57104714436;1091.1816137896578;1103.3269705479652;1114.3035602748282;1120.2494328799723;1122.7866705325962;14799.92600036993;14820.703585928437;15091.924540377227;15155.965869352127;15283.61790955235;32544.122693162735;32816.50775238326;33356.833215833765;34424.31151377009;36232.63767362398;45038;46099.76950115228;46333;47075.29387059265;49464.25803612877;9487.952560237154;9693.806123877624;9786.804263914824;9790.363626364264;9800.31397802147;11050.668479945614;16455.25951332169;17249.827501725325;18140.909295453966;18301.359452419103;14395.712085758436;14795.778063328844;14838.18389988561;14861.554153375417;15096.924515377354;14084.225367604471;14482.582812242366;14601.196934168724;14619.903507237212;14630.926845365704;14087.929560352133;14555.563333100024;14839.777403339174;15174.241287935338;15350.462733804263;9417.764555886157;9501.477418741759;9507.286953478379;9516.19112375428;9544.379615324982;13438.664033399244;13648.65878353049;14099.859001410265;14294.499692510712;14804.111753294837 06:47:38 INFO - 2015-11-26 06:47:38,808 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1379.3999610169571;1724.0690027385212;1757.5430388099098;1785.8571314294857;1795.5690634247785;6611.239707433647;7712.383009359251;7886.842263154734;7902.565358905265;7906.565138917357;7745.883811742814;7857.332126769217;7910.762677119696;7916.881246781289;7923.801904952379;1954.4722924810292;1974.9308774192907;1979.15885748557;2307.9538409231805;2351.376885125443;2254.211026140854;2287.965680514794;2289.5192009677903;2290.358699564125;2291.86248825071;2217.3347995601334;2230.888455577223;2293.7820907013806;2294.2773026496684;2296.7588403217674;1946.1436967734223;1999.070432249004;2206.9889650551804;2243.170027089979;2244.450109723128;2224.8998795054263;2250.5723912456747;2252.358077947777;2254.6054440472853;2256.4471704432353;7827;7846.489978151402;7863.960680196619;7869.449138560303;7877.763667089939;4803.18345881201;4921.311016457699;4922.384701912261;4927.605791536705;4935.0376676548085;2959.1714319990438;3004.8347340896244;3056.862441190152;3058.8776448942017;3130.906072817819;937.0957026469422;946.1153821177221;965.2422848064306;968.1480297338368;985;10699;10795.352278863293;10798.784024319548;10800.5679772809;10807;4204.915901681949;4260.595243451867;4328.545502722169;4332.415123958288;4378;8898.332625053146;8906.86639700405;8907.866382004275;8918;8918.554072296489;4803.087413391444;4813.374261346003;4827.589654879374;4832.758362081881;4838.056578967138;4412.9117417652105;4415.183191109655;4417.514073451917;4419.027813880941;4422.204003279377;3389;3401.744869134825;3413.573303337083;3427.7771944823508;3443.27691184854;1073.9731506712394;1077.49357801833;1079.2930630437077;1080.6001779341693;1091.0960374147164;943.565959658551;1246.1214843535286;1257.8427696537933;1262.659082047842;1265.519102740971;6662.600243985376;6782.626955517494;7132.821679457951;7363.374113200331;7388.445866559922;561.5591760468013;572.241779641976;573.3492486028388;599.4035934245367;599.7091410665823;5499.257600223998;5502.174673798962;5525.392206857243;5558.805441809517;5594.804181853616;9186.954065229766;9530.52347382628;9588.616455341707;9631.470269135265;9655.468949207861;9454.48000359973;9525;9532.094451027142;9544.188743956842;9578.233741300679;7236.963815180996;7476.738314159087;7514.173440921603;7531.623418829036;7537.2085930977555;6717.832054198684;6727.495437842083;6765.8985115224295;6775.525713200029;6799.286074962058;8207.220314070153;8372.204640559137;8576.142385761374;8582.055973842997;8608.870866936875;8507.489550626982;8552.786180345542;8571.614277357408;8697.521636310064;8699.521526316114;6680.331966803476;6743.291954344723;6754.628495432798;6755.054292399166;6759.391654751096;7649.770506884803;7756.534607923317;7759.961200194189;7761.534307941542;7781.494202876795;6943.583384996917;7039.296070392919;7192.676329565287;7272.236415176542;7338.339549440576;6816.591004539743;6840.41856442208;6842.213145488333;6918.169819621678;7004.929950700632;6261;6341.8097457076365;6790.490713196431;6857.245702972569;6897.068895699217;6336.683165841781;6448.967755161194;6527.575707579183;6533.150690410216;6546.574472659262;6383.93616063852;6413.839654008687;6454.548181627429;6455.741770329134;6455.870882582416 06:47:38 INFO - 2015-11-26 06:47:38,811 DEBUG : BROWSER_OUTPUT: |4;prototype.html;341.8376271271146;347.57075012359746;348.73496142931367;349.24911440403145;352.1846924370084;5988.101784732287;6044.184035155258;6053.303870054945;6082.39176082391;6098.847528811782;5922.58541902067;5942.049272116462;5961.314448838385;5970.104484327353;6009.218801555802;4620.722756634605;4627.005193883315;4633.2123538998285;4638.5361463853515;4643.303504474322;4430.468343798732;4568.7944042518;4584.3352713856475;4587.059652771174;4597.126545956275;4546.249868771649;4552.613027892633;4563.771811409433;4567.703099298552;4580.725156490621;4343.652507799368;4423.800928958172;4461.21928662486;4566.931496027562;4568.56598623132;3926.5288165420047;3949.7163421887713;3953;3959.2477429288633;3967.861124860616;5645.435456454363;5647.656222667588;5648.717564121818;5650.802221922213;5660.433956604348;4977.228529577921;5119.360079990001;5176.120059589841;5238.13570760824;5261.3686357637325;6275.121482992385;6309.14826498426;6316.368363163647;6328.240611126648;6347.365263473662;4371.169477799208;4372.409724687158;4374.737515749065;4377.956220437819;4381.408509851193;6459.5478316517865;6547.770828020996;6608.867822643569;6609.966950165219;6620.536562440631;4458.866234012985;4458.888527786831;4460.487043989918;4464.1071785643;4467.128909862546;4506;4517.661175411858;4518.570735780096;4519.163954668397;4520.977395113004;5270.49930256625;5275.3142091528625;5277.1292736698015;5280.181571856291;5280.9471905281225;6280.995040793451;6414.166158399472;6429.292777794439;6438.581492202992;6452.870942581216;1608.7345587978086;1620.1170362152654;1622.9837701623069;1625.2036502113879;1626.699060673779;1058.6559368205365;1080.0927221134284;1081.7944590527775;1085.7611325508376;1103.5309993252836;6206.689665516707;6212.285587157535;6226.62640241587;6227.439530442282;6236.844078898064;6652.8669426611195;6653.567518111307;6654.434373078343;6658.201015878126;6659.9001014984815;6064.241969753781;6224;6230.906536401958;6233.438990490878;6233.812985610439;3592.263585964871;3599.0822340303057;3610.5847827500174;3612.7832330060282;3615.439606860941;6807.5575087619145;6848.3494068063455;6854.143232095988;6880.105586273852;6888.862222755517;6365.872682546323;6573.112629795012;6771.322867713189;6771.492138089565;6784.491163162684;6723.966380168068;6736.157980252468;6794.5583537070925;6826.351496607815;6895.655217239118;6693.899591506034;6697.497687673346;6698.531102822755;6700.530962832555;6705.463562914858;6454;6609.768658096944;6623.768168114286;6630.867382652416;6633.668316584248;6875.278095800069;6882.414994725505;6886.414654754402;6894.413974812197;6900.896486552605;4418.7569683666425;4442.533533979015;4451.844185453494;4454.109178164315;4461.4869290030765;6652.101966234496;6657.40083392497;6667.43326817226;6668.766593169215;6673;6321.241451025906;6514.6416947068365;6528.314526974599;6533.607983521004;6542.574732642363;6491.318411566717;6494.935050649433;6504.089427480062;6505.512086593619;6514.348565143448;6249.593776404519;6391.32891046452;6392.520560957853;6395.232572091379;6400.743970241138;6519.641419721961;6550.279469258282;6560.770373036921;6565.916623757026;6578.736850525926;6410.903836442364;6416.903746443901;6422;6422.197225346832;6423.935760642334 06:47:38 INFO - 2015-11-26 06:47:38,813 DEBUG : BROWSER_OUTPUT: |5;yui.html;731.6926890705903;750.8460765543065;856.216561845911;874.0996773323475;877.784942689041;13190;13746.175229486238;13769;13782.448702051932;13791.931040344809;12878.806817897716;12969.481220751159;13001.934990325035;13003.934980325084;13073.869261307407;667.3826710292984;673.0442771264791;802.8394321135767;810.5217921426357;810.8094597769519;818.2594751749676;820.6922404098464;825.8678611422174;830.5847076461769;831.1854382704953;849.4605925237457;856.6573370651759;856.9742907712747;858.2576071697998;858.433433933604;697.5953946710921;705.0798707686467;829.9336053115736;832.1304237072274;835.1189495082685;616.1804799616499;616.6639181646013;617.3548641669466;617.9196704428396;618.3322012226785;12729.745405091939;12786.168899021626;12787;12809.423575939012;12844.036697247744;12041.277523348539;12975.935120324433;13129.409176587076;13298.667533311647;13318.600441986757;10092.545835437422;10188.541515631814;10218.846717299246;10461.267711260214;10533.21000924926;3520.876769313087;3544.6809787119023;3550.343186510504;3554.6445355464507;3576.517170182044;47035.76482117602;47738.28392574114;47770.28344574799;47783.52216477825;47817.76091119522;10005.549750261256;10027.598896044077;10031;10038.380586851554;10296.558481812552;18569.628607427774;18607.348742793936;18621.7206741899;18629.813701862808;18677.626447471248;7558.697652093964;7593.164751877289;7719.65261563231;7796.805079873048;7807.882881756778;4088.6115818997146;4177.56135605757;4225.154969006149;4314.935275970863;4368.563143685669;2928.2386579489485;2970.019893435161;2974.2415684000443;2977.419403216352;2977.478941185284;507.0442216422071;508.3366207099696;517.2913109040563;523.5183631059434;525.2069375243334;555.2642748358442;628.1206311164407;632.3644737039247;650.7494614573424;673.2089794491453;8238.299744521662;8323;8587.87118193215;8645.438046526955;8751.56242187888;574.6896675795024;612.2928018139056;660.3561527510639;665.1320027364336;674.9696263668146;3757.9436308455397;3866.980665096713;3986.4219688145636;4097.549269580344;4209.452771139732;56016.71991640097;56233.43766562282;56818.43181568132;57018;57334;50015;50040;50257.74871125621;50349;50440.49559504431;44355;44734;45007.54992450034;46116.53883461189;46296;7715.886058887493;7774.883376749466;7999.280064794197;8111.878321825059;8293.295069919124;33051.008469745946;33373;33827;34753.82623086868;34793.65206347904;44295.77852110848;44751.328730068424;44857.327140092275;45151.32273015842;45934.77032614816;10744.892551074388;10761.677149685522;11040.227184097357;11106.16703747206;11377.2604780689;11710.88289117132;11926.224795388272;12340.81488777687;12607.684807879876;12708.364581771059;11437;11745.354005529778;11899.881001189877;12177.634670959886;12187.75624487523;11430;11906.33130962706;11949.64151075469;11988.580399685969;12551.93724031374;6894.655267236719;10696.197785165989;11543.134264929995;11704.648860534198;12016.939915300367;9448.858267125861;9612.855807163038;9725.96798556323;9749.707508774749;10098.747531311483;8013.879791803244;9454.527273636153;9882.160016398686;10245.641402550875;10507.264491485828 06:47:38 INFO - 2015-11-26 06:47:38,813 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:47:38 INFO - 2015-11-26 06:47:38,813 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:47:38 INFO - 2015-11-26 06:47:38,813 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,915 06:47:38 INFO - 2015-11-26 06:47:38,813 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:47:38 INFO - 2015-11-26 06:47:38,813 DEBUG : BROWSER_OUTPUT: __startTimestamp1448549258799__endTimestamp 06:47:38 INFO - 2015-11-26 06:47:38,813 DEBUG : BROWSER_OUTPUT: 06:47:38 INFO - 2015-11-26 06:47:38,814 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:47:38 INFO - 2015-11-26 06:47:38,814 DEBUG : BROWSER_OUTPUT: Number of tests: 6 06:47:38 INFO - 2015-11-26 06:47:38,814 DEBUG : BROWSER_OUTPUT: 06:47:38 INFO - 2015-11-26 06:47:38,814 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5546.64 Median:5739.51 stddev:624.73 (10.9%) stddev-sans-first:598.07 06:47:38 INFO - 2015-11-26 06:47:38,814 DEBUG : BROWSER_OUTPUT: Values: 3165.8 3272.7 3317.0 3319.0 3338.0 5257.8 5366.0 5484.9 5542.4 5544.4 5463.7 5505.3 5549.7 5607.6 5613.4 5029.6 5079.2 5101.8 5117.4 5133.5 5131.9 5138.8 5214.2 5216.5 5222.7 4936.3 4983.0 5045.9 5120.5 5123.6 5050.0 5052.0 5057.3 5060.2 5092.9 4883.3 5048.1 5049.4 5068.1 5075.9 5287.6 5299.2 5369.1 5386.7 5388.6 6006.1 6022.9 6030.1 6036.1 6037.7 5717.1 5862.3 6021.8 6024.4 6034.6 5567.8 5580.3 5662.7 5674.5 5688.2 6144.2 6144.6 6145.7 6149.8 6149.8 5855.9 5946.9 5949.2 5952.0 5956.0 5683.6 5920.2 5938.5 5953.4 6037.4 5981.9 6026.7 6036.4 6037.0 6041.3 5734.3 6036.8 6040.1 6048.7 6058.6 4461.8 4467.9 4526.3 4546.5 4583.0 4084.2 4094.9 4103.0 4112.5 4126.5 5730.8 5745.5 5747.7 5749.0 5799.7 5807.2 5904.8 5908.0 5964.7 6053.1 5706.7 5707.9 5716.7 5724.7 5731.2 5277.5 5278.7 5283.2 5283.3 5334.8 5913.1 6064.5 6098.2 6170.1 6229.8 5894.4 5957.4 5962.7 5970.9 6012.9 6109.4 6117.8 6127.0 6172.1 6188.3 5710.0 5921.8 5946.3 5947.6 5959.9 5914.6 5980.2 5987.2 5988.0 6030.8 5869.7 5894.0 5894.5 5902.0 5907.7 5636.6 5730.4 5739.5 5847.2 5870.6 5706.1 5756.8 5761.0 5765.6 5803.8 5883.7 5890.8 5942.4 5961.2 6000.7 5574.0 5682.3 5692.8 5714.6 5784.6 06:47:38 INFO - 2015-11-26 06:47:38,814 DEBUG : BROWSER_OUTPUT: 06:47:38 INFO - 2015-11-26 06:47:38,815 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:46497.20 Median:18012.98 stddev:77941.79 (432.7%) stddev-sans-first:78084.08 06:47:38 INFO - 2015-11-26 06:47:38,816 DEBUG : BROWSER_OUTPUT: Values: 548.4 584.1 586.2 586.8 588.9 31192.7 40037.8 40358.0 40430.4 40567.0 40621.8 41329.2 41398.6 41399.8 41433.4 7552.3 7621.7 7633.4 8642.9 8859.7 7596.6 7672.1 10146.7 10300.8 10317.1 10055.1 10149.6 10151.6 10154.4 10171.5 7767.0 9966.2 10049.0 10064.4 10068.2 7616.4 10396.4 10652.6 10675.4 10691.6 38635.2 38958.8 39055.8 39136.4 39206.0 3050.9 3218.8 3261.6 3525.3 3675.4 28996.3 29142.4 29233.7 29431.4 29511.3 3798.3 3890.8 3949.6 3964.9 3969.1 261811.0 262807.0 262898.0 263110.0 263690.7 50012.2 50319.5 57710.4 57903.4 57950.7 65653.7 67258.7 67344.3 67363.3 67525.7 14825.6 15083.8 15095.1 15158.4 15167.7 10429.3 10431.2 10434.9 10439.5 10477.0 18623.4 18747.0 18838.8 18926.0 19013.0 7025.6 7261.7 7267.7 7286.5 7331.6 853.7 878.9 925.5 937.6 1068.9 27537.2 27748.9 28119.3 28165.9 28469.0 996.9 1005.1 1018.9 1030.7 1117.4 14564.4 14730.7 14869.4 14907.4 14988.9 313421.0 318471.0 319118.0 319142.0 319694.0 51211.7 51386.5 51489.0 51578.7 51581.7 1196.2 1208.7 1210.1 1219.1 1223.5 27233.3 27434.6 28256.2 28347.6 28501.1 41732.4 42071.0 43819.0 44582.0 44755.8 307449.0 307772.0 310183.0 312649.0 312679.0 28701.4 28857.4 29042.7 29056.6 29108.0 42495.0 43340.3 43421.0 44186.8 44239.0 36292.5 36505.6 36938.0 37205.4 37712.4 34803.0 35605.6 35804.6 35951.5 36688.1 32764.3 33076.7 33359.8 33369.7 33468.3 29943.7 31605.1 35512.0 35654.3 35812.1 25564.5 25755.7 25787.9 25890.4 25956.0 06:47:38 INFO - 2015-11-26 06:47:38,816 DEBUG : BROWSER_OUTPUT: 06:47:38 INFO - 2015-11-26 06:47:38,816 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28462.35 Median:18298.17 stddev:88184.82 (481.9%) stddev-sans-first:88406.95 06:47:38 INFO - 2015-11-26 06:47:38,817 DEBUG : BROWSER_OUTPUT: Values: 358.5 358.7 360.6 362.7 363.7 14004.6 14054.3 16487.3 17681.4 18831.8 10046.8 10281.3 16449.5 16760.4 16769.2 4697.7 4802.8 4804.6 4811.3 4812.4 3638.1 3730.4 4887.9 4929.7 5198.9 5303.8 5333.3 5336.1 5345.0 5357.1 5034.8 5160.6 5160.7 5280.8 5316.3 4967.1 4971.0 4974.5 5000.5 5004.6 11181.1 11337.8 11354.4 11367.1 13650.6 1967.2 2033.6 2045.6 2052.3 2113.7 9294.1 9625.7 9627.7 9640.3 10136.3 1773.9 1857.9 1949.6 1982.5 1988.0 59538.4 60612.4 62351.7 63732.4 63960.2 10296.0 10310.9 10341.7 10358.8 10359.0 13386.1 13438.2 13445.1 13445.7 13449.9 7435.2 7449.7 7458.4 7491.8 7522.7 7053.7 7095.6 7095.6 7164.2 7288.3 4417.7 4469.2 4490.4 4501.2 4513.1 1709.8 1716.5 1741.7 1765.8 1790.2 759.9 853.1 872.4 978.6 1019.5 16376.6 16731.8 16763.5 16911.6 16946.2 784.0 836.4 918.2 944.6 984.3 9094.3 9238.6 9266.9 9374.0 9384.8 496188.9 504802.0 534718.0 572395.0 581918.0 74836.6 78448.0 81027.7 84817.0 85790.6 1091.2 1103.3 1114.3 1120.2 1122.8 14799.9 14820.7 15091.9 15156.0 15283.6 32544.1 32816.5 33356.8 34424.3 36232.6 45038.0 46099.8 46333.0 47075.3 49464.3 9488.0 9693.8 9786.8 9790.4 9800.3 11050.7 16455.3 17249.8 18140.9 18301.4 14395.7 14795.8 14838.2 14861.6 15096.9 14084.2 14482.6 14601.2 14619.9 14630.9 14087.9 14555.6 14839.8 15174.2 15350.5 9417.8 9501.5 9507.3 9516.2 9544.4 13438.7 13648.7 14099.9 14294.5 14804.1 06:47:38 INFO - 2015-11-26 06:47:38,817 DEBUG : BROWSER_OUTPUT: 06:47:38 INFO - 2015-11-26 06:47:38,818 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5429.69 Median:6455.14 stddev:2865.81 (44.4%) stddev-sans-first:2857.68 06:47:38 INFO - 2015-11-26 06:47:38,818 DEBUG : BROWSER_OUTPUT: Values: 1379.4 1724.1 1757.5 1785.9 1795.6 6611.2 7712.4 7886.8 7902.6 7906.6 7745.9 7857.3 7910.8 7916.9 7923.8 1954.5 1974.9 1979.2 2308.0 2351.4 2254.2 2288.0 2289.5 2290.4 2291.9 2217.3 2230.9 2293.8 2294.3 2296.8 1946.1 1999.1 2207.0 2243.2 2244.5 2224.9 2250.6 2252.4 2254.6 2256.4 7827.0 7846.5 7864.0 7869.4 7877.8 4803.2 4921.3 4922.4 4927.6 4935.0 2959.2 3004.8 3056.9 3058.9 3130.9 937.1 946.1 965.2 968.1 985.0 10699.0 10795.4 10798.8 10800.6 10807.0 4204.9 4260.6 4328.5 4332.4 4378.0 8898.3 8906.9 8907.9 8918.0 8918.6 4803.1 4813.4 4827.6 4832.8 4838.1 4412.9 4415.2 4417.5 4419.0 4422.2 3389.0 3401.7 3413.6 3427.8 3443.3 1074.0 1077.5 1079.3 1080.6 1091.1 943.6 1246.1 1257.8 1262.7 1265.5 6662.6 6782.6 7132.8 7363.4 7388.4 561.6 572.2 573.3 599.4 599.7 5499.3 5502.2 5525.4 5558.8 5594.8 9187.0 9530.5 9588.6 9631.5 9655.5 9454.5 9525.0 9532.1 9544.2 9578.2 7237.0 7476.7 7514.2 7531.6 7537.2 6717.8 6727.5 6765.9 6775.5 6799.3 8207.2 8372.2 8576.1 8582.1 8608.9 8507.5 8552.8 8571.6 8697.5 8699.5 6680.3 6743.3 6754.6 6755.1 6759.4 7649.8 7756.5 7760.0 7761.5 7781.5 6943.6 7039.3 7192.7 7272.2 7338.3 6816.6 6840.4 6842.2 6918.2 7004.9 6261.0 6341.8 6790.5 6857.2 6897.1 6336.7 6449.0 6527.6 6533.2 6546.6 6383.9 6413.8 6454.5 6455.7 6455.9 06:47:38 INFO - 2015-11-26 06:47:38,818 DEBUG : BROWSER_OUTPUT: 06:47:38 INFO - 2015-11-26 06:47:38,818 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5348.99 Median:6152.77 stddev:1633.15 (26.5%) stddev-sans-first:1593.90 06:47:38 INFO - 2015-11-26 06:47:38,818 DEBUG : BROWSER_OUTPUT: Values: 341.8 347.6 348.7 349.2 352.2 5988.1 6044.2 6053.3 6082.4 6098.8 5922.6 5942.0 5961.3 5970.1 6009.2 4620.7 4627.0 4633.2 4638.5 4643.3 4430.5 4568.8 4584.3 4587.1 4597.1 4546.2 4552.6 4563.8 4567.7 4580.7 4343.7 4423.8 4461.2 4566.9 4568.6 3926.5 3949.7 3953.0 3959.2 3967.9 5645.4 5647.7 5648.7 5650.8 5660.4 4977.2 5119.4 5176.1 5238.1 5261.4 6275.1 6309.1 6316.4 6328.2 6347.4 4371.2 4372.4 4374.7 4378.0 4381.4 6459.5 6547.8 6608.9 6610.0 6620.5 4458.9 4458.9 4460.5 4464.1 4467.1 4506.0 4517.7 4518.6 4519.2 4521.0 5270.5 5275.3 5277.1 5280.2 5280.9 6281.0 6414.2 6429.3 6438.6 6452.9 1608.7 1620.1 1623.0 1625.2 1626.7 1058.7 1080.1 1081.8 1085.8 1103.5 6206.7 6212.3 6226.6 6227.4 6236.8 6652.9 6653.6 6654.4 6658.2 6659.9 6064.2 6224.0 6230.9 6233.4 6233.8 3592.3 3599.1 3610.6 3612.8 3615.4 6807.6 6848.3 6854.1 6880.1 6888.9 6365.9 6573.1 6771.3 6771.5 6784.5 6724.0 6736.2 6794.6 6826.4 6895.7 6693.9 6697.5 6698.5 6700.5 6705.5 6454.0 6609.8 6623.8 6630.9 6633.7 6875.3 6882.4 6886.4 6894.4 6900.9 4418.8 4442.5 4451.8 4454.1 4461.5 6652.1 6657.4 6667.4 6668.8 6673.0 6321.2 6514.6 6528.3 6533.6 6542.6 6491.3 6494.9 6504.1 6505.5 6514.3 6249.6 6391.3 6392.5 6395.2 6400.7 6519.6 6550.3 6560.8 6565.9 6578.7 6410.9 6416.9 6422.0 6422.2 6423.9 06:47:38 INFO - 2015-11-26 06:47:38,818 DEBUG : BROWSER_OUTPUT: 06:47:38 INFO - 2015-11-26 06:47:38,818 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13682.38 Median:44804.33 stddev:15725.10 (35.1%) stddev-sans-first:15739.14 06:47:38 INFO - 2015-11-26 06:47:38,819 DEBUG : BROWSER_OUTPUT: Values: 731.7 750.8 856.2 874.1 877.8 13190.0 13746.2 13769.0 13782.4 13791.9 12878.8 12969.5 13001.9 13003.9 13073.9 667.4 673.0 802.8 810.5 810.8 818.3 820.7 825.9 830.6 831.2 849.5 856.7 857.0 858.3 858.4 697.6 705.1 829.9 832.1 835.1 616.2 616.7 617.4 617.9 618.3 12729.7 12786.2 12787.0 12809.4 12844.0 12041.3 12975.9 13129.4 13298.7 13318.6 10092.5 10188.5 10218.8 10461.3 10533.2 3520.9 3544.7 3550.3 3554.6 3576.5 47035.8 47738.3 47770.3 47783.5 47817.8 10005.5 10027.6 10031.0 10038.4 10296.6 18569.6 18607.3 18621.7 18629.8 18677.6 7558.7 7593.2 7719.7 7796.8 7807.9 4088.6 4177.6 4225.2 4314.9 4368.6 2928.2 2970.0 2974.2 2977.4 2977.5 507.0 508.3 517.3 523.5 525.2 555.3 628.1 632.4 650.7 673.2 8238.3 8323.0 8587.9 8645.4 8751.6 574.7 612.3 660.4 665.1 675.0 3757.9 3867.0 3986.4 4097.5 4209.5 56016.7 56233.4 56818.4 57018.0 57334.0 50015.0 50040.0 50257.7 50349.0 50440.5 44355.0 44734.0 45007.5 46116.5 46296.0 7715.9 7774.9 7999.3 8111.9 8293.3 33051.0 33373.0 33827.0 34753.8 34793.7 44295.8 44751.3 44857.3 45151.3 45934.8 10744.9 10761.7 11040.2 11106.2 11377.3 11710.9 11926.2 12340.8 12607.7 12708.4 11437.0 11745.4 11899.9 12177.6 12187.8 11430.0 11906.3 11949.6 11988.6 12551.9 6894.7 10696.2 11543.1 11704.6 12016.9 9448.9 9612.9 9726.0 9749.7 10098.7 8013.9 9454.5 9882.2 10245.6 10507.3 06:47:38 INFO - 2015-11-26 06:47:38,820 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:47:38 INFO - 2015-11-26 06:47:38,820 DEBUG : BROWSER_OUTPUT: 06:47:39 INFO - 2015-11-26 06:47:39,509 INFO : Browser exited with error code: 0 06:47:39 INFO - 2015-11-26 06:47:39,528 INFO : Completed test dromaeo_css (00:18:34) 06:47:39 INFO - 2015-11-26 06:47:39,528 INFO : Starting test kraken 06:47:39 INFO - 2015-11-26 06:47:39,528 DEBUG : operating with platform_type : linux_ 06:47:39 INFO - 2015-11-26 06:47:39,528 INFO : Initialising browser for kraken test... 06:47:39 INFO - 2015-11-26 06:47:39,534 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRfPeWb/profile http://localhost:36918/getInfo.html 06:47:43 INFO - 2015-11-26 06:47:43,554 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:47:43 INFO - 2015-11-26 06:47:43,554 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:47:43 INFO - 2015-11-26 06:47:43,555 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:47:43 INFO - 2015-11-26 06:47:43,555 DEBUG : BROWSER_OUTPUT: __metrics 06:47:43 INFO - 2015-11-26 06:47:43,716 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] 06:47:44 INFO - 2015-11-26 06:47:44,954 INFO : Browser initialized. 06:47:44 INFO - 2015-11-26 06:47:44,954 INFO : Running cycle 1/1 for kraken test... 06:47:44 INFO - 2015-11-26 06:47:44,954 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRfPeWb/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:47:56 INFO - 2015-11-26 06:47:56,695 DEBUG : BROWSER_OUTPUT: RSS: Main: 138858496 06:47:56 INFO - 2015-11-26 06:47:56,695 DEBUG : BROWSER_OUTPUT: 06:47:59 INFO - 2015-11-26 06:47:59,129 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36918/tests/kraken/driver.html?testName=audio-beat-detection) 06:47:59 INFO - 2015-11-26 06:47:59,710 DEBUG : BROWSER_OUTPUT: RSS: Main: 178057216 06:47:59 INFO - 2015-11-26 06:47:59,710 DEBUG : BROWSER_OUTPUT: 06:48:02 INFO - 2015-11-26 06:48:02,307 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36918/tests/kraken/driver.html?testName=audio-dft) 06:48:02 INFO - 2015-11-26 06:48:02,880 DEBUG : BROWSER_OUTPUT: RSS: Main: 167235584 06:48:02 INFO - 2015-11-26 06:48:02,880 DEBUG : BROWSER_OUTPUT: 06:48:05 INFO - 2015-11-26 06:48:05,785 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36918/tests/kraken/driver.html?testName=audio-fft) 06:48:06 INFO - 2015-11-26 06:48:06,347 DEBUG : BROWSER_OUTPUT: RSS: Main: 148746240 06:48:06 INFO - 2015-11-26 06:48:06,347 DEBUG : BROWSER_OUTPUT: 06:48:08 INFO - 2015-11-26 06:48:08,324 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36918/tests/kraken/driver.html?testName=audio-oscillator) 06:48:08 INFO - 2015-11-26 06:48:08,889 DEBUG : BROWSER_OUTPUT: RSS: Main: 153575424 06:48:08 INFO - 2015-11-26 06:48:08,889 DEBUG : BROWSER_OUTPUT: 06:48:11 INFO - 2015-11-26 06:48:11,083 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36918/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:48:11 INFO - 2015-11-26 06:48:11,643 DEBUG : BROWSER_OUTPUT: RSS: Main: 154202112 06:48:11 INFO - 2015-11-26 06:48:11,643 DEBUG : BROWSER_OUTPUT: 06:48:14 INFO - 2015-11-26 06:48:14,547 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36918/tests/kraken/driver.html?testName=imaging-darkroom) 06:48:15 INFO - 2015-11-26 06:48:15,115 DEBUG : BROWSER_OUTPUT: RSS: Main: 155238400 06:48:15 INFO - 2015-11-26 06:48:15,115 DEBUG : BROWSER_OUTPUT: 06:48:18 INFO - 2015-11-26 06:48:18,520 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36918/tests/kraken/driver.html?testName=imaging-desaturate) 06:48:19 INFO - 2015-11-26 06:48:19,085 DEBUG : BROWSER_OUTPUT: RSS: Main: 155852800 06:48:19 INFO - 2015-11-26 06:48:19,085 DEBUG : BROWSER_OUTPUT: 06:48:21 INFO - 2015-11-26 06:48:21,895 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36918/tests/kraken/driver.html?testName=json-parse-financial) 06:48:22 INFO - 2015-11-26 06:48:22,477 DEBUG : BROWSER_OUTPUT: RSS: Main: 155889664 06:48:22 INFO - 2015-11-26 06:48:22,477 DEBUG : BROWSER_OUTPUT: 06:48:24 INFO - 2015-11-26 06:48:24,037 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36918/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:48:24 INFO - 2015-11-26 06:48:24,598 DEBUG : BROWSER_OUTPUT: RSS: Main: 165130240 06:48:24 INFO - 2015-11-26 06:48:24,598 DEBUG : BROWSER_OUTPUT: 06:48:26 INFO - 2015-11-26 06:48:26,387 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36918/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:48:26 INFO - 2015-11-26 06:48:26,981 DEBUG : BROWSER_OUTPUT: RSS: Main: 162963456 06:48:26 INFO - 2015-11-26 06:48:26,981 DEBUG : BROWSER_OUTPUT: 06:48:29 INFO - 2015-11-26 06:48:29,022 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36918/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:48:29 INFO - 2015-11-26 06:48:29,603 DEBUG : BROWSER_OUTPUT: RSS: Main: 170643456 06:48:29 INFO - 2015-11-26 06:48:29,603 DEBUG : BROWSER_OUTPUT: 06:48:32 INFO - 2015-11-26 06:48:32,018 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36918/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:48:32 INFO - 2015-11-26 06:48:32,598 DEBUG : BROWSER_OUTPUT: RSS: Main: 179593216 06:48:32 INFO - 2015-11-26 06:48:32,598 DEBUG : BROWSER_OUTPUT: 06:48:35 INFO - 2015-11-26 06:48:35,172 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36918/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:48:35 INFO - 2015-11-26 06:48:35,741 DEBUG : BROWSER_OUTPUT: RSS: Main: 179798016 06:48:35 INFO - 2015-11-26 06:48:35,742 DEBUG : BROWSER_OUTPUT: 06:48:37 INFO - 2015-11-26 06:48:37,423 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36918/tests/kraken/driver.html?testName=ai-astar) 06:48:37 INFO - 2015-11-26 06:48:37,673 DEBUG : BROWSER_OUTPUT: RSS: Main: 237645824 06:48:37 INFO - 2015-11-26 06:48:37,673 DEBUG : BROWSER_OUTPUT: 06:48:37 INFO - 2015-11-26 06:48:37,675 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:48:37 INFO - 2015-11-26 06:48:37,675 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:48:37 INFO - 2015-11-26 06:48:37,675 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:48:37 INFO - 2015-11-26 06:48:37,675 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:48:37 INFO - 2015-11-26 06:48:37,675 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;109;104;110;111;111;98;110;109;101 06:48:37 INFO - 2015-11-26 06:48:37,675 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;158;125;151;159;132;150;146;124;160;122 06:48:37 INFO - 2015-11-26 06:48:37,675 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;161;160;159;162;161;159;166;161;162 06:48:37 INFO - 2015-11-26 06:48:37,675 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;83;94;71;71;72;109;91;73 06:48:37 INFO - 2015-11-26 06:48:37,676 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;94;133;93;93;93;137;93;93;135 06:48:37 INFO - 2015-11-26 06:48:37,676 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;111;110;110;110;110;110;110;109;110;110 06:48:37 INFO - 2015-11-26 06:48:37,676 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;147;146;146;146;146;147;146;146;146 06:48:37 INFO - 2015-11-26 06:48:37,676 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;96;95;95;96;96;96;96;95 06:48:37 INFO - 2015-11-26 06:48:37,676 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;58;59;58;57;60;58;58;57;66;57 06:48:37 INFO - 2015-11-26 06:48:37,676 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;70;70;69;70;71;71;70;70;69;71 06:48:37 INFO - 2015-11-26 06:48:37,676 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;86;87;85;86;86;86;84;86;91 06:48:37 INFO - 2015-11-26 06:48:37,677 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;125;119;113;120;126;118;124;124;130;121 06:48:37 INFO - 2015-11-26 06:48:37,677 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;151;150;165;152;141;153;165;142;143 06:48:37 INFO - 2015-11-26 06:48:37,677 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;68;67;68;69;67;66;67;66;65;66 06:48:37 INFO - 2015-11-26 06:48:37,677 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:48:37 INFO - 2015-11-26 06:48:37,677 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:48:37 INFO - 2015-11-26 06:48:37,677 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,905 06:48:37 INFO - 2015-11-26 06:48:37,677 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:48:37 INFO - 2015-11-26 06:48:37,678 DEBUG : BROWSER_OUTPUT: __startTimestamp1448549317673__endTimestamp 06:48:37 INFO - 2015-11-26 06:48:37,678 DEBUG : BROWSER_OUTPUT: 06:48:37 INFO - 2015-11-26 06:48:37,681 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:48:37 INFO - 2015-11-26 06:48:37,681 DEBUG : BROWSER_OUTPUT: Number of tests: 14 06:48:37 INFO - 2015-11-26 06:48:37,682 DEBUG : BROWSER_OUTPUT: 06:48:37 INFO - 2015-11-26 06:48:37,682 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.10 Median:110.00 stddev:4.53 (4.1%) stddev-sans-first:4.80 06:48:37 INFO - 2015-11-26 06:48:37,682 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 104.0 110.0 111.0 111.0 98.0 110.0 109.0 101.0 06:48:37 INFO - 2015-11-26 06:48:37,682 DEBUG : BROWSER_OUTPUT: 06:48:37 INFO - 2015-11-26 06:48:37,682 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.70 Median:150.50 stddev:15.41 (10.2%) stddev-sans-first:15.32 06:48:37 INFO - 2015-11-26 06:48:37,682 DEBUG : BROWSER_OUTPUT: Values: 158.0 125.0 151.0 159.0 132.0 150.0 146.0 124.0 160.0 122.0 06:48:37 INFO - 2015-11-26 06:48:37,683 DEBUG : BROWSER_OUTPUT: 06:48:37 INFO - 2015-11-26 06:48:37,683 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:1.99 (1.2%) stddev-sans-first:2.11 06:48:37 INFO - 2015-11-26 06:48:37,683 DEBUG : BROWSER_OUTPUT: Values: 161.0 161.0 160.0 159.0 162.0 161.0 159.0 166.0 161.0 162.0 06:48:37 INFO - 2015-11-26 06:48:37,683 DEBUG : BROWSER_OUTPUT: 06:48:37 INFO - 2015-11-26 06:48:37,683 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.70 Median:72.50 stddev:13.26 (18.3%) stddev-sans-first:13.59 06:48:37 INFO - 2015-11-26 06:48:37,685 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 83.0 94.0 71.0 71.0 72.0 109.0 91.0 73.0 06:48:37 INFO - 2015-11-26 06:48:37,685 DEBUG : BROWSER_OUTPUT: 06:48:37 INFO - 2015-11-26 06:48:37,685 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.70 Median:93.00 stddev:20.24 (21.8%) stddev-sans-first:20.94 06:48:37 INFO - 2015-11-26 06:48:37,685 DEBUG : BROWSER_OUTPUT: Values: 93.0 94.0 133.0 93.0 93.0 93.0 137.0 93.0 93.0 135.0 06:48:37 INFO - 2015-11-26 06:48:37,685 DEBUG : BROWSER_OUTPUT: 06:48:37 INFO - 2015-11-26 06:48:37,685 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.00 Median:110.00 stddev:0.47 (0.4%) stddev-sans-first:0.33 06:48:37 INFO - 2015-11-26 06:48:37,685 DEBUG : BROWSER_OUTPUT: Values: 111.0 110.0 110.0 110.0 110.0 110.0 110.0 109.0 110.0 110.0 06:48:37 INFO - 2015-11-26 06:48:37,685 DEBUG : BROWSER_OUTPUT: 06:48:37 INFO - 2015-11-26 06:48:37,685 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 06:48:37 INFO - 2015-11-26 06:48:37,685 DEBUG : BROWSER_OUTPUT: Values: 146.0 147.0 146.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 06:48:37 INFO - 2015-11-26 06:48:37,685 DEBUG : BROWSER_OUTPUT: 06:48:37 INFO - 2015-11-26 06:48:37,685 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 06:48:37 INFO - 2015-11-26 06:48:37,686 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 96.0 95.0 95.0 96.0 96.0 96.0 96.0 95.0 06:48:37 INFO - 2015-11-26 06:48:37,686 DEBUG : BROWSER_OUTPUT: 06:48:37 INFO - 2015-11-26 06:48:37,686 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:58.80 Median:58.00 stddev:2.70 (4.7%) stddev-sans-first:2.85 06:48:37 INFO - 2015-11-26 06:48:37,686 DEBUG : BROWSER_OUTPUT: Values: 58.0 59.0 58.0 57.0 60.0 58.0 58.0 57.0 66.0 57.0 06:48:37 INFO - 2015-11-26 06:48:37,686 DEBUG : BROWSER_OUTPUT: 06:48:37 INFO - 2015-11-26 06:48:37,686 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:70.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 06:48:37 INFO - 2015-11-26 06:48:37,686 DEBUG : BROWSER_OUTPUT: Values: 70.0 70.0 69.0 70.0 71.0 71.0 70.0 70.0 69.0 71.0 06:48:37 INFO - 2015-11-26 06:48:37,686 DEBUG : BROWSER_OUTPUT: 06:48:37 INFO - 2015-11-26 06:48:37,686 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:86.00 stddev:1.87 (2.2%) stddev-sans-first:1.94 06:48:37 INFO - 2015-11-26 06:48:37,686 DEBUG : BROWSER_OUTPUT: Values: 85.0 86.0 87.0 85.0 86.0 86.0 86.0 84.0 86.0 91.0 06:48:37 INFO - 2015-11-26 06:48:37,687 DEBUG : BROWSER_OUTPUT: 06:48:37 INFO - 2015-11-26 06:48:37,687 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:122.00 Median:124.00 stddev:4.81 (3.9%) stddev-sans-first:4.97 06:48:37 INFO - 2015-11-26 06:48:37,687 DEBUG : BROWSER_OUTPUT: Values: 125.0 119.0 113.0 120.0 126.0 118.0 124.0 124.0 130.0 121.0 06:48:37 INFO - 2015-11-26 06:48:37,687 DEBUG : BROWSER_OUTPUT: 06:48:37 INFO - 2015-11-26 06:48:37,687 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.30 Median:151.50 stddev:9.06 (6.0%) stddev-sans-first:8.96 06:48:37 INFO - 2015-11-26 06:48:37,687 DEBUG : BROWSER_OUTPUT: Values: 141.0 151.0 150.0 165.0 152.0 141.0 153.0 165.0 142.0 143.0 06:48:37 INFO - 2015-11-26 06:48:37,687 DEBUG : BROWSER_OUTPUT: 06:48:37 INFO - 2015-11-26 06:48:37,687 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:1.20 (1.8%) stddev-sans-first:1.20 06:48:37 INFO - 2015-11-26 06:48:37,687 DEBUG : BROWSER_OUTPUT: Values: 68.0 67.0 68.0 69.0 67.0 66.0 67.0 66.0 65.0 66.0 06:48:37 INFO - 2015-11-26 06:48:37,687 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:48:37 INFO - 2015-11-26 06:48:37,687 DEBUG : BROWSER_OUTPUT: 06:48:38 INFO - 2015-11-26 06:48:38,469 INFO : Browser exited with error code: 0 06:48:38 INFO - 2015-11-26 06:48:38,518 INFO : Completed test kraken (00:00:58) 06:48:38 INFO - 2015-11-26 06:48:38,518 INFO : Starting test v8_7 06:48:38 INFO - 2015-11-26 06:48:38,518 DEBUG : operating with platform_type : linux_ 06:48:38 INFO - 2015-11-26 06:48:38,518 INFO : Initialising browser for v8_7 test... 06:48:38 INFO - 2015-11-26 06:48:38,524 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpINkmC0/profile http://localhost:36918/getInfo.html 06:48:42 INFO - 2015-11-26 06:48:42,641 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:48:42 INFO - 2015-11-26 06:48:42,642 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:48:42 INFO - 2015-11-26 06:48:42,642 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:48:42 INFO - 2015-11-26 06:48:42,642 DEBUG : BROWSER_OUTPUT: __metrics 06:48:42 INFO - 2015-11-26 06:48:42,718 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] 06:48:43 INFO - 2015-11-26 06:48:43,994 INFO : Browser initialized. 06:48:43 INFO - 2015-11-26 06:48:43,994 INFO : Running cycle 1/1 for v8_7 test... 06:48:43 INFO - 2015-11-26 06:48:43,994 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpINkmC0/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:48:55 INFO - 2015-11-26 06:48:55,639 DEBUG : BROWSER_OUTPUT: RSS: Main: 138702848 06:48:55 INFO - 2015-11-26 06:48:55,640 DEBUG : BROWSER_OUTPUT: 06:49:17 INFO - 2015-11-26 06:49:17,104 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36918/tests/v8_7/run.html (next: http://localhost:36918/tests/v8_7/run.html) 06:49:17 INFO - 2015-11-26 06:49:17,354 DEBUG : BROWSER_OUTPUT: RSS: Main: 617832448 06:49:17 INFO - 2015-11-26 06:49:17,354 DEBUG : BROWSER_OUTPUT: 06:49:17 INFO - 2015-11-26 06:49:17,355 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:49:17 INFO - 2015-11-26 06:49:17,355 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:49:17 INFO - 2015-11-26 06:49:17,356 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:49:17 INFO - 2015-11-26 06:49:17,356 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:49:17 INFO - 2015-11-26 06:49:17,356 DEBUG : BROWSER_OUTPUT: |0;Richards;164.69038208168644 06:49:17 INFO - 2015-11-26 06:49:17,356 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;188.00526414739613 06:49:17 INFO - 2015-11-26 06:49:17,356 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.8199943836001 06:49:17 INFO - 2015-11-26 06:49:17,356 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5256.544502617801 06:49:17 INFO - 2015-11-26 06:49:17,356 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1094.0919037199126 06:49:17 INFO - 2015-11-26 06:49:17,357 DEBUG : BROWSER_OUTPUT: |5;Earley;781.8608287724785 06:49:17 INFO - 2015-11-26 06:49:17,357 DEBUG : BROWSER_OUTPUT: |6;Boyer;12650 06:49:17 INFO - 2015-11-26 06:49:17,357 DEBUG : BROWSER_OUTPUT: |7;RegExp;25794.871794871793 06:49:17 INFO - 2015-11-26 06:49:17,357 DEBUG : BROWSER_OUTPUT: |8;Splay;649.2012779552716 06:49:17 INFO - 2015-11-26 06:49:17,357 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4850.241545893719 06:49:17 INFO - 2015-11-26 06:49:17,358 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:49:17 INFO - 2015-11-26 06:49:17,358 DEBUG : BROWSER_OUTPUT: __startTimestamp1448549357354__endTimestamp 06:49:17 INFO - 2015-11-26 06:49:17,358 DEBUG : BROWSER_OUTPUT: 06:49:17 INFO - 2015-11-26 06:49:17,358 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:49:17 INFO - 2015-11-26 06:49:17,358 DEBUG : BROWSER_OUTPUT: Number of tests: 10 06:49:17 INFO - 2015-11-26 06:49:17,358 DEBUG : BROWSER_OUTPUT: 06:49:17 INFO - 2015-11-26 06:49:17,360 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.69 Median:164.69 stddev:0.00 (0.0%) 06:49:17 INFO - 2015-11-26 06:49:17,360 DEBUG : BROWSER_OUTPUT: Values: 164.7 06:49:17 INFO - 2015-11-26 06:49:17,360 DEBUG : BROWSER_OUTPUT: 06:49:17 INFO - 2015-11-26 06:49:17,360 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:188.01 Median:188.01 stddev:0.00 (0.0%) 06:49:17 INFO - 2015-11-26 06:49:17,360 DEBUG : BROWSER_OUTPUT: Values: 188.0 06:49:17 INFO - 2015-11-26 06:49:17,360 DEBUG : BROWSER_OUTPUT: 06:49:17 INFO - 2015-11-26 06:49:17,360 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.82 Median:280.82 stddev:0.00 (0.0%) 06:49:17 INFO - 2015-11-26 06:49:17,361 DEBUG : BROWSER_OUTPUT: Values: 280.8 06:49:17 INFO - 2015-11-26 06:49:17,361 DEBUG : BROWSER_OUTPUT: 06:49:17 INFO - 2015-11-26 06:49:17,361 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5256.54 Median:5256.54 stddev:0.00 (0.0%) 06:49:17 INFO - 2015-11-26 06:49:17,361 DEBUG : BROWSER_OUTPUT: Values: 5256.5 06:49:17 INFO - 2015-11-26 06:49:17,361 DEBUG : BROWSER_OUTPUT: 06:49:17 INFO - 2015-11-26 06:49:17,361 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1094.09 Median:1094.09 stddev:0.00 (0.0%) 06:49:17 INFO - 2015-11-26 06:49:17,361 DEBUG : BROWSER_OUTPUT: Values: 1094.1 06:49:17 INFO - 2015-11-26 06:49:17,362 DEBUG : BROWSER_OUTPUT: 06:49:17 INFO - 2015-11-26 06:49:17,362 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:781.86 Median:781.86 stddev:0.00 (0.0%) 06:49:17 INFO - 2015-11-26 06:49:17,362 DEBUG : BROWSER_OUTPUT: Values: 781.9 06:49:17 INFO - 2015-11-26 06:49:17,362 DEBUG : BROWSER_OUTPUT: 06:49:17 INFO - 2015-11-26 06:49:17,362 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12650.00 Median:12650.00 stddev:0.00 (0.0%) 06:49:17 INFO - 2015-11-26 06:49:17,362 DEBUG : BROWSER_OUTPUT: Values: 12650.0 06:49:17 INFO - 2015-11-26 06:49:17,362 DEBUG : BROWSER_OUTPUT: 06:49:17 INFO - 2015-11-26 06:49:17,362 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25794.87 Median:25794.87 stddev:0.00 (0.0%) 06:49:17 INFO - 2015-11-26 06:49:17,363 DEBUG : BROWSER_OUTPUT: Values: 25794.9 06:49:17 INFO - 2015-11-26 06:49:17,363 DEBUG : BROWSER_OUTPUT: 06:49:17 INFO - 2015-11-26 06:49:17,363 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:649.20 Median:649.20 stddev:0.00 (0.0%) 06:49:17 INFO - 2015-11-26 06:49:17,363 DEBUG : BROWSER_OUTPUT: Values: 649.2 06:49:17 INFO - 2015-11-26 06:49:17,363 DEBUG : BROWSER_OUTPUT: 06:49:17 INFO - 2015-11-26 06:49:17,363 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4850.24 Median:4850.24 stddev:0.00 (0.0%) 06:49:17 INFO - 2015-11-26 06:49:17,363 DEBUG : BROWSER_OUTPUT: Values: 4850.2 06:49:17 INFO - 2015-11-26 06:49:17,364 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:49:17 INFO - 2015-11-26 06:49:17,364 DEBUG : BROWSER_OUTPUT: 06:49:18 INFO - 2015-11-26 06:49:18,213 INFO : Browser exited with error code: 0 06:49:18 INFO - 2015-11-26 06:49:18,225 INFO : Completed test v8_7 (00:00:39) 06:49:18 INFO - 2015-11-26 06:49:18,306 INFO : Completed test suite (00:20:12) 06:49:18 INFO - 2015-11-26 06:49:18,306 DEBUG : Working with test: dromaeo_css 06:49:18 INFO - 2015-11-26 06:49:18,307 DEBUG : Generating results file: dromaeo_css 06:49:18 INFO - 2015-11-26 06:49:18,308 DEBUG : Working with test: kraken 06:49:18 INFO - 2015-11-26 06:49:18,308 DEBUG : Generating results file: kraken 06:49:18 INFO - 2015-11-26 06:49:18,308 INFO : javascript benchmark 06:49:18 INFO - 2015-11-26 06:49:18,308 DEBUG : Working with test: v8_7 06:49:18 INFO - 2015-11-26 06:49:18,309 DEBUG : Generating results file: v8_7 06:49:18 INFO - 2015-11-26 06:49:18,309 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:49:18 INFO - 2015-11-26 06:49:18,376 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,201,35]] 06:49:18 INFO - 2015-11-26 06:49:18,376 DEBUG : process_Request line: dromaeo_css 6961.07 graph.html#tests=[[72,201,35]] 06:49:18 INFO - 2015-11-26 06:49:18,377 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:49:18 INFO - 2015-11-26 06:49:18,418 DEBUG : process_Request line: kraken 1503.40 graph.html#tests=[[232,201,35]] 06:49:18 INFO - 2015-11-26 06:49:18,418 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:49:18 INFO - 2015-11-26 06:49:18,454 DEBUG : process_Request line: v8_7 20850.29 graph.html#tests=[[230,201,35]] 06:49:18 INFO - 2015-11-26 06:49:18,455 INFO : javascript benchmark 06:49:18 INFO - 2015-11-26 06:49:18,459 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3165.7625678074146, 3272.656371081035, 3317.0, 3318.971118953125, 3338.0152854907806, 5257.789688412468, 5365.973170134144, 5484.890302193954, 5542.390337062925, 5544.417836127209, 5463.726813659321, 5505.311836020497, 5549.694766787825, 5607.635503692257, 5613.4386561343845, 5029.597632189425, 5079.212722028092, 5101.770420331075, 5117.385913690344, 5133.460986596409, 5131.948680513203, 5138.768755405997, 5214.165733482643, 5216.452272511388, 5222.686638801665, 4936.30891675164, 4982.950170498285, 5045.915128247408, 5120.539151476348, 5123.615728820316, 5050.015247026831, 5052.040112378636, 5057.317262169597, 5060.165072762989, 5092.898142037138, 4883.34074899891, 5048.066107770074, 5049.394072711261, 5068.087744206042, 5075.923861142086, 5287.550558202557, 5299.152135658314, 5369.060414427459, 5386.703731294777, 5388.595855310868, 6006.099085137221, 6022.939770602282, 6030.12563178341, 6036.124761909498, 6037.72830222641, 5717.056685646863, 5862.267216597926, 6021.849453763647, 6024.367441418632, 6034.607750496204, 5567.777288908439, 5580.302462192226, 5662.745176467069, 5674.517665998354, 5688.203651488795, 6144.17053697754, 6144.600600960924, 6145.692715364214, 6149.754009839557, 6149.7847575334645, 5855.882882342329, 5946.940530594725, 5949.167116603679, 5952.0, 5955.970220148958, 5683.602147849693, 5920.2303700519515, 5938.524918006549, 5953.434423729739, 6037.366076561898, 5981.880362392728, 6026.668533230714, 6036.396360363929, 6037.034074548052, 6041.335453100155, 5734.340550836624, 6036.8188954331445, 6040.093985902062, 6048.727807248684, 6058.575899687068, 4461.821527138943, 4467.888302792456, 4526.298423744325, 4546.5453454654935, 4582.9541704582525, 4084.1627466369323, 4094.856680016245, 4103.035786590149, 4112.527059388209, 4126.504819421689, 5730.799422020209, 5745.482906538348, 5747.655140691489, 5748.971255143698, 5799.681017543991, 5807.157962095473, 5904.793332233351, 5908.0, 5964.671943043088, 6053.092036194606, 5706.743196556165, 5707.8858422832145, 5716.657000579979, 5724.713764311768, 5731.168980497804, 5277.525022747971, 5278.736063196825, 5283.2075188722, 5283.286756287852, 5334.759935802898, 5913.113033045078, 6064.4541991221, 6098.176746139215, 6170.127795527211, 6229.750809967551, 5894.38108998549, 5957.434043765836, 5962.701864906824, 5970.910436343545, 6012.939870601238, 6109.358517355614, 6117.785877494266, 6126.969365153146, 6172.105044768394, 6188.257409110755, 5710.0, 5921.792737254175, 5946.345901950868, 5947.583669143291, 5959.880802383841, 5914.58597898143, 5980.222571065734, 5987.191729116514, 5987.970060149845, 6030.7889223876955, 5869.6771677558145, 5893.970530147321, 5894.528437724886, 5901.970490147693, 5907.70461476933, 5636.577256705846, 5730.398308177581, 5739.512141468022, 5847.181394604844, 5870.589058765845, 5706.144078388275, 5756.769729210952, 5761.0, 5765.625234359753, 5803.767849285981, 5883.705814709162, 5890.823275301748, 5942.376050514634, 5961.225040744675, 6000.669963152069, 5573.972130139324, 5682.346530149086, 5692.800751973661, 5714.628549144292, 5784.566157538286], "unit": "ms", "name": "dojo.html", "value": 5505.249539029996}, {"lowerIsBetter": false, "replicates": [548.3620912405617, 584.1238142785821, 586.1881294407247, 586.7623612436963, 588.9440503152199, 31192.68807311929, 40037.79981100094, 40358.0, 40430.39354409683, 40567.0, 40621.796891015576, 41329.17341653165, 41398.58601413985, 41399.79300103495, 41433.378499322534, 7552.3202911737935, 7621.657025433841, 7633.3893288537065, 8642.91357086429, 8859.734207973772, 7596.582187979659, 7672.117706463772, 10146.746331341701, 10300.793984120313, 10317.071463568278, 10055.145312648434, 10149.594016239378, 10151.644692435766, 10154.390736555793, 10171.491425428736, 7766.961165194138, 9966.152877005463, 10049.045340692695, 10064.396136231813, 10068.194544436428, 7616.428767842434, 10396.376217426978, 10652.627158049509, 10675.359478431319, 10691.625793097204, 38635.22729545421, 38958.80520597407, 39055.804720976215, 39136.41295380572, 39206.0, 3050.8932187373557, 3218.8229647369385, 3261.608606967179, 3525.3301872644115, 3675.4483439055593, 28996.27509312263, 29142.41715165685, 29233.707662923312, 29431.411371772443, 29511.26221844471, 3798.2783271178387, 3890.8054597270307, 3949.6050394960275, 3964.86122985697, 3969.0672691917375, 261811.0, 262807.0, 262897.9999999981, 263110.0, 263690.68154659297, 50012.249816252785, 50319.49680503221, 57710.422895770505, 57903.42096579065, 57950.710246449344, 65653.67173164104, 67258.66370668115, 67344.32655673478, 67363.32636673668, 67525.66237168782, 14825.629359266104, 15083.849161508464, 15095.094294342374, 15158.393664253526, 15167.696646067237, 10429.322094063862, 10431.165506759593, 10434.947825260977, 10439.530221140067, 10477.004684554955, 18623.44129676112, 18747.0, 18838.81161188398, 18926.0, 19013.0, 7025.578465292099, 7261.745838895719, 7267.745628902963, 7286.48994570375, 7331.560106393634, 853.7353420439559, 878.872563478292, 925.509479975614, 937.634322614181, 1068.931068931069, 27537.17388478349, 27748.86125569359, 28119.297017574314, 28165.859170704014, 28469.0, 996.8504724291414, 1005.1154983614373, 1018.9032041956002, 1030.6907927621683, 1117.3742704085575, 14564.41742330295, 14730.705385892223, 14869.405223790927, 14907.403703851942, 14988.925055374653, 313421.0, 318471.0, 319118.0, 319142.0, 319694.0, 51211.7439412808, 51386.48613513892, 51489.0, 51578.74210628923, 51581.74209128931, 1196.2284326609463, 1208.7038675524377, 1210.0803389424102, 1219.0674134287278, 1223.4922507159606, 27233.31916702098, 27434.5884811732, 28256.15231543057, 28347.574786378635, 28501.144965650234, 41732.3740143892, 42071.0, 43819.0, 44582.0, 44755.776221118685, 307449.0, 307772.0, 310183.0, 312649.0, 312679.0, 28701.425971480872, 28857.422851543273, 29042.709572904, 29056.564151538165, 29107.981220657177, 42495.0, 43340.34989475097, 43421.0, 44186.779066104464, 44239.0, 36292.45561316635, 36505.63494365128, 36938.0, 37205.44191837179, 37712.43431348587, 34803.0, 35605.64394356127, 35804.64195358013, 35951.46072808962, 36688.08279793026, 32764.34471310513, 33076.66923330736, 33359.83320083384, 33369.66630333666, 33468.33063338768, 29943.70056299409, 31605.051848444582, 35512.0, 35654.28691426209, 35812.104697382776, 25564.48871022606, 25755.732801595215, 25787.87106064458, 25890.35274118162, 25956.0], "unit": "ms", "name": "ext.html", "value": 18326.206532078668}, {"lowerIsBetter": false, "replicates": [358.53569627332604, 358.7363287983332, 360.6429634661685, 362.67540551206673, 363.69994754327683, 14004.579862604138, 14054.297285135728, 16487.340506379744, 17681.381151659694, 18831.798594238997, 10046.799064018714, 10281.280310378277, 16449.506514804576, 16760.41338553151, 16769.161541922917, 4697.741624210676, 4802.807887684488, 4804.615630749532, 4811.254255590389, 4812.350332705075, 3638.108663377476, 3730.3658378075793, 4887.8778030549165, 4929.679570827885, 5198.8700282493055, 5303.787848486056, 5333.306670132877, 5336.119540275849, 5345.037893179227, 5357.089294819871, 5034.798608055673, 5160.56135228506, 5160.716160611165, 5280.841574752763, 5316.282301889234, 4967.130752118364, 4971.0, 4974.502549744997, 5000.474950130257, 5004.649674522785, 11181.105511559137, 11337.829932551018, 11354.375509346983, 11367.147463940237, 13650.590482285548, 1967.2229469359522, 2033.6034473277716, 2045.6317862784692, 2052.3022172461433, 2113.704081428601, 9294.07059294072, 9625.71122866315, 9627.663031793854, 9640.325177237597, 10136.341137826017, 1773.9024353660543, 1857.8792378495355, 1949.5905859769466, 1982.5241941933975, 1987.9801201988084, 59538.40461595372, 60612.39387606156, 62351.68824155896, 63732.36267637311, 63960.16239025627, 10296.0, 10310.896891031143, 10341.74145646365, 10358.844617330746, 10359.0, 13386.129901556367, 13438.193708377334, 13445.126066805626, 13445.663858403617, 13449.932750336186, 7435.219301973323, 7449.664765085584, 7458.366038886756, 7491.775246742606, 7522.6614802334025, 7053.682584283617, 7095.609741464165, 7095.609741464269, 7164.247753985861, 7288.344049035612, 4417.734935903856, 4469.195544801902, 4490.4059059034225, 4501.167284052461, 4513.142502924499, 1709.8119206887231, 1716.5279548124365, 1741.6690828742499, 1765.7704498415123, 1790.1675720789635, 759.8708219602681, 853.1298075962484, 872.4154816273112, 978.5987745024506, 1019.5310157327564, 16376.590585235226, 16731.83268167327, 16763.49709508717, 16911.577210569834, 16946.23741931616, 784.0, 836.431226765793, 918.2241006349624, 944.6127087893931, 984.3306551544875, 9094.317926155432, 9238.630454781867, 9266.907330926604, 9374.0, 9384.812303753886, 496188.8620581039, 504802.0, 534718.0, 572395.0, 581918.0, 74836.62581687166, 78448.0, 81027.68181659185, 84817.0, 85790.57104714436, 1091.1816137896578, 1103.3269705479652, 1114.3035602748282, 1120.2494328799723, 1122.7866705325962, 14799.92600036993, 14820.703585928437, 15091.924540377227, 15155.965869352127, 15283.61790955235, 32544.122693162735, 32816.50775238326, 33356.833215833765, 34424.31151377009, 36232.63767362398, 45038.0, 46099.76950115228, 46333.0, 47075.29387059265, 49464.25803612877, 9487.952560237154, 9693.806123877624, 9786.804263914824, 9790.363626364264, 9800.31397802147, 11050.668479945614, 16455.25951332169, 17249.827501725325, 18140.909295453966, 18301.359452419103, 14395.712085758436, 14795.778063328844, 14838.18389988561, 14861.554153375417, 15096.924515377354, 14084.225367604471, 14482.582812242366, 14601.196934168724, 14619.903507237212, 14630.926845365704, 14087.929560352133, 14555.563333100024, 14839.777403339174, 15174.241287935338, 15350.462733804263, 9417.764555886157, 9501.477418741759, 9507.286953478379, 9516.19112375428, 9544.379615324982, 13438.664033399244, 13648.65878353049, 14099.859001410265, 14294.499692510712, 14804.111753294837], "unit": "ms", "name": "jquery.html", "value": 8561.954748508955}, {"lowerIsBetter": false, "replicates": [1379.3999610169571, 1724.0690027385212, 1757.5430388099098, 1785.8571314294857, 1795.5690634247785, 6611.239707433647, 7712.383009359251, 7886.842263154734, 7902.565358905265, 7906.565138917357, 7745.883811742814, 7857.332126769217, 7910.762677119696, 7916.881246781289, 7923.801904952379, 1954.4722924810292, 1974.9308774192907, 1979.15885748557, 2307.9538409231805, 2351.376885125443, 2254.211026140854, 2287.965680514794, 2289.5192009677903, 2290.358699564125, 2291.86248825071, 2217.3347995601334, 2230.888455577223, 2293.7820907013806, 2294.2773026496684, 2296.7588403217674, 1946.1436967734223, 1999.070432249004, 2206.9889650551804, 2243.170027089979, 2244.450109723128, 2224.8998795054263, 2250.5723912456747, 2252.358077947777, 2254.6054440472853, 2256.4471704432353, 7827.0, 7846.489978151402, 7863.960680196619, 7869.449138560303, 7877.763667089939, 4803.18345881201, 4921.311016457699, 4922.384701912261, 4927.605791536705, 4935.0376676548085, 2959.1714319990438, 3004.8347340896244, 3056.862441190152, 3058.8776448942017, 3130.906072817819, 937.0957026469422, 946.1153821177221, 965.2422848064306, 968.1480297338368, 985.0, 10699.0, 10795.352278863293, 10798.784024319548, 10800.5679772809, 10807.0, 4204.915901681949, 4260.595243451867, 4328.545502722169, 4332.415123958288, 4378.0, 8898.332625053146, 8906.86639700405, 8907.866382004275, 8918.0, 8918.554072296489, 4803.087413391444, 4813.374261346003, 4827.589654879374, 4832.758362081881, 4838.056578967138, 4412.9117417652105, 4415.183191109655, 4417.514073451917, 4419.027813880941, 4422.204003279377, 3389.0, 3401.744869134825, 3413.573303337083, 3427.7771944823508, 3443.27691184854, 1073.9731506712394, 1077.49357801833, 1079.2930630437077, 1080.6001779341693, 1091.0960374147164, 943.565959658551, 1246.1214843535286, 1257.8427696537933, 1262.659082047842, 1265.519102740971, 6662.600243985376, 6782.626955517494, 7132.821679457951, 7363.374113200331, 7388.445866559922, 561.5591760468013, 572.241779641976, 573.3492486028388, 599.4035934245367, 599.7091410665823, 5499.257600223998, 5502.174673798962, 5525.392206857243, 5558.805441809517, 5594.804181853616, 9186.954065229766, 9530.52347382628, 9588.616455341707, 9631.470269135265, 9655.468949207861, 9454.48000359973, 9525.0, 9532.094451027142, 9544.188743956842, 9578.233741300679, 7236.963815180996, 7476.738314159087, 7514.173440921603, 7531.623418829036, 7537.2085930977555, 6717.832054198684, 6727.495437842083, 6765.8985115224295, 6775.525713200029, 6799.286074962058, 8207.220314070153, 8372.204640559137, 8576.142385761374, 8582.055973842997, 8608.870866936875, 8507.489550626982, 8552.786180345542, 8571.614277357408, 8697.521636310064, 8699.521526316114, 6680.331966803476, 6743.291954344723, 6754.628495432798, 6755.054292399166, 6759.391654751096, 7649.770506884803, 7756.534607923317, 7759.961200194189, 7761.534307941542, 7781.494202876795, 6943.583384996917, 7039.296070392919, 7192.676329565287, 7272.236415176542, 7338.339549440576, 6816.591004539743, 6840.41856442208, 6842.213145488333, 6918.169819621678, 7004.929950700632, 6261.0, 6341.8097457076365, 6790.490713196431, 6857.245702972569, 6897.068895699217, 6336.683165841781, 6448.967755161194, 6527.575707579183, 6533.150690410216, 6546.574472659262, 6383.93616063852, 6413.839654008687, 6454.548181627429, 6455.741770329134, 6455.870882582416], "unit": "ms", "name": "mootools.html", "value": 4380.687380870669}, {"lowerIsBetter": false, "replicates": [341.8376271271146, 347.57075012359746, 348.73496142931367, 349.24911440403145, 352.1846924370084, 5988.101784732287, 6044.184035155258, 6053.303870054945, 6082.39176082391, 6098.847528811782, 5922.58541902067, 5942.049272116462, 5961.314448838385, 5970.104484327353, 6009.218801555802, 4620.722756634605, 4627.005193883315, 4633.2123538998285, 4638.5361463853515, 4643.303504474322, 4430.468343798732, 4568.7944042518, 4584.3352713856475, 4587.059652771174, 4597.126545956275, 4546.249868771649, 4552.613027892633, 4563.771811409433, 4567.703099298552, 4580.725156490621, 4343.652507799368, 4423.800928958172, 4461.21928662486, 4566.931496027562, 4568.56598623132, 3926.5288165420047, 3949.7163421887713, 3953.0, 3959.2477429288633, 3967.861124860616, 5645.435456454363, 5647.656222667588, 5648.717564121818, 5650.802221922213, 5660.433956604348, 4977.228529577921, 5119.360079990001, 5176.120059589841, 5238.13570760824, 5261.3686357637325, 6275.121482992385, 6309.14826498426, 6316.368363163647, 6328.240611126648, 6347.365263473662, 4371.169477799208, 4372.409724687158, 4374.737515749065, 4377.956220437819, 4381.408509851193, 6459.5478316517865, 6547.770828020996, 6608.867822643569, 6609.966950165219, 6620.536562440631, 4458.866234012985, 4458.888527786831, 4460.487043989918, 4464.1071785643, 4467.128909862546, 4506.0, 4517.661175411858, 4518.570735780096, 4519.163954668397, 4520.977395113004, 5270.49930256625, 5275.3142091528625, 5277.1292736698015, 5280.181571856291, 5280.9471905281225, 6280.995040793451, 6414.166158399472, 6429.292777794439, 6438.581492202992, 6452.870942581216, 1608.7345587978086, 1620.1170362152654, 1622.9837701623069, 1625.2036502113879, 1626.699060673779, 1058.6559368205365, 1080.0927221134284, 1081.7944590527775, 1085.7611325508376, 1103.5309993252836, 6206.689665516707, 6212.285587157535, 6226.62640241587, 6227.439530442282, 6236.844078898064, 6652.8669426611195, 6653.567518111307, 6654.434373078343, 6658.201015878126, 6659.9001014984815, 6064.241969753781, 6224.0, 6230.906536401958, 6233.438990490878, 6233.812985610439, 3592.263585964871, 3599.0822340303057, 3610.5847827500174, 3612.7832330060282, 3615.439606860941, 6807.5575087619145, 6848.3494068063455, 6854.143232095988, 6880.105586273852, 6888.862222755517, 6365.872682546323, 6573.112629795012, 6771.322867713189, 6771.492138089565, 6784.491163162684, 6723.966380168068, 6736.157980252468, 6794.5583537070925, 6826.351496607815, 6895.655217239118, 6693.899591506034, 6697.497687673346, 6698.531102822755, 6700.530962832555, 6705.463562914858, 6454.0, 6609.768658096944, 6623.768168114286, 6630.867382652416, 6633.668316584248, 6875.278095800069, 6882.414994725505, 6886.414654754402, 6894.413974812197, 6900.896486552605, 4418.7569683666425, 4442.533533979015, 4451.844185453494, 4454.109178164315, 4461.4869290030765, 6652.101966234496, 6657.40083392497, 6667.43326817226, 6668.766593169215, 6673.0, 6321.241451025906, 6514.6416947068365, 6528.314526974599, 6533.607983521004, 6542.574732642363, 6491.318411566717, 6494.935050649433, 6504.089427480062, 6505.512086593619, 6514.348565143448, 6249.593776404519, 6391.32891046452, 6392.520560957853, 6395.232572091379, 6400.743970241138, 6519.641419721961, 6550.279469258282, 6560.770373036921, 6565.916623757026, 6578.736850525926, 6410.903836442364, 6416.903746443901, 6422.0, 6422.197225346832, 6423.935760642334], "unit": "ms", "name": "prototype.html", "value": 4822.062752250443}, {"lowerIsBetter": false, "replicates": [731.6926890705903, 750.8460765543065, 856.216561845911, 874.0996773323475, 877.784942689041, 13190.0, 13746.175229486238, 13769.0, 13782.448702051932, 13791.931040344809, 12878.806817897716, 12969.481220751159, 13001.934990325035, 13003.934980325084, 13073.869261307407, 667.3826710292984, 673.0442771264791, 802.8394321135767, 810.5217921426357, 810.8094597769519, 818.2594751749676, 820.6922404098464, 825.8678611422174, 830.5847076461769, 831.1854382704953, 849.4605925237457, 856.6573370651759, 856.9742907712747, 858.2576071697998, 858.433433933604, 697.5953946710921, 705.0798707686467, 829.9336053115736, 832.1304237072274, 835.1189495082685, 616.1804799616499, 616.6639181646013, 617.3548641669466, 617.9196704428396, 618.3322012226785, 12729.745405091939, 12786.168899021626, 12787.0, 12809.423575939012, 12844.036697247744, 12041.277523348539, 12975.935120324433, 13129.409176587076, 13298.667533311647, 13318.600441986757, 10092.545835437422, 10188.541515631814, 10218.846717299246, 10461.267711260214, 10533.21000924926, 3520.876769313087, 3544.6809787119023, 3550.343186510504, 3554.6445355464507, 3576.517170182044, 47035.76482117602, 47738.28392574114, 47770.28344574799, 47783.52216477825, 47817.76091119522, 10005.549750261256, 10027.598896044077, 10031.0, 10038.380586851554, 10296.558481812552, 18569.628607427774, 18607.348742793936, 18621.7206741899, 18629.813701862808, 18677.626447471248, 7558.697652093964, 7593.164751877289, 7719.65261563231, 7796.805079873048, 7807.882881756778, 4088.6115818997146, 4177.56135605757, 4225.154969006149, 4314.935275970863, 4368.563143685669, 2928.2386579489485, 2970.019893435161, 2974.2415684000443, 2977.419403216352, 2977.478941185284, 507.0442216422071, 508.3366207099696, 517.2913109040563, 523.5183631059434, 525.2069375243334, 555.2642748358442, 628.1206311164407, 632.3644737039247, 650.7494614573424, 673.2089794491453, 8238.299744521662, 8323.0, 8587.87118193215, 8645.438046526955, 8751.56242187888, 574.6896675795024, 612.2928018139056, 660.3561527510639, 665.1320027364336, 674.9696263668146, 3757.9436308455397, 3866.980665096713, 3986.4219688145636, 4097.549269580344, 4209.452771139732, 56016.71991640097, 56233.43766562282, 56818.43181568132, 57018.0, 57334.0, 50015.0, 50040.0, 50257.74871125621, 50349.0, 50440.49559504431, 44355.0, 44734.0, 45007.54992450034, 46116.53883461189, 46296.0, 7715.886058887493, 7774.883376749466, 7999.280064794197, 8111.878321825059, 8293.295069919124, 33051.008469745946, 33373.0, 33827.0, 34753.82623086868, 34793.65206347904, 44295.77852110848, 44751.328730068424, 44857.327140092275, 45151.32273015842, 45934.77032614816, 10744.892551074388, 10761.677149685522, 11040.227184097357, 11106.16703747206, 11377.2604780689, 11710.88289117132, 11926.224795388272, 12340.81488777687, 12607.684807879876, 12708.364581771059, 11437.0, 11745.354005529778, 11899.881001189877, 12177.634670959886, 12187.75624487523, 11430.0, 11906.33130962706, 11949.64151075469, 11988.580399685969, 12551.93724031374, 6894.655267236719, 10696.197785165989, 11543.134264929995, 11704.648860534198, 12016.939915300367, 9448.858267125861, 9612.855807163038, 9725.96798556323, 9749.707508774749, 10098.747531311483, 8013.879791803244, 9454.527273636153, 9882.160016398686, 10245.641402550875, 10507.264491485828], "unit": "ms", "name": "yui.html", "value": 6234.772243010779}], "name": "dromaeo_css", "value": 6961.070119655799}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 109.0, 104.0, 110.0, 111.0, 111.0, 98.0, 110.0, 109.0, 101.0], "unit": "ms", "name": "ai-astar", "value": 107.1}, {"lowerIsBetter": true, "replicates": [158.0, 125.0, 151.0, 159.0, 132.0, 150.0, 146.0, 124.0, 160.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.7}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 160.0, 159.0, 162.0, 161.0, 159.0, 166.0, 161.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 161.2}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 83.0, 94.0, 71.0, 71.0, 72.0, 109.0, 91.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 80.7}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 133.0, 93.0, 93.0, 93.0, 137.0, 93.0, 93.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 105.7}, {"lowerIsBetter": true, "replicates": [111.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.0}, {"lowerIsBetter": true, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.2}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [58.0, 59.0, 58.0, 57.0, 60.0, 58.0, 58.0, 57.0, 66.0, 57.0], "unit": "ms", "name": "json-parse-financial", "value": 58.8}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 69.0, 70.0, 71.0, 71.0, 70.0, 70.0, 69.0, 71.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 70.1}, {"lowerIsBetter": true, "replicates": [85.0, 86.0, 87.0, 85.0, 86.0, 86.0, 86.0, 84.0, 86.0, 91.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.2}, {"lowerIsBetter": true, "replicates": [125.0, 119.0, 113.0, 120.0, 126.0, 118.0, 124.0, 124.0, 130.0, 121.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 122.0}, {"lowerIsBetter": true, "replicates": [141.0, 151.0, 150.0, 165.0, 152.0, 141.0, 153.0, 165.0, 142.0, 143.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 150.3}, {"lowerIsBetter": true, "replicates": [68.0, 67.0, 68.0, 69.0, 67.0, 66.0, 67.0, 66.0, 65.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.9}], "name": "kraken", "value": 1503.3999999999999}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.69038208168644], "unit": "score", "name": "Richards", "value": 214.35374400000003}, {"lowerIsBetter": false, "replicates": [188.00526414739613], "unit": "score", "name": "DeltaBlue", "value": 351.68164199999995}, {"lowerIsBetter": false, "replicates": [280.8199943836001], "unit": "score", "name": "Encrypt", "value": 947.8705409999999}, {"lowerIsBetter": false, "replicates": [5256.544502617801], "unit": "score", "name": "Decrypt", "value": 50.6380189243028}, {"lowerIsBetter": false, "replicates": [1094.0919037199126], "unit": "score", "name": "RayTrace", "value": 676.3499459999998}, {"lowerIsBetter": false, "replicates": [781.8608287724785], "unit": "score", "name": "Earley", "value": 852.4061770000001}, {"lowerIsBetter": false, "replicates": [12650.0], "unit": "score", "name": "Boyer", "value": 52.684822134387325}, {"lowerIsBetter": false, "replicates": [25794.871794871793], "unit": "score", "name": "RegExp", "value": 35.31651590457257}, {"lowerIsBetter": false, "replicates": [649.2012779552716], "unit": "score", "name": "Splay", "value": 125.52501476377947}, {"lowerIsBetter": false, "replicates": [4850.241545893719], "unit": "score", "name": "NavierStokes", "value": 305.9641434262948}], "name": "v8_7", "value": 20850.290240498907}]} 06:49:18 INFO - RETURN: dromaeo_css: 6961.07 06:49:18 INFO - RETURN: kraken: 1503.40 06:49:18 INFO - RETURN: v8_7: 20850.29 06:49:18 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,201,35]]", "result": "20850.29"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,201,35]]", "result": "1503.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,201,35]]", "result": "6961.07"}}} 06:49:18 INFO - Return code: 0 06:49:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 06:49:18 INFO - # TBPL SUCCESS # 06:49:18 INFO - Running post-action listener: _resource_record_post_action 06:49:18 INFO - Running post-run listener: _resource_record_post_run 06:49:19 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 4096; Write bytes: 504020992; Read time: 104; Write time: 408936 06:49:19 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 231849984; Read time: 0; Write time: 271040 06:49:19 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 4096; Write bytes: 267309056; Read time: 104; Write time: 137620 06:49:19 INFO - Running post-run listener: _upload_blobber_files 06:49:19 INFO - Blob upload gear active. 06:49:19 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 06:49:19 INFO - Copying logs to upload dir... 06:49:19 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1258.400034 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2015-11-26 06:49:19.558992) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-26 06:49:19.562623) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/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-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010024 build_url: 'https://queue.taskcluster.net/v1/task/DFjZ31MeTwGaNJ74KgIRRQ/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-26 06:49:19.615104) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 06:49:19.615433) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20aa3857bbc5a756c598c738000001bb-1448548098.567808-1947665683 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004558 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 06:49:19.665498) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-26 06:49:19.665812) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-26 06:49:19.666155) ========= ========= Total master_lag: 0.21 =========