builder: b2g-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-058 starttime: 1448478037.64 results: success (0) buildid: 20151125083212 builduid: 68e990e9c0f24a3ca2a18bce66186ba3 revision: 9ae9573c9e20580ddb1e4ee291923cfca9b86ba3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:37.639270) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:37.639698) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:37.639982) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006684 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:37.660529) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:37.660819) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:37.677538) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:37.677832) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004623 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:37.734690) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:37.735061) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:37.735421) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:37.735686) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-25 11:00:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.6M=0s 2015-11-25 11:00:37 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.168315 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:37.917940) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:37.918243) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016259 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:37.967511) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:37.967854) ========= bash -c 'python archiver_client.py mozharness --repo integration/b2g-inbound --rev 9ae9573c9e20580ddb1e4ee291923cfca9b86ba3 --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 9ae9573c9e20580ddb1e4ee291923cfca9b86ba3 --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=e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-25 11:00:38,063 truncating revision to first 12 chars 2015-11-25 11:00:38,063 Setting DEBUG logging. 2015-11-25 11:00:38,063 attempt 1/10 2015-11-25 11:00:38,063 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/9ae9573c9e20?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-25 11:00:38,411 unpacking tar archive at: b2g-inbound-9ae9573c9e20/testing/mozharness/ program finished with exit code 0 elapsedTime=0.634580 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:38.615160) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:38.615576) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:38.626253) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:38.626502) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 11:00:38.626887) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2015-11-25 11:00:38.627176) ========= /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=e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:00:38 INFO - MultiFileLogger online at 20151125 11:00:38 in /builds/slave/test-pgo 11:00:38 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 11:00:38 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 11:00:38 INFO - {'append_to_log': False, 11:00:38 INFO - 'base_work_dir': '/builds/slave/test-pgo', 11:00:38 INFO - 'blob_upload_branch': 'B2G-Inbound', 11:00:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 11:00:38 INFO - 'branch': 'B2G-Inbound', 11:00:38 INFO - 'buildbot_json_path': 'buildprops.json', 11:00:38 INFO - 'config_files': ('talos/linux_config.py',), 11:00:38 INFO - 'default_actions': ('clobber', 11:00:38 INFO - 'read-buildbot-config', 11:00:38 INFO - 'download-and-extract', 11:00:38 INFO - 'populate-webroot', 11:00:38 INFO - 'create-virtualenv', 11:00:38 INFO - 'install', 11:00:38 INFO - 'run-tests'), 11:00:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:00:38 INFO - 'download_minidump_stackwalk': True, 11:00:38 INFO - 'download_symbols': 'ondemand', 11:00:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:00:38 INFO - 'tooltool.py': '/tools/tooltool.py', 11:00:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:00:38 INFO - '/tools/misc-python/virtualenv.py')}, 11:00:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:00:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:00:38 INFO - 'installer_path': 'installer.exe', 11:00:38 INFO - 'log_level': 'info', 11:00:38 INFO - 'log_name': 'talos', 11:00:38 INFO - 'log_to_console': True, 11:00:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:00:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:00:38 INFO - 'opt_config_files': (), 11:00:38 INFO - 'pip_index': False, 11:00:38 INFO - 'sps_profile': False, 11:00:38 INFO - 'sps_profile_interval': 0, 11:00:38 INFO - 'suite': 'dromaeojs', 11:00:38 INFO - 'system_bits': '64', 11:00:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:00:38 INFO - 'title': 'talos-linux64-ix-058', 11:00:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:00:38 INFO - 'use_talos_json': True, 11:00:38 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 11:00:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:00:38 INFO - 'work_dir': 'build'} 11:00:38 INFO - ##### 11:00:38 INFO - ##### Running clobber step. 11:00:38 INFO - ##### 11:00:38 INFO - Running pre-action listener: _resource_record_pre_action 11:00:38 INFO - Running main action method: clobber 11:00:38 INFO - rmtree: /builds/slave/test-pgo/build 11:00:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 11:00:39 INFO - Running post-action listener: _resource_record_post_action 11:00:39 INFO - ##### 11:00:39 INFO - ##### Running read-buildbot-config step. 11:00:39 INFO - ##### 11:00:39 INFO - Running pre-action listener: _resource_record_pre_action 11:00:39 INFO - Running main action method: read_buildbot_config 11:00:39 INFO - Using buildbot properties: 11:00:39 INFO - { 11:00:39 INFO - "properties": { 11:00:39 INFO - "buildnumber": 1007, 11:00:39 INFO - "product": "firefox", 11:00:39 INFO - "script_repo_revision": "production", 11:00:39 INFO - "builddir": "b2g-inbound_ubuntu64_hw_test-dromaeojs", 11:00:39 INFO - "repository": "", 11:00:39 INFO - "buildername": "Ubuntu HW 12.04 x64 b2g-inbound pgo talos dromaeojs", 11:00:39 INFO - "buildid": "20151125083212", 11:00:39 INFO - "slavename": "talos-linux64-ix-058", 11:00:39 INFO - "pgo_build": "True", 11:00:39 INFO - "basedir": "/builds/slave/test-pgo", 11:00:39 INFO - "project": "", 11:00:39 INFO - "platform": "ubuntu64_hw", 11:00:39 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:00:39 INFO - "slavebuilddir": "test", 11:00:39 INFO - "scheduler": "tests-b2g-inbound-linux64-pgo-talos", 11:00:39 INFO - "branch": "b2g-inbound", 11:00:39 INFO - "repo_path": "integration/b2g-inbound", 11:00:39 INFO - "stage_platform": "linux64-pgo", 11:00:39 INFO - "builduid": "68e990e9c0f24a3ca2a18bce66186ba3", 11:00:39 INFO - "revision": "9ae9573c9e20580ddb1e4ee291923cfca9b86ba3" 11:00:39 INFO - }, 11:00:39 INFO - "sourcestamp": { 11:00:39 INFO - "repository": "", 11:00:39 INFO - "hasPatch": false, 11:00:39 INFO - "project": "", 11:00:39 INFO - "branch": "b2g-inbound-linux64-pgo-talos", 11:00:39 INFO - "changes": [ 11:00:39 INFO - { 11:00:39 INFO - "category": null, 11:00:39 INFO - "files": [ 11:00:39 INFO - { 11:00:39 INFO - "url": null, 11:00:39 INFO - "name": "https://queue.taskcluster.net/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 11:00:39 INFO - } 11:00:39 INFO - ], 11:00:39 INFO - "repository": "", 11:00:39 INFO - "rev": "9ae9573c9e20580ddb1e4ee291923cfca9b86ba3", 11:00:39 INFO - "who": "sendchange", 11:00:39 INFO - "when": 1448477978, 11:00:39 INFO - "number": 6706941, 11:00:39 INFO - "comments": "", 11:00:39 INFO - "project": "", 11:00:39 INFO - "at": "Wed 25 Nov 2015 10:59:38", 11:00:39 INFO - "branch": "b2g-inbound-linux64-pgo-talos", 11:00:39 INFO - "revlink": "", 11:00:39 INFO - "properties": [ 11:00:39 INFO - [ 11:00:39 INFO - "buildid", 11:00:39 INFO - "20151125083212", 11:00:39 INFO - "Change" 11:00:39 INFO - ], 11:00:39 INFO - [ 11:00:39 INFO - "builduid", 11:00:39 INFO - "68e990e9c0f24a3ca2a18bce66186ba3", 11:00:39 INFO - "Change" 11:00:39 INFO - ], 11:00:39 INFO - [ 11:00:39 INFO - "pgo_build", 11:00:39 INFO - "True", 11:00:39 INFO - "Change" 11:00:39 INFO - ] 11:00:39 INFO - ], 11:00:39 INFO - "revision": "9ae9573c9e20580ddb1e4ee291923cfca9b86ba3" 11:00:39 INFO - } 11:00:39 INFO - ], 11:00:39 INFO - "revision": "9ae9573c9e20580ddb1e4ee291923cfca9b86ba3" 11:00:39 INFO - } 11:00:39 INFO - } 11:00:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 11:00:39 INFO - Running post-action listener: _resource_record_post_action 11:00:39 INFO - ##### 11:00:39 INFO - ##### Running download-and-extract step. 11:00:39 INFO - ##### 11:00:39 INFO - Running pre-action listener: _resource_record_pre_action 11:00:39 INFO - Running main action method: download_and_extract 11:00:39 INFO - mkdir: /builds/slave/test-pgo/build/tests 11:00:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:00:39 INFO - https://queue.taskcluster.net/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 11:00:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/test_packages.json 11:00:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/test_packages.json 11:00:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 11:00:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 11:00:40 INFO - Downloaded 1302 bytes. 11:00:40 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 11:00:40 INFO - Using the following test package requirements: 11:00:40 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 11:00:40 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 11:00:40 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:00:40 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 11:00:40 INFO - u'jsshell-linux-x86_64.zip'], 11:00:40 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 11:00:40 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:00:40 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 11:00:40 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 11:00:40 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:00:40 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 11:00:40 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:00:40 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 11:00:40 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:00:40 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 11:00:40 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 11:00:40 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:00:40 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:00: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')]} 11:00:40 INFO - https://queue.taskcluster.net/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:00:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 11:00:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 11:00:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/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 11:00:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/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 11:00:42 INFO - Downloaded 22754021 bytes. 11:00:42 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 11:00:42 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 11:00:42 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 11:00:43 INFO - Return code: 0 11:00:43 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 11:00:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:00:43 INFO - https://queue.taskcluster.net/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:00:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 11:00:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 11:00:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/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 11:00:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/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 11:00:49 INFO - Downloaded 22754021 bytes. 11:00:49 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 11:00:49 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 11:00:49 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 11:00:50 INFO - Return code: 0 11:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:00:50 INFO - https://queue.taskcluster.net/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 11:00:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 11:00:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 11:00:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/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 11:00:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/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 11:00:51 INFO - Downloaded 11131717 bytes. 11:00:51 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 11:00:51 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 11:00:51 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 11:00:51 INFO - Return code: 0 11:00:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:00:51 INFO - https://queue.taskcluster.net/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:00:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 11:00:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 11:00:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/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 11:00:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/rUmNhVeiREK9eCcJtc-CcQ/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 11:00:52 INFO - Downloaded 58392439 bytes. 11:00:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 11:00:52 INFO - mkdir: /builds/slave/test-pgo/properties 11:00:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 11:00:52 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 11:00:52 INFO - Contents: 11:00:52 INFO - build_url:https://queue.taskcluster.net/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 11:00:52 INFO - Running post-action listener: _resource_record_post_action 11:00:52 INFO - Running post-action listener: set_extra_try_arguments 11:00:52 INFO - ##### 11:00:52 INFO - ##### Running populate-webroot step. 11:00:52 INFO - ##### 11:00:52 INFO - Running pre-action listener: _resource_record_pre_action 11:00:52 INFO - Running main action method: populate_webroot 11:00:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:00:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:00:52 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 11:00:52 INFO - '../fennec_ids.txt'], 11:00:52 INFO - 'tests': ['tcheck2']}, 11:00:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:00:52 INFO - '--tppagecycles', 11:00:52 INFO - '7'], 11:00:52 INFO - 'tests': ['tsvgm']}}, 11:00:52 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 11:00:52 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 11:00:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:00:52 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 11:00:52 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 11:00:52 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 11:00:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:00:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:00:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:00:52 INFO - 'talos_options': ['--e10s'], 11:00:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:00:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:00:52 INFO - 'tests': ['damp', 'tps']}, 11:00:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:00:52 INFO - 'talos_options': ['--e10s'], 11:00:52 INFO - 'tests': ['damp', 'tps']}, 11:00:52 INFO - 'g3': {'tests': ['dromaeo_dom']}, 11:00:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:00:52 INFO - 'other': {'tests': ['a11yr', 11:00:52 INFO - 'ts_paint', 11:00:52 INFO - 'tpaint', 11:00:52 INFO - 'sessionrestore', 11:00:52 INFO - 'sessionrestore_no_auto_restore']}, 11:00:52 INFO - 'other-e10s': {'talos_options': ['--e10s'], 11:00:52 INFO - 'tests': ['a11yr', 11:00:52 INFO - 'ts_paint', 11:00:52 INFO - 'tpaint', 11:00:52 INFO - 'sessionrestore', 11:00:52 INFO - 'sessionrestore_no_auto_restore']}, 11:00:52 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 11:00:52 INFO - 'tests': ['a11yr', 11:00:52 INFO - 'ts_paint', 11:00:52 INFO - 'tpaint', 11:00:52 INFO - 'sessionrestore', 11:00:52 INFO - 'sessionrestore_no_auto_restore']}, 11:00:52 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 11:00:52 INFO - 'tests': ['a11yr', 11:00:52 INFO - 'ts_paint', 11:00:52 INFO - 'tpaint', 11:00:52 INFO - 'sessionrestore', 11:00:52 INFO - 'sessionrestore_no_auto_restore']}, 11:00:52 INFO - 'other_l64': {'tests': ['a11yr', 11:00:52 INFO - 'ts_paint', 11:00:52 INFO - 'tpaint', 11:00:52 INFO - 'sessionrestore', 11:00:52 INFO - 'sessionrestore_no_auto_restore']}, 11:00:52 INFO - 'other_nol64': {'tests': ['a11yr', 11:00:52 INFO - 'ts_paint', 11:00:52 INFO - 'tpaint', 11:00:52 INFO - 'sessionrestore', 11:00:52 INFO - 'sessionrestore_no_auto_restore']}, 11:00:52 INFO - 'svgr': {'tests': ['tsvgx', 11:00:52 INFO - 'tsvgr_opacity', 11:00:52 INFO - 'tart', 11:00:52 INFO - 'tscrollx', 11:00:52 INFO - 'cart']}, 11:00:52 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 11:00:52 INFO - 'tests': ['tsvgx', 11:00:52 INFO - 'tsvgr_opacity', 11:00:52 INFO - 'tart', 11:00:52 INFO - 'tscrollx', 11:00:52 INFO - 'cart']}, 11:00:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:00:52 INFO - 'tests': ['tp5o']}, 11:00:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:00:52 INFO - 'talos_options': ['--e10s'], 11:00:52 INFO - 'tests': ['tp5o']}, 11:00:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:00:52 INFO - 'talos_options': ['--xperf_path', 11:00:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:00:52 INFO - 'tests': ['tp5n']}, 11:00:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:00:52 INFO - 'talos_options': ['--e10s', 11:00:52 INFO - '--xperf_path', 11:00:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:00:52 INFO - 'tests': ['tp5n']}}, 11:00:52 INFO - 'talos.zip': {'path': '', 11:00:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:00:52 INFO - Running post-action listener: _resource_record_post_action 11:00:52 INFO - ##### 11:00:52 INFO - ##### Running create-virtualenv step. 11:00:52 INFO - ##### 11:00:52 INFO - Running pre-action listener: _resource_record_pre_action 11:00:52 INFO - Running main action method: create_virtualenv 11:00:52 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 11:00:52 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 11:00:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 11:00:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:00:52 INFO - Using real prefix '/usr' 11:00:52 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 11:00:53 INFO - Installing distribute.............................................................................................................................................................................................done. 11:00:55 INFO - Installing pip.................done. 11:00:55 INFO - Return code: 0 11:00:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 11:00:55 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')]} 11:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:00:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:55 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')]} 11:00:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:00:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:55 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 0x2161030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238e7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d640>, '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 0x241e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241e9d0>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911', '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 11:00:55 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 11:00:55 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 11:00:55 INFO - Using env: {'DISPLAY': ':0', 11:00:55 INFO - 'HOME': '/home/cltbld', 11:00:55 INFO - 'LANG': 'en_US.UTF-8', 11:00:55 INFO - 'LANGUAGE': 'en_US:en', 11:00:55 INFO - 'LOGNAME': 'cltbld', 11:00:55 INFO - 'MAIL': '/var/mail/cltbld', 11:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:00:55 INFO - 'MOZ_NO_REMOTE': '1', 11:00:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:00:55 INFO - 'NO_EM_RESTART': '1', 11:00:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:00:55 INFO - 'PWD': '/builds/slave/test-pgo', 11:00:55 INFO - 'SHELL': '/bin/bash', 11:00:55 INFO - 'SHLVL': '1', 11:00:55 INFO - 'TERM': 'linux', 11:00:55 INFO - 'TMOUT': '86400', 11:00:55 INFO - 'USER': 'cltbld', 11:00:55 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911', 11:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:00:55 INFO - '_': '/tools/buildbot/bin/python'} 11:00:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:00:55 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 11:00:55 INFO - Cleaning up... 11:00:55 INFO - Return code: 0 11:00:55 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 11:00:55 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')]} 11:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:00:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:55 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')]} 11:00:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:00:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:55 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 0x2161030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238e7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d640>, '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 0x241e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241e9d0>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911', '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 11:00:55 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 11:00:55 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 11:00:55 INFO - Using env: {'DISPLAY': ':0', 11:00:55 INFO - 'HOME': '/home/cltbld', 11:00:55 INFO - 'LANG': 'en_US.UTF-8', 11:00:55 INFO - 'LANGUAGE': 'en_US:en', 11:00:55 INFO - 'LOGNAME': 'cltbld', 11:00:55 INFO - 'MAIL': '/var/mail/cltbld', 11:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:00:55 INFO - 'MOZ_NO_REMOTE': '1', 11:00:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:00:55 INFO - 'NO_EM_RESTART': '1', 11:00:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:00:55 INFO - 'PWD': '/builds/slave/test-pgo', 11:00:55 INFO - 'SHELL': '/bin/bash', 11:00:55 INFO - 'SHLVL': '1', 11:00:55 INFO - 'TERM': 'linux', 11:00:55 INFO - 'TMOUT': '86400', 11:00:55 INFO - 'USER': 'cltbld', 11:00:55 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911', 11:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:00:55 INFO - '_': '/tools/buildbot/bin/python'} 11:00:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:00:55 INFO - Downloading/unpacking psutil>=0.7.1 11:00:55 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 11:00:55 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 11:00:55 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 11:00:55 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 11:00:55 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 11:00:55 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 11:00:57 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 11:00:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 11:00:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 11:00:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:00:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:00:57 INFO - Installing collected packages: psutil 11:00:57 INFO - Running setup.py install for psutil 11:00:57 INFO - building 'psutil._psutil_linux' extension 11:00:57 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 11:00:57 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 11:00:57 INFO - building 'psutil._psutil_posix' extension 11:00:57 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 11:00:58 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 11:00:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:00:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:00:58 INFO - Successfully installed psutil 11:00:58 INFO - Cleaning up... 11:00:58 INFO - Return code: 0 11:00:58 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 11:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:00:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:00:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:58 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 0x2161030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238e7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d640>, '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 0x241e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241e9d0>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911', '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 11:00:58 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 11:00:58 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 11:00:58 INFO - Using env: {'DISPLAY': ':0', 11:00:58 INFO - 'HOME': '/home/cltbld', 11:00:58 INFO - 'LANG': 'en_US.UTF-8', 11:00:58 INFO - 'LANGUAGE': 'en_US:en', 11:00:58 INFO - 'LOGNAME': 'cltbld', 11:00:58 INFO - 'MAIL': '/var/mail/cltbld', 11:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:00:58 INFO - 'MOZ_NO_REMOTE': '1', 11:00:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:00:58 INFO - 'NO_EM_RESTART': '1', 11:00:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:00:58 INFO - 'PWD': '/builds/slave/test-pgo', 11:00:58 INFO - 'SHELL': '/bin/bash', 11:00:58 INFO - 'SHLVL': '1', 11:00:58 INFO - 'TERM': 'linux', 11:00:58 INFO - 'TMOUT': '86400', 11:00:58 INFO - 'USER': 'cltbld', 11:00:58 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911', 11:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:00:58 INFO - '_': '/tools/buildbot/bin/python'} 11:00:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:00:58 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 11:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:00:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:00:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:00:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:00:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:00 INFO - Downloading mozsystemmonitor-0.0.tar.gz 11:01:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 11:01:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:01:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 11:01:00 INFO - Installing collected packages: mozsystemmonitor 11:01:00 INFO - Running setup.py install for mozsystemmonitor 11:01:00 INFO - Successfully installed mozsystemmonitor 11:01:00 INFO - Cleaning up... 11:01:00 INFO - Return code: 0 11:01:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 11:01:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:01:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:00 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 0x2161030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238e7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d640>, '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 0x241e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241e9d0>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911', '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 11:01:00 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 11:01:00 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 11:01:00 INFO - Using env: {'DISPLAY': ':0', 11:01:00 INFO - 'HOME': '/home/cltbld', 11:01:00 INFO - 'LANG': 'en_US.UTF-8', 11:01:00 INFO - 'LANGUAGE': 'en_US:en', 11:01:00 INFO - 'LOGNAME': 'cltbld', 11:01:00 INFO - 'MAIL': '/var/mail/cltbld', 11:01:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:00 INFO - 'MOZ_NO_REMOTE': '1', 11:01:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:01:00 INFO - 'NO_EM_RESTART': '1', 11:01:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:01:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:01:00 INFO - 'PWD': '/builds/slave/test-pgo', 11:01:00 INFO - 'SHELL': '/bin/bash', 11:01:00 INFO - 'SHLVL': '1', 11:01:00 INFO - 'TERM': 'linux', 11:01:00 INFO - 'TMOUT': '86400', 11:01:00 INFO - 'USER': 'cltbld', 11:01:00 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911', 11:01:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:00 INFO - '_': '/tools/buildbot/bin/python'} 11:01:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:00 INFO - Downloading/unpacking blobuploader==1.2.4 11:01:00 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 11:01:00 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 11:01:00 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 11:01:00 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 11:01:00 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 11:01:00 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 11:01:02 INFO - Downloading blobuploader-1.2.4.tar.gz 11:01:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 11:01:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:01:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:01:02 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 11:01:02 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 11:01:02 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 11:01:02 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 11:01:02 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 11:01:02 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 11:01:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 11:01:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 11:01:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:01:02 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 11:01:02 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 11:01:02 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 11:01:02 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 11:01:02 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 11:01:02 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 11:01:03 INFO - Downloading docopt-0.6.1.tar.gz 11:01:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 11:01:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 11:01:03 INFO - Installing collected packages: blobuploader, requests, docopt 11:01:03 INFO - Running setup.py install for blobuploader 11:01:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:01:03 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 11:01:03 INFO - Running setup.py install for requests 11:01:03 INFO - Running setup.py install for docopt 11:01:03 INFO - Successfully installed blobuploader requests docopt 11:01:03 INFO - Cleaning up... 11:01:03 INFO - Return code: 0 11:01:03 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 11:01:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:01:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:03 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 0x2161030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238e7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d640>, '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 0x241e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241e9d0>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911', '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 11:01:03 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 11:01:03 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 11:01:03 INFO - Using env: {'DISPLAY': ':0', 11:01:03 INFO - 'HOME': '/home/cltbld', 11:01:03 INFO - 'LANG': 'en_US.UTF-8', 11:01:03 INFO - 'LANGUAGE': 'en_US:en', 11:01:03 INFO - 'LOGNAME': 'cltbld', 11:01:03 INFO - 'MAIL': '/var/mail/cltbld', 11:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:03 INFO - 'MOZ_NO_REMOTE': '1', 11:01:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:01:03 INFO - 'NO_EM_RESTART': '1', 11:01:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:01:03 INFO - 'PWD': '/builds/slave/test-pgo', 11:01:03 INFO - 'SHELL': '/bin/bash', 11:01:03 INFO - 'SHLVL': '1', 11:01:03 INFO - 'TERM': 'linux', 11:01:03 INFO - 'TMOUT': '86400', 11:01:03 INFO - 'USER': 'cltbld', 11:01:03 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911', 11:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:03 INFO - '_': '/tools/buildbot/bin/python'} 11:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 11:01:03 INFO - Running setup.py (path:/tmp/pip-PslAvr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 11:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 11:01:04 INFO - Running setup.py (path:/tmp/pip-PuAPUL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 11:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 11:01:04 INFO - Running setup.py (path:/tmp/pip-tfn_91-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 11:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 11:01:04 INFO - Running setup.py (path:/tmp/pip-HOPuwO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 11:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 11:01:04 INFO - Running setup.py (path:/tmp/pip-TJKAjT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 11:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 11:01:04 INFO - Running setup.py (path:/tmp/pip-fePwNA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 11:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 11:01:04 INFO - Running setup.py (path:/tmp/pip-XLsEhx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 11:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 11:01:04 INFO - Running setup.py (path:/tmp/pip-JsAr5q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 11:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 11:01:04 INFO - Running setup.py (path:/tmp/pip-dOyI0f-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 11:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 11:01:04 INFO - Running setup.py (path:/tmp/pip-dFZUsm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 11:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 11:01:04 INFO - Running setup.py (path:/tmp/pip-sNl_PU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 11:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 11:01:04 INFO - Running setup.py (path:/tmp/pip-mY65En-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 11:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 11:01:04 INFO - Running setup.py (path:/tmp/pip-XXmFC2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 11:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 11:01:04 INFO - Running setup.py (path:/tmp/pip-gTfdRy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 11:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 11:01:04 INFO - Running setup.py (path:/tmp/pip-wtxOtS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 11:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 11:01:05 INFO - Running setup.py (path:/tmp/pip-_SqMq4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 11:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 11:01:05 INFO - Running setup.py (path:/tmp/pip-LZ7dLB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 11:01:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:01:05 INFO - Running setup.py install for manifestparser 11:01:05 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 11:01:05 INFO - Running setup.py install for mozcrash 11:01:05 INFO - Running setup.py install for mozdebug 11:01:05 INFO - Running setup.py install for mozdevice 11:01:05 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 11:01:05 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 11:01:05 INFO - Running setup.py install for mozfile 11:01:05 INFO - Running setup.py install for mozhttpd 11:01:05 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 11:01:05 INFO - Running setup.py install for mozinfo 11:01:05 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 11:01:05 INFO - Running setup.py install for mozInstall 11:01:05 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 11:01:05 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 11:01:05 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 11:01:05 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 11:01:05 INFO - Running setup.py install for mozleak 11:01:06 INFO - Running setup.py install for mozlog 11:01:06 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 11:01:06 INFO - Running setup.py install for moznetwork 11:01:06 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 11:01:06 INFO - Running setup.py install for mozprocess 11:01:06 INFO - Running setup.py install for mozprofile 11:01:06 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 11:01:06 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 11:01:06 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 11:01:06 INFO - Running setup.py install for mozrunner 11:01:06 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 11:01:06 INFO - Running setup.py install for mozscreenshot 11:01:06 INFO - Running setup.py install for moztest 11:01:06 INFO - Running setup.py install for mozversion 11:01:06 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 11:01:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:01:06 INFO - Cleaning up... 11:01:06 INFO - Return code: 0 11:01:06 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 11:01:06 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')]} 11:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:06 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')]} 11:01:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:06 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 0x2161030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238e7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d640>, '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 0x241e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241e9d0>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911', '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 11:01:06 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 11:01:06 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 11:01:06 INFO - Using env: {'DISPLAY': ':0', 11:01:06 INFO - 'HOME': '/home/cltbld', 11:01:06 INFO - 'LANG': 'en_US.UTF-8', 11:01:06 INFO - 'LANGUAGE': 'en_US:en', 11:01:06 INFO - 'LOGNAME': 'cltbld', 11:01:06 INFO - 'MAIL': '/var/mail/cltbld', 11:01:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:06 INFO - 'MOZ_NO_REMOTE': '1', 11:01:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:01:06 INFO - 'NO_EM_RESTART': '1', 11:01:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:01:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:01:06 INFO - 'PWD': '/builds/slave/test-pgo', 11:01:06 INFO - 'SHELL': '/bin/bash', 11:01:06 INFO - 'SHLVL': '1', 11:01:06 INFO - 'TERM': 'linux', 11:01:06 INFO - 'TMOUT': '86400', 11:01:06 INFO - 'USER': 'cltbld', 11:01:06 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911', 11:01:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:06 INFO - '_': '/tools/buildbot/bin/python'} 11:01:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 11:01:07 INFO - Running setup.py (path:/tmp/pip-1bzrjd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 11:01:07 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)) 11:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 11:01:07 INFO - Running setup.py (path:/tmp/pip-4rIlbr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 11:01:07 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)) 11:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 11:01:07 INFO - Running setup.py (path:/tmp/pip-yo6eH4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 11:01:07 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)) 11:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 11:01:07 INFO - Running setup.py (path:/tmp/pip-anUyci-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 11:01:07 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)) 11:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 11:01:07 INFO - Running setup.py (path:/tmp/pip-QeFqWS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 11:01:07 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)) 11:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 11:01:07 INFO - Running setup.py (path:/tmp/pip-cAFaIV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 11:01:07 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)) 11:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 11:01:07 INFO - Running setup.py (path:/tmp/pip-nFpJvw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 11:01:07 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)) 11:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 11:01:07 INFO - Running setup.py (path:/tmp/pip-T_iaGd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 11:01:07 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)) 11:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 11:01:07 INFO - Running setup.py (path:/tmp/pip-0e4FM_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 11:01:07 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)) 11:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 11:01:07 INFO - Running setup.py (path:/tmp/pip-D7XTQv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 11:01:07 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)) 11:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 11:01:07 INFO - Running setup.py (path:/tmp/pip-HPBAyN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 11:01:07 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)) 11:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 11:01:07 INFO - Running setup.py (path:/tmp/pip-e7393v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 11:01:08 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)) 11:01:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 11:01:08 INFO - Running setup.py (path:/tmp/pip-Pag9gq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 11:01:08 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)) 11:01:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 11:01:08 INFO - Running setup.py (path:/tmp/pip-pDjFU1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 11:01:08 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)) 11:01:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 11:01:08 INFO - Running setup.py (path:/tmp/pip-6H4e9a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 11:01:08 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)) 11:01:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 11:01:08 INFO - Running setup.py (path:/tmp/pip-ZY9pBp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 11:01:08 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)) 11:01:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 11:01:08 INFO - Running setup.py (path:/tmp/pip-e4xtXN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 11:01:08 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)) 11:01:08 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)) 11:01:08 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)) 11:01:08 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)) 11:01:08 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)) 11:01:08 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)) 11:01:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 11:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:01:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:10 INFO - Downloading blessings-1.5.1.tar.gz 11:01:10 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 11:01:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 11:01:10 INFO - Installing collected packages: blessings 11:01:10 INFO - Running setup.py install for blessings 11:01:10 INFO - Successfully installed blessings 11:01:10 INFO - Cleaning up... 11:01:10 INFO - Return code: 0 11:01:10 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 11:01:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 11:01:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 11:01:10 INFO - Reading from file tmpfile_stdout 11:01:10 INFO - Current package versions: 11:01:10 INFO - argparse == 1.2.1 11:01:10 INFO - blessings == 1.5.1 11:01:10 INFO - blobuploader == 1.2.4 11:01:10 INFO - docopt == 0.6.1 11:01:10 INFO - manifestparser == 1.1 11:01:10 INFO - mozInstall == 1.12 11:01:10 INFO - mozcrash == 0.16 11:01:10 INFO - mozdebug == 0.1 11:01:10 INFO - mozdevice == 0.47 11:01:10 INFO - mozfile == 1.2 11:01:10 INFO - mozhttpd == 0.7 11:01:10 INFO - mozinfo == 0.9 11:01:10 INFO - mozleak == 0.1 11:01:10 INFO - mozlog == 3.1 11:01:10 INFO - moznetwork == 0.27 11:01:10 INFO - mozprocess == 0.22 11:01:10 INFO - mozprofile == 0.27 11:01:10 INFO - mozrunner == 6.11 11:01:10 INFO - mozscreenshot == 0.1 11:01:10 INFO - mozsystemmonitor == 0.0 11:01:10 INFO - moztest == 0.7 11:01:10 INFO - mozversion == 1.4 11:01:10 INFO - psutil == 3.1.1 11:01:10 INFO - requests == 1.2.3 11:01:10 INFO - wsgiref == 0.1.2 11:01:10 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 11:01:10 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')]} 11:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:10 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')]} 11:01:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:10 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 0x2161030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238e7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d640>, '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 0x241e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241e9d0>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911', '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 11:01:10 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 11:01:10 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 11:01:10 INFO - Using env: {'DISPLAY': ':0', 11:01:10 INFO - 'HOME': '/home/cltbld', 11:01:10 INFO - 'LANG': 'en_US.UTF-8', 11:01:10 INFO - 'LANGUAGE': 'en_US:en', 11:01:10 INFO - 'LOGNAME': 'cltbld', 11:01:10 INFO - 'MAIL': '/var/mail/cltbld', 11:01:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:10 INFO - 'MOZ_NO_REMOTE': '1', 11:01:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:01:10 INFO - 'NO_EM_RESTART': '1', 11:01:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:01:10 INFO - 'PWD': '/builds/slave/test-pgo', 11:01:10 INFO - 'SHELL': '/bin/bash', 11:01:10 INFO - 'SHLVL': '1', 11:01:10 INFO - 'TERM': 'linux', 11:01:10 INFO - 'TMOUT': '86400', 11:01:10 INFO - 'USER': 'cltbld', 11:01:10 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911', 11:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:10 INFO - '_': '/tools/buildbot/bin/python'} 11:01:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:10 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)) 11:01:10 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)) 11:01:10 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)) 11:01:10 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)) 11:01:10 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)) 11:01:10 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)) 11:01:10 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)) 11:01:10 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)) 11:01:10 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)) 11:01:10 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)) 11:01:10 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)) 11:01:10 INFO - Cleaning up... 11:01:10 INFO - Return code: 0 11:01:10 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 11:01:10 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')]} 11:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:10 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')]} 11:01:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:10 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 0x2161030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238e7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d640>, '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 0x241e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241e9d0>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911', '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 11:01:10 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 11:01:10 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 11:01:10 INFO - Using env: {'DISPLAY': ':0', 11:01:10 INFO - 'HOME': '/home/cltbld', 11:01:10 INFO - 'LANG': 'en_US.UTF-8', 11:01:10 INFO - 'LANGUAGE': 'en_US:en', 11:01:10 INFO - 'LOGNAME': 'cltbld', 11:01:10 INFO - 'MAIL': '/var/mail/cltbld', 11:01:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:10 INFO - 'MOZ_NO_REMOTE': '1', 11:01:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:01:10 INFO - 'NO_EM_RESTART': '1', 11:01:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:01:10 INFO - 'PWD': '/builds/slave/test-pgo', 11:01:10 INFO - 'SHELL': '/bin/bash', 11:01:10 INFO - 'SHLVL': '1', 11:01:10 INFO - 'TERM': 'linux', 11:01:10 INFO - 'TMOUT': '86400', 11:01:10 INFO - 'USER': 'cltbld', 11:01:10 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911', 11:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:10 INFO - '_': '/tools/buildbot/bin/python'} 11:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:11 INFO - Downloading/unpacking jsonschema 11:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:01:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:01:12 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 11:01:12 INFO - Downloading/unpacking functools32 (from jsonschema) 11:01:12 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 11:01:12 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 11:01:12 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 11:01:12 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 11:01:12 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 11:01:12 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 11:01:12 INFO - Downloading functools32-3.2.3-2.tar.gz 11:01:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 11:01:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 11:01:12 INFO - warning: no files found matching '*.txt' 11:01:12 INFO - no previously-included directories found matching 'build' 11:01:12 INFO - no previously-included directories found matching 'dist' 11:01:12 INFO - no previously-included directories found matching '.git*' 11:01:12 INFO - Installing collected packages: jsonschema, functools32 11:01:12 INFO - Running setup.py install for functools32 11:01:13 INFO - warning: no files found matching '*.txt' 11:01:13 INFO - no previously-included directories found matching 'build' 11:01:13 INFO - no previously-included directories found matching 'dist' 11:01:13 INFO - no previously-included directories found matching '.git*' 11:01:13 INFO - Successfully installed jsonschema functools32 11:01:13 INFO - Cleaning up... 11:01:13 INFO - Return code: 0 11:01:13 INFO - Running post-action listener: _resource_record_post_action 11:01:13 INFO - Running post-action listener: _start_resource_monitoring 11:01:13 INFO - Starting resource monitoring. 11:01:13 INFO - ##### 11:01:13 INFO - ##### Running install step. 11:01:13 INFO - ##### 11:01:13 INFO - Running pre-action listener: _resource_record_pre_action 11:01:13 INFO - Running main action method: install 11:01:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 11:01:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 11:01:13 INFO - Reading from file tmpfile_stdout 11:01:13 INFO - Detecting whether we're running mozinstall >=1.0... 11:01:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 11:01:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 11:01:13 INFO - Reading from file tmpfile_stdout 11:01:13 INFO - Output received: 11:01:13 INFO - Usage: mozinstall [options] installer 11:01:13 INFO - Options: 11:01:13 INFO - -h, --help show this help message and exit 11:01:13 INFO - -d DEST, --destination=DEST 11:01:13 INFO - Directory to install application into. [default: 11:01:13 INFO - "/builds/slave/test-pgo"] 11:01:13 INFO - --app=APP Application being installed. [default: firefox] 11:01:13 INFO - mkdir: /builds/slave/test-pgo/build/application 11:01:13 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'] 11:01:13 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 11:01:28 INFO - Reading from file tmpfile_stdout 11:01:28 INFO - Output received: 11:01:28 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 11:01:28 INFO - Running post-action listener: _resource_record_post_action 11:01:28 INFO - ##### 11:01:28 INFO - ##### Running run-tests step. 11:01:28 INFO - ##### 11:01:28 INFO - Running pre-action listener: _resource_record_pre_action 11:01:28 INFO - Running main action method: run_tests 11:01:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 11:01:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 11:01:28 INFO - Python 2.7.3 11:01:28 INFO - Return code: 0 11:01:28 INFO - grabbing minidump binary from tooltool 11:01: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')]} 11:01:28 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 0x241d640>, '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 0x241e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241e9d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 11:01:28 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 11:01:28 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 11:01:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:01:28 INFO - Return code: 0 11:01:28 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 11:01:28 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 11:01:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 11:01:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 11:01:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 11:01:28 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/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 11:01:28 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/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-058 --webServer localhost 11:01:28 INFO - Using env: {'DISPLAY': ':0', 11:01:28 INFO - 'HOME': '/home/cltbld', 11:01:28 INFO - 'LANG': 'en_US.UTF-8', 11:01:28 INFO - 'LANGUAGE': 'en_US:en', 11:01:28 INFO - 'LOGNAME': 'cltbld', 11:01:28 INFO - 'MAIL': '/var/mail/cltbld', 11:01:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 11:01:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 11:01:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:28 INFO - 'MOZ_NO_REMOTE': '1', 11:01:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 11:01:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:01:28 INFO - 'NO_EM_RESTART': '1', 11:01:28 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:01:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:01:28 INFO - 'PWD': '/builds/slave/test-pgo', 11:01:28 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 11:01:28 INFO - 'SHELL': '/bin/bash', 11:01:28 INFO - 'SHLVL': '1', 11:01:28 INFO - 'TERM': 'linux', 11:01:28 INFO - 'TMOUT': '86400', 11:01:28 INFO - 'USER': 'cltbld', 11:01:28 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911', 11:01:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:28 INFO - '_': '/tools/buildbot/bin/python'} 11:01:28 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/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost'] with output_timeout 3600 11:01:28 INFO - mozversion INFO | application_buildid: 20151125083212 11:01:28 INFO - mozversion INFO | application_changeset: 9ae9573c9e20580ddb1e4ee291923cfca9b86ba3 11:01:28 INFO - mozversion INFO | application_display_name: Nightly 11:01:28 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:01:28 INFO - mozversion INFO | application_name: Firefox 11:01:28 INFO - mozversion INFO | application_remotingname: firefox 11:01:28 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/b2g-inbound 11:01:28 INFO - mozversion INFO | application_vendor: Mozilla 11:01:28 INFO - mozversion INFO | application_version: 45.0a1 11:01:28 INFO - mozversion INFO | platform_buildid: 20151125083212 11:01:28 INFO - mozversion INFO | platform_changeset: 9ae9573c9e20580ddb1e4ee291923cfca9b86ba3 11:01:28 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/b2g-inbound 11:01:28 INFO - mozversion INFO | platform_version: 45.0a1 11:01:28 INFO - 2015-11-25 11:01:28,432 DEBUG : using testdate: 1448478088 11:01:28 INFO - 2015-11-25 11:01:28,432 DEBUG : actual date: 1448478088 11:01:28 INFO - 2015-11-25 11:01:28,441 INFO : starting webserver on 'localhost:42995' 11:01:28 INFO - 2015-11-25 11:01:28,441 INFO : Starting test suite talos-linux64-ix-058 11:01:28 INFO - 2015-11-25 11:01:28,442 INFO : Starting test dromaeo_css 11:01:28 INFO - 2015-11-25 11:01:28,442 DEBUG : operating with platform_type : linux_ 11:01:28 INFO - 2015-11-25 11:01:28,442 INFO : Initialising browser for dromaeo_css test... 11:01:28 INFO - 2015-11-25 11:01:28,453 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmprOv08p/profile http://localhost:42995/getInfo.html 11:01:32 INFO - 2015-11-25 11:01:32,286 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 11:01:32 INFO - 2015-11-25 11:01:32,286 DEBUG : BROWSER_OUTPUT: colorDepth:24 11:01:32 INFO - 2015-11-25 11:01:32,286 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 11:01:32 INFO - 2015-11-25 11:01:32,287 DEBUG : BROWSER_OUTPUT: __metrics 11:01:32 INFO - 2015-11-25 11:01:32,343 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] 11:01:33 INFO - 2015-11-25 11:01:33,622 INFO : Browser initialized. 11:01:33 INFO - 2015-11-25 11:01:33,622 INFO : Running cycle 1/1 for dromaeo_css test... 11:01:33 INFO - 2015-11-25 11:01:33,622 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmprOv08p/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 11:01:45 INFO - 2015-11-25 11:01:45,338 DEBUG : BROWSER_OUTPUT: RSS: Main: 138821632 11:01:45 INFO - 2015-11-25 11:01:45,338 DEBUG : BROWSER_OUTPUT: 11:02:36 INFO - 2015-11-25 11:02:36,935 DEBUG : BROWSER_OUTPUT: 1448478156933 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] 11:04:45 INFO - 2015-11-25 11:04:45,781 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42995/tests/dromaeo/cssquery-ext.html) 11:04:46 INFO - 2015-11-25 11:04:46,927 DEBUG : BROWSER_OUTPUT: RSS: Main: 263090176 11:04:46 INFO - 2015-11-25 11:04:46,927 DEBUG : BROWSER_OUTPUT: 11:07:49 INFO - 2015-11-25 11:07:49,270 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/dromaeo/cssquery-ext.html (next: http://localhost:42995/tests/dromaeo/cssquery-jquery.html) 11:07:49 INFO - 2015-11-25 11:07:49,831 DEBUG : BROWSER_OUTPUT: RSS: Main: 223649792 11:07:49 INFO - 2015-11-25 11:07:49,831 DEBUG : BROWSER_OUTPUT: 11:10:51 INFO - 2015-11-25 11:10:51,659 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42995/tests/dromaeo/cssquery-mootools.html) 11:10:52 INFO - 2015-11-25 11:10:52,232 DEBUG : BROWSER_OUTPUT: RSS: Main: 210022400 11:10:52 INFO - 2015-11-25 11:10:52,232 DEBUG : BROWSER_OUTPUT: 11:13:54 INFO - 2015-11-25 11:13:54,061 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42995/tests/dromaeo/cssquery-prototype.html) 11:13:54 INFO - 2015-11-25 11:13:54,643 DEBUG : BROWSER_OUTPUT: RSS: Main: 199823360 11:13:54 INFO - 2015-11-25 11:13:54,643 DEBUG : BROWSER_OUTPUT: 11:16:57 INFO - 2015-11-25 11:16:57,060 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42995/tests/dromaeo/cssquery-yui.html) 11:16:57 INFO - 2015-11-25 11:16:57,654 DEBUG : BROWSER_OUTPUT: RSS: Main: 204754944 11:16:57 INFO - 2015-11-25 11:16:57,654 DEBUG : BROWSER_OUTPUT: 11:20:00 INFO - 2015-11-25 11:20:00,396 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/dromaeo/cssquery-yui.html (next: http://localhost:42995/tests/dromaeo/cssquery-dojo.html) 11:20:00 INFO - 2015-11-25 11:20:00,654 DEBUG : BROWSER_OUTPUT: RSS: Main: 261013504 11:20:00 INFO - 2015-11-25 11:20:00,655 DEBUG : BROWSER_OUTPUT: 11:20:00 INFO - 2015-11-25 11:20:00,655 DEBUG : BROWSER_OUTPUT: __start_tp_report 11:20:00 INFO - 2015-11-25 11:20:00,655 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 11:20:00 INFO - 2015-11-25 11:20:00,655 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 11:20:00 INFO - 2015-11-25 11:20:00,656 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 11:20:00 INFO - 2015-11-25 11:20:00,656 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3168.9225663274487;3259.8696052157916;3291.078498020555;3302.8183449910243;3316.6849149330815;5275.841724748254;5380.838574842751;5428.972855135724;5524.861878453035;5538.390777014531;5365.651232669874;5416.972915135429;5424.159255315422;5480.451954804527;5518.365387980383;4944.5797107245835;4991.550760431561;5013.047520971022;5037.874053148664;5116.820911268106;4834.4923783002805;5063.898722025558;5083.364579427572;5121.155009423441;5161.329027226455;4879.8780030499165;4959.553640172384;4982.202847544412;4991.151504244287;5019.372578427697;5042.3949126104735;5068.239764035387;5074.746262686851;5089.923651145235;5135.152699804527;4850.708957462563;5042.722650254234;5043;5059.038782631288;5072.416672082684;5171.948280517185;5249.947500524984;5253.86865328366;5273.2617433559335;5276.683398996034;5959.046552551615;5979.431953964367;5986.461218490357;5989.13157592151;5995.280566332024;5775.5957083004205;5988.161657367972;6010.519158467312;6027.09593560965;6032.607880487798;5495.890082198333;5527;5578.219049333096;5689.061304884648;5702.287214098238;5999.190109335271;6004.0693692477735;6022.879542409215;6047.546434017467;6052.727627256784;5686.431356864363;5769.653820770767;5774.769009239583;5775.595708300462;5847.6783776891825;5762;5840.094785308284;5900.704964751745;5907.763689452459;5988.371221021817;5838.38696936824;5898.292204935349;5898.3216930053595;5916.763329466773;5964.522838172936;5771.076627739541;5886.411358864079;5887.087501437284;5889.4993925515955;5900.321463031806;4455.131249406335;4506.81972721094;4527.977360113178;4542.704724192917;4555.498895121534;4029.879103626896;4041.818118184689;4051.9797401013398;4074.7758873261664;4093.038136038029;5385.3268341457315;5589.944100558942;5591.384947655754;5595.692236926927;5599.048161812501;5829.708514574254;5870.295564532198;5918.260217472816;5954.07711804671;5976.671283079472;5663;5674.886502269931;5675.262215911987;5678.545716342683;5678.545716342765;5182.274481572583;5198.402183748917;5200.063988482109;5206.375234971828;5260.684358938476;5871.207387002785;6001.7599296027665;6024.03615421548;6027.819165425044;6173.81478555644;5997.610155339889;6020.759169633166;6038.154658347923;6042.697865106815;6043.425874541911;6139.386061393825;6209.285932117684;6209.348018457997;6212.378762123752;6216.7824126155365;5769.105788602773;5786.33457152416;5884.411558844081;5891.204687367151;5909.5863289569315;5963.552733545088;6056.90914636272;6073.058675905242;6081.878362432815;6082.330943596113;5897.056470964625;5900.262467191601;5902.085176797603;5903.852403689943;5905.793297234576;5690.829275121753;5743.655380677173;5760.884782304414;5826;5862.853428664317;5676.545876329801;5687.516561092353;5689.971550142222;5704.971475142598;5740.770369185353;5773.156862159255;5799.623024503394;5815.883682326415;5823.7379317931645;5887;5631.577631677558;5649.887002260014;5710.057840456278;5748;5755.913661295168 11:20:00 INFO - 2015-11-25 11:20:00,656 DEBUG : BROWSER_OUTPUT: |1;ext.html;563.2986931270566;578.2944807335051;599.7720866070895;601.278465840991;613.2947110822554;31031.844840775793;39099.60900390995;39664.40503392444;39698.404523932164;39714.801425992904;42306.365404518954;42670.14659706812;42788.572114278926;42811;42857.35713964293;7427.554346739213;7540.283673051052;8742.213200811926;8853.778655533632;8912;10507.2644914856;10678.893211067907;10730.409827459487;10741.301815382014;10755.623553175641;10381.740456488611;10481.109105725984;10493.947530262376;10497.422641754738;10501.474926253695;7732.110807257153;7890.684372625088;7904.249096335839;10392.896071039306;10514.106300964351;10222.437765922872;10363.378197308186;10365.689029329133;10370;10397.272190946636;39917.60082399168;40591.39112913309;40838;40854.79572602118;40982.79508602468;3371.359441706068;3912.6674232690257;3913.902152446183;3966.6430021297933;3982.6614737747327;29292.560611590845;29644.258893527618;30226.84886575575;30258.243543911358;30295.697043029508;3739.9439008414897;3843.8846834595006;3867.9613203867884;3914.8042597869994;3998.52017757868;276701;277223;279077;281206;281677;61739;62232.377676222655;62239;62469.68765156145;62618.68690656609;66657.33342666608;66684.6665766668;66774.66612666905;66810;66883;16151.11168885699;16336.509904702878;16362.672746545002;16432.424865129662;16452;12297.200681955645;12316.384180790925;12358.691032724075;12361.752764944651;12374.443150058269;20032;20744.79255207459;20857.269995550083;20924.16303347849;20953.68569471459;7677.347425468786;7737.497062690907;7782;7831.334336581454;7937.920620793718;878.0517041595062;893.0801274687083;998.605550807427;1009.3136667066466;1036.6423583863555;28280.434391312057;28740.856295718804;29051.41897162087;29076.85461572721;29427.264318391783;1033.788073444942;1069.2889228662852;1110.9611163609236;1121.9887801122047;1157.1668398752884;15003.249837508081;15454.690906181813;15616.76574851378;15627;15776.605584860241;378581;382310;383055;384315;385033;52455.213171802454;52541;52860.20709689358;52986.47013529892;53010.20484692733;1141.560499207811;1252.1110011891476;1333.8399392073013;1336.7927971164486;1349.8852597529124;29437;30529;31985.8400707995;32861.34277314488;33035.41972827853;43448.34827477527;49252.75373623109;49597.504024960734;49647.50352496429;49839.75080124576;286796;290573;296452;296787;297578;38488.422673659355;39379.80310098431;39405;39432.80283598564;41069;47112;47311;47785.76107119581;48230.276545851135;48391.75804120957;37782.62217377901;38766.629490958694;39161.2167756649;39615;40074.59925400825;38318;39854.80072599618;39989.80005099956;41884.58115418807;43298.567014329456;36598.45102323414;37026.62973370232;37697.62302376941;37744.24511509809;37771.2445751078;31536.842315788275;31875;33715.83142084274;33908.15229619279;33932.3213535723;25204.369890752878;26034.34914127162;26072.496841558506;26076.478470430866;26344.73655263423 11:20:00 INFO - 2015-11-25 11:20:00,656 DEBUG : BROWSER_OUTPUT: |2;jquery.html;358.998205008975;359.2169071424297;359.77693829825495;360.0889748935194;362.76420326787587;14394.712105757879;14420.143813291537;16391.180440977947;16668.749968750464;17325.740113898286;15963.281652325644;16152.353905843782;16289.755653665205;16868.662626747457;16906.66186676269;5298.15229563269;5450.482204190595;5452.727363631822;5455.890882182354;5456.945430545703;4033.415154802552;4170.145120250357;5383.057964856134;5440.319960004999;5827.417258274181;5948.256467941507;5974.074018527113;5999.970000149993;6003.969980150093;6004.699765011732;5133.563647090002;5294.735263236823;5306.73466326686;5319.175527793198;5943.762249510014;5080.949190508085;5103.897922041576;5104.132297509433;5127.2052831811125;5382.865428364283;12799.528054273691;13016.739665206738;13019.479220831156;13777.862221377758;20341.18635254588;1965.6363572738946;2079.1371580793952;2119.141747592212;2125.946851328714;2209.7790220977936;9430.10414010668;9451.385659932152;9557.856632150524;10413.3751974881;13375.665608359772;1794.7666803315617;1834.8899066056078;1850.5928695686928;1851.4075495841337;1875.099952023023;62257;63231.36768632301;63256.68371658112;65038.3496165037;65233.673831631015;9701.51492425376;9741.853872191923;9908.456105748644;9952;9982;13050.804237936438;13053.804192937114;13064.54274100402;13069.673258168621;13074.28091454979;7343.742968996059;7377.852442951111;7411.592362420119;7423.257674232534;7468.439867009996;7180.246074162243;7217.566945983258;7240.927590724025;7243.384312333511;7250.129984401906;4191.434156388909;4207.347861081537;4241.448611680462;4272.33778764292;4275.8075886585175;1649.128239448211;1689.7211960026705;1704.1479260369815;1710.8716846236582;1774.7870255569414;400.28549039963525;411.2802595457949;470.543572734447;879.3932186791094;1045.5399624165343;10238.436885971343;10538.262321637565;10618.203634727426;10700.78598428027;10770.299930504492;389.2759467390651;452.1612408981358;452.7147896824979;454.60676514815106;458.562073220074;5510.9448905511235;5866.413358664185;5900.911486327708;5952.077427998667;6066.332703402622;584105;593165;595752;597697;598694;79066.48938828982;81750.59124704458;82266.58866705628;83906;86742.56628716816;505.6864743858831;507.6091409614576;508.59312549959884;512.9640925135279;513.9152039913372;9922.553485093333;10290.588376465157;10292.382457052601;10361.84457233127;10392.42841643717;34293.82853085719;34994;35091.473627895095;35881.282374351846;36023.819880900424;47934.52065479301;48605.27092093692;48725.269120962505;49102.75448622877;49236.50763492319;9533.952330238304;9577.712668619952;9787.51062446889;9788.363756355815;9799.804003919739;14722.852771472577;16965.236564354385;17075.7438638423;17239.3966211182;18089.909550452165;14290.642733931734;14455.85544144545;14755.55733328002;14839.851601483848;14932.402703892158;13725.176489410667;14055.22696251675;14469.565913022627;14482.34829432657;14514.201718905138;11163.944180279372;14127.505537306144;14443.205623690797;14667.119972801665;15042.849571504144;9461.243100552078;9513.524323783922;9552.761180970254;9759.804803904024;9798.31411801167;10338.328008679411;10351.223658225814;10405.011523905217;13689.657758556115;14691.41234350614 11:20:00 INFO - 2015-11-25 11:20:00,658 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1314.7173357728097;1660.535050185948;1716.4936343778584;1722.9655406891864;1750.8336708012741;6696.598204107757;7488.663010164549;7664.693412263517;7680.807979800507;7682.807929801757;7483.73806916758;7640.235976402357;7649.847003059936;7658.38732901368;7667.079950405945;1917.808219178078;1957.8825270483746;2236.944076398087;2275.48801519658;2307.515421761432;2307.157887371106;2312.329424466911;2322.071171531384;2325.139698311627;2326.2672258238686;2216.1689366487567;2269.103704036904;2269.31920423873;2269.682244485765;2272.36373815332;1931.1309910540242;1971.3396012335884;2224.1214720185517;2261.378121016717;2271.6138256496433;2241.09235759516;2257.255105815077;2264.784845439681;2278;2284.0863654538152;7703.46075774696;7714.884276735854;7764.767056988243;7767.067951845758;7784.922150778533;4559.863204103882;4628.722276663411;4656.3946686930485;4659.021605462857;4661.184292748755;2912.7961042727015;2960.763138948879;2989.925251868699;2999.5350720638335;3046.390721855638;936.9437833729997;947.3794664494767;952.0812416018575;984.6455276100598;997.6757553795046;10393.064624183784;10408.635697750618;10447.059764621144;10459.372437653765;10474.999999999924;4208.705390622627;4246.787660616957;4265.829366825355;4313.396124542566;4366.759828209479;9151.633934642672;9155.450672959643;9156.313276504288;9156.404833685789;9158.038405967278;4636.327732478771;4639.350490931272;4643.721376717408;4647.209974304376;4650.930236046462;4212.368144778307;4216.156768646283;4249.171411574714;4254.978725106354;4257.063446041866;3726.5900750917376;3765.6234376562124;3766.9246615067545;3780.773153610792;3784.413415920537;1169.351010189336;1172.302480024384;1177.528988404645;1185.2236784905901;1209.7701436726993;1323.6955500235;1327.502186679995;1344.717609302038;1349;1371.7599420101442;7277.2358902315555;7319.707211711472;7418.035655364876;7432.256774322633;7445.218252083561;1023.6007956896816;1068.2427406915735;1148.9310641361378;1151.988480115205;1165.411467209071;5809.68046757424;5857.179994800731;5863.384344643836;5872.647641141545;5877.412258774174;9060.864087038699;9080.182783549511;9210.815783684422;9223.53882305882;9335.253179745605;9083.50040747752;9182.632694692138;9188.448693078437;9190.310726695525;9190.816183676288;7155.713771449188;7163.391111755455;7180.425565954711;7227.421806255487;7232.276772322831;7097.538659987084;7159.284071592799;7159.749408770668;7167.283271672791;7167.534110282815;7937.087234968052;8172.959135204286;8195.221453961864;8234.135415781257;8338.207870252316;8162.265396114378;8330.708425205088;8351.20663536963;8476.32189424857;8494.74515764528;6820.215675197416;7067.787966361017;7090.751823686146;7093.432525397852;7103.609301488468;7606.923930760843;7643.503172293783;7647.349975251944;7652;7653.464257502144;5753.367129615655;6919.446444284345;7071.328223818729;7160.964195178991;7197.1363436387965;6869.416099631588;6894.517383783288;6909.1018167637885;7021.087258656342;7064.929350706427;6962.582245065109;7026.859462810818;7056.717731290896;7070.787876363717;7076.150861896605;6543.1821022372205;6581.276059633532;6581.440577550962;6589.374009469093;6594.439472644687;6380.106785050004;6435.485161187;6445.709943052668;6565.409113179837;6574.506911981716 11:20:00 INFO - 2015-11-25 11:20:00,659 DEBUG : BROWSER_OUTPUT: |4;prototype.html;339.96430374810643;342.9245565975485;344.06929256361536;349.5386090360725;350.8667005572004;5869.853253668655;5875.148103524992;5883.176355310255;5927.992241318975;5988.161657367961;5660.518855897254;5689.573282003846;5764.682942438164;5858.970705146479;5879.559033072515;4549.818007279705;4563.338315944186;4579.4046773919345;4584.885377865555;4600.194965880974;4364.934525982113;4531.093781243748;4549.272116461367;4581.3128030795315;4586.082783443308;4396.230659634567;4409.5149533551285;4471.642268618519;4472.060867217888;4473.351364052211;4485.304777759453;4490.550944905516;4496.516149670633;4500.684952053358;4500.752458614775;3927.8625248116177;3954.723169378116;3959.46547216125;3966.9685881670684;3970.68234541236;5517.862053448655;5617.634853734494;5619.353774315965;5628.971855140739;5634.126710359901;5225;5226.686398816083;5228.085085110129;5230.032443997872;5238.214267859814;6212.502999760009;6216.2851272103835;6222.719977600974;6222.81331560054;6227.314995350508;4369.978150109261;4371.803268852909;4376.037271800199;4385.035292235703;4387.692861499696;6327.272363678144;6397.232332120128;6439.710213040425;6466.321036291169;6472.611643301417;4455.376247325377;4464.598186163261;4473.686841921034;4479.372887795711;4480.820767169342;4521.480029796616;4525.298578720303;4536.366907913149;4537.38745269391;4539.886502837389;5215.817446389358;5218.165093585008;5219.217117432416;5223.347081614798;5225.869353266199;6147.569670123048;6250.343713909974;6261.405166509175;6266.122742816009;6270.968645156836;1619.408915745768;1632.387854554542;1633.918304084791;1636.1819090454774;1636.7544868269617;1099.7910397024539;1115.8995690387903;1117.9888201118047;1120.1039168665197;1143.6054561176547;6070.817875463743;6078.149059131725;6085.208922840003;6089.421504957022;6089.695515224221;6469.870602587922;6470.2559205691;6473.06140609609;6480.189976252968;6485.643289619116;5894.528437724972;5944.138099975479;6032.095185722177;6032.13003968771;6046.637201767908;3464.6708562686504;3464.7401444891734;3471.409860323751;3481.617022127564;3492.6856582907153;6584.637844918576;6617.239017513048;6627.204735431683;6630.469562434993;6636.767713130017;6526.053722210253;6676.6327851967635;6684.16447944007;6685.76599819004;6700.02849586817;6701.899471507932;6734.259231484631;6738.561993470409;6739.393454589111;6741.797746067626;6510.056041873996;6523.510736694672;6524.380183882524;6529.444997175293;6540.313267106885;6328.114064030948;6443.130177425987;6443.130177425987;6444.581102228341;6447.9355206447335;6714.83212919681;6724.596524208563;6732.427743641845;6733.93266067333;6734.191896972395;4536.410266665444;4547.431571053618;4552.294394368878;4554.36238926544;4557.065801510748;6364.554481186273;6509.674516274073;6509.967450162719;6514;6515.967420162869;6306.337834527492;6324.367563243639;6328.208973878265;6336.683165841781;6337.7148028337915;6300;6314.084457753508;6314.084457753692;6315.684215789284;6325.177726895658;6069.180660610761;6193.194884664965;6208.441240288395;6210.192674952227;6214.4407003369915;6274.717637706405;6307.842303942254;6339.270983836917;6343.619382837044;6350.68246587678;6182.258129024546;6201.751929922752;6207.6275423474735;6211.440970312694;6220.129181914445 11:20:00 INFO - 2015-11-25 11:20:00,663 DEBUG : BROWSER_OUTPUT: |5;yui.html;790.9446338756289;801.6032064128258;805.8106345008923;807.5397023696491;810.1331575214521;13137.540186093489;13640.863591364083;13644.522441714555;13658.180509169431;13695;12600.621981340551;12748.043896707737;14424;14530.128192308468;14608.342624581892;702.0837806662304;706.9925356367182;841.9621117049733;855.2046596665099;856.7429771068686;838.0948575538434;847.6694089305145;848.0544193224541;848.1772680499905;848.5121055393142;877.5042101212795;894.2443635128307;894.2935081285776;894.3515950935584;894.472261365794;724.2033762860864;728.2098922668911;863.87905693203;875.426595579893;877.5085951866943;643.5302229372537;643.5430844100695;643.9645819479927;647.5531883000715;650.7136859781681;13491.797623035662;13600;13614.978876584197;13654.93172534131;13660;11869.52521899123;11905.940470297679;12115.333656648882;12252.264864108181;12535.749285014248;9724.75688107796;9896.307258491837;10101.49492525378;10339.741506462398;10519.00069493397;3487.5291835602375;3548.8048157351336;3556.395412779834;3562.5724913010345;3563.055790215595;48206;48414;48725;48848.26727599053;48966;8977.820443591092;9650.372725772802;9688.12806847387;9800.313978021613;9882.703518894445;17967.191476383596;18233.361832335806;18236.27054917815;18253.087345632666;18299.35952241692;7269.89095163573;7365.889511657329;7472.589007604634;7504.399648028254;7535.246475352421;4025.5571887092397;4158.355454904495;4161;4179.770112643834;4183.058811767328;3092.2578581140815;3098.2409309718814;3113.346197298593;3118.220444888778;3133.013100873218;557.1197507937449;559.233849626015;559.683778665061;574.0872013498557;578.5487319890492;580.2022219448259;591.6368122965306;600.1417972299654;669.1167658690482;713.864365770502;8044.477108987897;8076.555789431638;8091.126836694722;8228.917710822934;8378.958105209556;509.93625796775405;512.0833707663242;513.4685600403639;570.4894119762789;577.3706659740818;3559.8220088995445;3584.258002714125;3640.12636967126;3653.159773252167;3876.3604005338802;57968.13047804286;58594.707026464595;58991.70504147452;59657.403425966055;63226.36773632205;48868.266975995386;49341.31257530529;49381.75309123431;50220.49779502231;51201.74399128055;44006.55993440089;45008.24876268586;45202;45634;45842;7965.601719914097;8067.394945379003;8122.796930076795;8144.433498512286;8377.874331885148;32464;33509.497357540145;34452.655473444946;34649;35490.64509354874;43415;44175.55824441843;44332.77833610812;44726.552734472236;44794.77602611966;9983.450910200008;10189.796204075728;10227.181825454094;10548.367097974146;10741.677749667522;10746.946265268623;10935.61725339641;10942.72643183895;11143.275687080313;11335.206535542433;10512.159027277952;10640.148788097089;10652.254342195973;11243.943780281374;11269.154813388865;10747.785044299226;11032.28290161137;11257.268277561932;11311.434428278388;11331.150163737588;9804.215662747105;9853.507324633596;9866.654667086617;10060.446675432922;10121.89878101239;8764.1674040966;8782.912170878464;8860.86708699357;9075.319351048565;9102;7664.271894170045;7818.218178182136;8209.384296177692;9238;9298.535073246447 11:20:00 INFO - 2015-11-25 11:20:00,663 DEBUG : BROWSER_OUTPUT: __end_tp_report 11:20:00 INFO - 2015-11-25 11:20:00,663 DEBUG : BROWSER_OUTPUT: __start_cc_report 11:20:00 INFO - 2015-11-25 11:20:00,663 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,951 11:20:00 INFO - 2015-11-25 11:20:00,664 DEBUG : BROWSER_OUTPUT: __end_cc_report 11:20:00 INFO - 2015-11-25 11:20:00,664 DEBUG : BROWSER_OUTPUT: __startTimestamp1448479200648__endTimestamp 11:20:00 INFO - 2015-11-25 11:20:00,664 DEBUG : BROWSER_OUTPUT: 11:20:00 INFO - 2015-11-25 11:20:00,665 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 11:20:00 INFO - 2015-11-25 11:20:00,665 DEBUG : BROWSER_OUTPUT: Number of tests: 6 11:20:00 INFO - 2015-11-25 11:20:00,665 DEBUG : BROWSER_OUTPUT: 11:20:00 INFO - 2015-11-25 11:20:00,665 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5499.31 Median:5704.97 stddev:623.48 (10.9%) stddev-sans-first:597.99 11:20:00 INFO - 2015-11-25 11:20:00,666 DEBUG : BROWSER_OUTPUT: Values: 3168.9 3259.9 3291.1 3302.8 3316.7 5275.8 5380.8 5429.0 5524.9 5538.4 5365.7 5417.0 5424.2 5480.5 5518.4 4944.6 4991.6 5013.0 5037.9 5116.8 4834.5 5063.9 5083.4 5121.2 5161.3 4879.9 4959.6 4982.2 4991.2 5019.4 5042.4 5068.2 5074.7 5089.9 5135.2 4850.7 5042.7 5043.0 5059.0 5072.4 5171.9 5249.9 5253.9 5273.3 5276.7 5959.0 5979.4 5986.5 5989.1 5995.3 5775.6 5988.2 6010.5 6027.1 6032.6 5495.9 5527.0 5578.2 5689.1 5702.3 5999.2 6004.1 6022.9 6047.5 6052.7 5686.4 5769.7 5774.8 5775.6 5847.7 5762.0 5840.1 5900.7 5907.8 5988.4 5838.4 5898.3 5898.3 5916.8 5964.5 5771.1 5886.4 5887.1 5889.5 5900.3 4455.1 4506.8 4528.0 4542.7 4555.5 4029.9 4041.8 4052.0 4074.8 4093.0 5385.3 5589.9 5591.4 5595.7 5599.0 5829.7 5870.3 5918.3 5954.1 5976.7 5663.0 5674.9 5675.3 5678.5 5678.5 5182.3 5198.4 5200.1 5206.4 5260.7 5871.2 6001.8 6024.0 6027.8 6173.8 5997.6 6020.8 6038.2 6042.7 6043.4 6139.4 6209.3 6209.3 6212.4 6216.8 5769.1 5786.3 5884.4 5891.2 5909.6 5963.6 6056.9 6073.1 6081.9 6082.3 5897.1 5900.3 5902.1 5903.9 5905.8 5690.8 5743.7 5760.9 5826.0 5862.9 5676.5 5687.5 5690.0 5705.0 5740.8 5773.2 5799.6 5815.9 5823.7 5887.0 5631.6 5649.9 5710.1 5748.0 5755.9 11:20:00 INFO - 2015-11-25 11:20:00,666 DEBUG : BROWSER_OUTPUT: 11:20:00 INFO - 2015-11-25 11:20:00,666 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:49822.00 Median:37757.74 stddev:84268.79 (223.2%) stddev-sans-first:84424.02 11:20:00 INFO - 2015-11-25 11:20:00,668 DEBUG : BROWSER_OUTPUT: Values: 563.3 578.3 599.8 601.3 613.3 31031.8 39099.6 39664.4 39698.4 39714.8 42306.4 42670.1 42788.6 42811.0 42857.4 7427.6 7540.3 8742.2 8853.8 8912.0 10507.3 10678.9 10730.4 10741.3 10755.6 10381.7 10481.1 10493.9 10497.4 10501.5 7732.1 7890.7 7904.2 10392.9 10514.1 10222.4 10363.4 10365.7 10370.0 10397.3 39917.6 40591.4 40838.0 40854.8 40982.8 3371.4 3912.7 3913.9 3966.6 3982.7 29292.6 29644.3 30226.8 30258.2 30295.7 3739.9 3843.9 3868.0 3914.8 3998.5 276701.0 277223.0 279077.0 281206.0 281677.0 61739.0 62232.4 62239.0 62469.7 62618.7 66657.3 66684.7 66774.7 66810.0 66883.0 16151.1 16336.5 16362.7 16432.4 16452.0 12297.2 12316.4 12358.7 12361.8 12374.4 20032.0 20744.8 20857.3 20924.2 20953.7 7677.3 7737.5 7782.0 7831.3 7937.9 878.1 893.1 998.6 1009.3 1036.6 28280.4 28740.9 29051.4 29076.9 29427.3 1033.8 1069.3 1111.0 1122.0 1157.2 15003.2 15454.7 15616.8 15627.0 15776.6 378581.0 382310.0 383055.0 384315.0 385033.0 52455.2 52541.0 52860.2 52986.5 53010.2 1141.6 1252.1 1333.8 1336.8 1349.9 29437.0 30529.0 31985.8 32861.3 33035.4 43448.3 49252.8 49597.5 49647.5 49839.8 286796.0 290573.0 296452.0 296787.0 297578.0 38488.4 39379.8 39405.0 39432.8 41069.0 47112.0 47311.0 47785.8 48230.3 48391.8 37782.6 38766.6 39161.2 39615.0 40074.6 38318.0 39854.8 39989.8 41884.6 43298.6 36598.5 37026.6 37697.6 37744.2 37771.2 31536.8 31875.0 33715.8 33908.2 33932.3 25204.4 26034.3 26072.5 26076.5 26344.7 11:20:00 INFO - 2015-11-25 11:20:00,668 DEBUG : BROWSER_OUTPUT: 11:20:00 INFO - 2015-11-25 11:20:00,668 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29876.58 Median:4274.07 stddev:97169.32 (2273.5%) stddev-sans-first:97416.63 11:20:00 INFO - 2015-11-25 11:20:00,669 DEBUG : BROWSER_OUTPUT: Values: 359.0 359.2 359.8 360.1 362.8 14394.7 14420.1 16391.2 16668.7 17325.7 15963.3 16152.4 16289.8 16868.7 16906.7 5298.2 5450.5 5452.7 5455.9 5456.9 4033.4 4170.1 5383.1 5440.3 5827.4 5948.3 5974.1 6000.0 6004.0 6004.7 5133.6 5294.7 5306.7 5319.2 5943.8 5080.9 5103.9 5104.1 5127.2 5382.9 12799.5 13016.7 13019.5 13777.9 20341.2 1965.6 2079.1 2119.1 2125.9 2209.8 9430.1 9451.4 9557.9 10413.4 13375.7 1794.8 1834.9 1850.6 1851.4 1875.1 62257.0 63231.4 63256.7 65038.3 65233.7 9701.5 9741.9 9908.5 9952.0 9982.0 13050.8 13053.8 13064.5 13069.7 13074.3 7343.7 7377.9 7411.6 7423.3 7468.4 7180.2 7217.6 7240.9 7243.4 7250.1 4191.4 4207.3 4241.4 4272.3 4275.8 1649.1 1689.7 1704.1 1710.9 1774.8 400.3 411.3 470.5 879.4 1045.5 10238.4 10538.3 10618.2 10700.8 10770.3 389.3 452.2 452.7 454.6 458.6 5510.9 5866.4 5900.9 5952.1 6066.3 584105.0 593165.0 595752.0 597697.0 598694.0 79066.5 81750.6 82266.6 83906.0 86742.6 505.7 507.6 508.6 513.0 513.9 9922.6 10290.6 10292.4 10361.8 10392.4 34293.8 34994.0 35091.5 35881.3 36023.8 47934.5 48605.3 48725.3 49102.8 49236.5 9534.0 9577.7 9787.5 9788.4 9799.8 14722.9 16965.2 17075.7 17239.4 18089.9 14290.6 14455.9 14755.6 14839.9 14932.4 13725.2 14055.2 14469.6 14482.3 14514.2 11163.9 14127.5 14443.2 14667.1 15042.8 9461.2 9513.5 9552.8 9759.8 9798.3 10338.3 10351.2 10405.0 13689.7 14691.4 11:20:00 INFO - 2015-11-25 11:20:00,670 DEBUG : BROWSER_OUTPUT: 11:20:00 INFO - 2015-11-25 11:20:00,670 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5425.04 Median:6569.96 stddev:2780.07 (42.3%) stddev-sans-first:2770.70 11:20:00 INFO - 2015-11-25 11:20:00,670 DEBUG : BROWSER_OUTPUT: Values: 1314.7 1660.5 1716.5 1723.0 1750.8 6696.6 7488.7 7664.7 7680.8 7682.8 7483.7 7640.2 7649.8 7658.4 7667.1 1917.8 1957.9 2236.9 2275.5 2307.5 2307.2 2312.3 2322.1 2325.1 2326.3 2216.2 2269.1 2269.3 2269.7 2272.4 1931.1 1971.3 2224.1 2261.4 2271.6 2241.1 2257.3 2264.8 2278.0 2284.1 7703.5 7714.9 7764.8 7767.1 7784.9 4559.9 4628.7 4656.4 4659.0 4661.2 2912.8 2960.8 2989.9 2999.5 3046.4 936.9 947.4 952.1 984.6 997.7 10393.1 10408.6 10447.1 10459.4 10475.0 4208.7 4246.8 4265.8 4313.4 4366.8 9151.6 9155.5 9156.3 9156.4 9158.0 4636.3 4639.4 4643.7 4647.2 4650.9 4212.4 4216.2 4249.2 4255.0 4257.1 3726.6 3765.6 3766.9 3780.8 3784.4 1169.4 1172.3 1177.5 1185.2 1209.8 1323.7 1327.5 1344.7 1349.0 1371.8 7277.2 7319.7 7418.0 7432.3 7445.2 1023.6 1068.2 1148.9 1152.0 1165.4 5809.7 5857.2 5863.4 5872.6 5877.4 9060.9 9080.2 9210.8 9223.5 9335.3 9083.5 9182.6 9188.4 9190.3 9190.8 7155.7 7163.4 7180.4 7227.4 7232.3 7097.5 7159.3 7159.7 7167.3 7167.5 7937.1 8173.0 8195.2 8234.1 8338.2 8162.3 8330.7 8351.2 8476.3 8494.7 6820.2 7067.8 7090.8 7093.4 7103.6 7606.9 7643.5 7647.3 7652.0 7653.5 5753.4 6919.4 7071.3 7161.0 7197.1 6869.4 6894.5 6909.1 7021.1 7064.9 6962.6 7026.9 7056.7 7070.8 7076.2 6543.2 6581.3 6581.4 6589.4 6594.4 6380.1 6435.5 6445.7 6565.4 6574.5 11:20:00 INFO - 2015-11-25 11:20:00,670 DEBUG : BROWSER_OUTPUT: 11:20:00 INFO - 2015-11-25 11:20:00,671 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5254.65 Median:5966.15 stddev:1572.11 (26.4%) stddev-sans-first:1532.63 11:20:00 INFO - 2015-11-25 11:20:00,672 DEBUG : BROWSER_OUTPUT: Values: 340.0 342.9 344.1 349.5 350.9 5869.9 5875.1 5883.2 5928.0 5988.2 5660.5 5689.6 5764.7 5859.0 5879.6 4549.8 4563.3 4579.4 4584.9 4600.2 4364.9 4531.1 4549.3 4581.3 4586.1 4396.2 4409.5 4471.6 4472.1 4473.4 4485.3 4490.6 4496.5 4500.7 4500.8 3927.9 3954.7 3959.5 3967.0 3970.7 5517.9 5617.6 5619.4 5629.0 5634.1 5225.0 5226.7 5228.1 5230.0 5238.2 6212.5 6216.3 6222.7 6222.8 6227.3 4370.0 4371.8 4376.0 4385.0 4387.7 6327.3 6397.2 6439.7 6466.3 6472.6 4455.4 4464.6 4473.7 4479.4 4480.8 4521.5 4525.3 4536.4 4537.4 4539.9 5215.8 5218.2 5219.2 5223.3 5225.9 6147.6 6250.3 6261.4 6266.1 6271.0 1619.4 1632.4 1633.9 1636.2 1636.8 1099.8 1115.9 1118.0 1120.1 1143.6 6070.8 6078.1 6085.2 6089.4 6089.7 6469.9 6470.3 6473.1 6480.2 6485.6 5894.5 5944.1 6032.1 6032.1 6046.6 3464.7 3464.7 3471.4 3481.6 3492.7 6584.6 6617.2 6627.2 6630.5 6636.8 6526.1 6676.6 6684.2 6685.8 6700.0 6701.9 6734.3 6738.6 6739.4 6741.8 6510.1 6523.5 6524.4 6529.4 6540.3 6328.1 6443.1 6443.1 6444.6 6447.9 6714.8 6724.6 6732.4 6733.9 6734.2 4536.4 4547.4 4552.3 4554.4 4557.1 6364.6 6509.7 6510.0 6514.0 6516.0 6306.3 6324.4 6328.2 6336.7 6337.7 6300.0 6314.1 6314.1 6315.7 6325.2 6069.2 6193.2 6208.4 6210.2 6214.4 6274.7 6307.8 6339.3 6343.6 6350.7 6182.3 6201.8 6207.6 6211.4 6220.1 11:20:00 INFO - 2015-11-25 11:20:00,672 DEBUG : BROWSER_OUTPUT: 11:20:00 INFO - 2015-11-25 11:20:00,672 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13556.42 Median:49361.53 stddev:15995.90 (32.4%) stddev-sans-first:16012.05 11:20:00 INFO - 2015-11-25 11:20:00,672 DEBUG : BROWSER_OUTPUT: Values: 790.9 801.6 805.8 807.5 810.1 13137.5 13640.9 13644.5 13658.2 13695.0 12600.6 12748.0 14424.0 14530.1 14608.3 702.1 707.0 842.0 855.2 856.7 838.1 847.7 848.1 848.2 848.5 877.5 894.2 894.3 894.4 894.5 724.2 728.2 863.9 875.4 877.5 643.5 643.5 644.0 647.6 650.7 13491.8 13600.0 13615.0 13654.9 13660.0 11869.5 11905.9 12115.3 12252.3 12535.7 9724.8 9896.3 10101.5 10339.7 10519.0 3487.5 3548.8 3556.4 3562.6 3563.1 48206.0 48414.0 48725.0 48848.3 48966.0 8977.8 9650.4 9688.1 9800.3 9882.7 17967.2 18233.4 18236.3 18253.1 18299.4 7269.9 7365.9 7472.6 7504.4 7535.2 4025.6 4158.4 4161.0 4179.8 4183.1 3092.3 3098.2 3113.3 3118.2 3133.0 557.1 559.2 559.7 574.1 578.5 580.2 591.6 600.1 669.1 713.9 8044.5 8076.6 8091.1 8228.9 8379.0 509.9 512.1 513.5 570.5 577.4 3559.8 3584.3 3640.1 3653.2 3876.4 57968.1 58594.7 58991.7 59657.4 63226.4 48868.3 49341.3 49381.8 50220.5 51201.7 44006.6 45008.2 45202.0 45634.0 45842.0 7965.6 8067.4 8122.8 8144.4 8377.9 32464.0 33509.5 34452.7 34649.0 35490.6 43415.0 44175.6 44332.8 44726.6 44794.8 9983.5 10189.8 10227.2 10548.4 10741.7 10746.9 10935.6 10942.7 11143.3 11335.2 10512.2 10640.1 10652.3 11243.9 11269.2 10747.8 11032.3 11257.3 11311.4 11331.2 9804.2 9853.5 9866.7 10060.4 10121.9 8764.2 8782.9 8860.9 9075.3 9102.0 7664.3 7818.2 8209.4 9238.0 9298.5 11:20:00 INFO - 2015-11-25 11:20:00,672 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 11:20:00 INFO - 2015-11-25 11:20:00,672 DEBUG : BROWSER_OUTPUT: 11:20:01 INFO - 2015-11-25 11:20:01,413 INFO : Browser exited with error code: 0 11:20:01 INFO - 2015-11-25 11:20:01,432 INFO : Completed test dromaeo_css (00:18:32) 11:20:01 INFO - 2015-11-25 11:20:01,432 INFO : Starting test kraken 11:20:01 INFO - 2015-11-25 11:20:01,432 DEBUG : operating with platform_type : linux_ 11:20:01 INFO - 2015-11-25 11:20:01,432 INFO : Initialising browser for kraken test... 11:20:01 INFO - 2015-11-25 11:20:01,438 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKqs7ar/profile http://localhost:42995/getInfo.html 11:20:05 INFO - 2015-11-25 11:20:05,320 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 11:20:05 INFO - 2015-11-25 11:20:05,321 DEBUG : BROWSER_OUTPUT: colorDepth:24 11:20:05 INFO - 2015-11-25 11:20:05,321 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 11:20:05 INFO - 2015-11-25 11:20:05,321 DEBUG : BROWSER_OUTPUT: __metrics 11:20:05 INFO - 2015-11-25 11:20:05,469 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] 11:20:06 INFO - 2015-11-25 11:20:06,657 INFO : Browser initialized. 11:20:06 INFO - 2015-11-25 11:20:06,658 INFO : Running cycle 1/1 for kraken test... 11:20:06 INFO - 2015-11-25 11:20:06,658 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKqs7ar/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 11:20:18 INFO - 2015-11-25 11:20:18,372 DEBUG : BROWSER_OUTPUT: RSS: Main: 139411456 11:20:18 INFO - 2015-11-25 11:20:18,372 DEBUG : BROWSER_OUTPUT: 11:20:20 INFO - 2015-11-25 11:20:20,851 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42995/tests/kraken/driver.html?testName=audio-beat-detection) 11:20:21 INFO - 2015-11-25 11:20:21,432 DEBUG : BROWSER_OUTPUT: RSS: Main: 177119232 11:20:21 INFO - 2015-11-25 11:20:21,433 DEBUG : BROWSER_OUTPUT: 11:20:24 INFO - 2015-11-25 11:20:24,014 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42995/tests/kraken/driver.html?testName=audio-dft) 11:20:24 INFO - 2015-11-25 11:20:24,582 DEBUG : BROWSER_OUTPUT: RSS: Main: 165453824 11:20:24 INFO - 2015-11-25 11:20:24,582 DEBUG : BROWSER_OUTPUT: 11:20:27 INFO - 2015-11-25 11:20:27,502 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42995/tests/kraken/driver.html?testName=audio-fft) 11:20:28 INFO - 2015-11-25 11:20:28,054 DEBUG : BROWSER_OUTPUT: RSS: Main: 149254144 11:20:28 INFO - 2015-11-25 11:20:28,055 DEBUG : BROWSER_OUTPUT: 11:20:30 INFO - 2015-11-25 11:20:30,026 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42995/tests/kraken/driver.html?testName=audio-oscillator) 11:20:30 INFO - 2015-11-25 11:20:30,587 DEBUG : BROWSER_OUTPUT: RSS: Main: 152780800 11:20:30 INFO - 2015-11-25 11:20:30,587 DEBUG : BROWSER_OUTPUT: 11:20:32 INFO - 2015-11-25 11:20:32,767 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42995/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:20:33 INFO - 2015-11-25 11:20:33,328 DEBUG : BROWSER_OUTPUT: RSS: Main: 155172864 11:20:33 INFO - 2015-11-25 11:20:33,328 DEBUG : BROWSER_OUTPUT: 11:20:36 INFO - 2015-11-25 11:20:36,227 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42995/tests/kraken/driver.html?testName=imaging-darkroom) 11:20:36 INFO - 2015-11-25 11:20:36,789 DEBUG : BROWSER_OUTPUT: RSS: Main: 155774976 11:20:36 INFO - 2015-11-25 11:20:36,789 DEBUG : BROWSER_OUTPUT: 11:20:40 INFO - 2015-11-25 11:20:40,139 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42995/tests/kraken/driver.html?testName=imaging-desaturate) 11:20:40 INFO - 2015-11-25 11:20:40,725 DEBUG : BROWSER_OUTPUT: RSS: Main: 156753920 11:20:40 INFO - 2015-11-25 11:20:40,725 DEBUG : BROWSER_OUTPUT: 11:20:43 INFO - 2015-11-25 11:20:43,484 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42995/tests/kraken/driver.html?testName=json-parse-financial) 11:20:44 INFO - 2015-11-25 11:20:44,051 DEBUG : BROWSER_OUTPUT: RSS: Main: 154603520 11:20:44 INFO - 2015-11-25 11:20:44,052 DEBUG : BROWSER_OUTPUT: 11:20:45 INFO - 2015-11-25 11:20:45,586 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42995/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:20:46 INFO - 2015-11-25 11:20:46,152 DEBUG : BROWSER_OUTPUT: RSS: Main: 165302272 11:20:46 INFO - 2015-11-25 11:20:46,152 DEBUG : BROWSER_OUTPUT: 11:20:47 INFO - 2015-11-25 11:20:47,970 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42995/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:20:48 INFO - 2015-11-25 11:20:48,572 DEBUG : BROWSER_OUTPUT: RSS: Main: 162770944 11:20:48 INFO - 2015-11-25 11:20:48,572 DEBUG : BROWSER_OUTPUT: 11:20:50 INFO - 2015-11-25 11:20:50,625 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42995/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:20:51 INFO - 2015-11-25 11:20:51,198 DEBUG : BROWSER_OUTPUT: RSS: Main: 171216896 11:20:51 INFO - 2015-11-25 11:20:51,199 DEBUG : BROWSER_OUTPUT: 11:20:53 INFO - 2015-11-25 11:20:53,613 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42995/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:20:54 INFO - 2015-11-25 11:20:54,195 DEBUG : BROWSER_OUTPUT: RSS: Main: 180318208 11:20:54 INFO - 2015-11-25 11:20:54,195 DEBUG : BROWSER_OUTPUT: 11:20:56 INFO - 2015-11-25 11:20:56,730 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42995/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:20:57 INFO - 2015-11-25 11:20:57,300 DEBUG : BROWSER_OUTPUT: RSS: Main: 179322880 11:20:57 INFO - 2015-11-25 11:20:57,301 DEBUG : BROWSER_OUTPUT: 11:20:58 INFO - 2015-11-25 11:20:58,954 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42995/tests/kraken/driver.html?testName=ai-astar) 11:20:59 INFO - 2015-11-25 11:20:59,204 DEBUG : BROWSER_OUTPUT: RSS: Main: 237424640 11:20:59 INFO - 2015-11-25 11:20:59,204 DEBUG : BROWSER_OUTPUT: 11:20:59 INFO - 2015-11-25 11:20:59,205 DEBUG : BROWSER_OUTPUT: __start_tp_report 11:20:59 INFO - 2015-11-25 11:20:59,205 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 11:20:59 INFO - 2015-11-25 11:20:59,205 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 11:20:59 INFO - 2015-11-25 11:20:59,205 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 11:20:59 INFO - 2015-11-25 11:20:59,205 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;112;105;111;111;110;101;109;109;101 11:20:59 INFO - 2015-11-25 11:20:59,206 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;122;153;160;132;148;152;125;163;124 11:20:59 INFO - 2015-11-25 11:20:59,206 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;160;164;161;159;161;159;162;160;163 11:20:59 INFO - 2015-11-25 11:20:59,206 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;86;95;70;72;71;95;91;71 11:20:59 INFO - 2015-11-25 11:20:59,206 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;93;136;93;93;93;137;94;93;136 11:20:59 INFO - 2015-11-25 11:20:59,206 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;110;109;110;110;109 11:20:59 INFO - 2015-11-25 11:20:59,207 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;146;146;145;147;146;146;146;145 11:20:59 INFO - 2015-11-25 11:20:59,207 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;95;96;96;97;96;96;96 11:20:59 INFO - 2015-11-25 11:20:59,207 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;57;58;57;56;56;57;57;56;55 11:20:59 INFO - 2015-11-25 11:20:59,207 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;69;69;70;70;71;70;70;70;70 11:20:59 INFO - 2015-11-25 11:20:59,207 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;90;94;84;84;84;84;85;87;86 11:20:59 INFO - 2015-11-25 11:20:59,207 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;119;122;121;123;126;121;125;125;117;121 11:20:59 INFO - 2015-11-25 11:20:59,208 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;150;150;150;151;139;149;152;150;140 11:20:59 INFO - 2015-11-25 11:20:59,208 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;67;68;66;65;67;66;67;67;68 11:20:59 INFO - 2015-11-25 11:20:59,208 DEBUG : BROWSER_OUTPUT: __end_tp_report 11:20:59 INFO - 2015-11-25 11:20:59,208 DEBUG : BROWSER_OUTPUT: __start_cc_report 11:20:59 INFO - 2015-11-25 11:20:59,208 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,912 11:20:59 INFO - 2015-11-25 11:20:59,208 DEBUG : BROWSER_OUTPUT: __end_cc_report 11:20:59 INFO - 2015-11-25 11:20:59,209 DEBUG : BROWSER_OUTPUT: __startTimestamp1448479259204__endTimestamp 11:20:59 INFO - 2015-11-25 11:20:59,209 DEBUG : BROWSER_OUTPUT: 11:20:59 INFO - 2015-11-25 11:20:59,212 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 11:20:59 INFO - 2015-11-25 11:20:59,212 DEBUG : BROWSER_OUTPUT: Number of tests: 14 11:20:59 INFO - 2015-11-25 11:20:59,212 DEBUG : BROWSER_OUTPUT: 11:20:59 INFO - 2015-11-25 11:20:59,213 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.00 Median:110.50 stddev:4.16 (3.8%) stddev-sans-first:4.27 11:20:59 INFO - 2015-11-25 11:20:59,213 DEBUG : BROWSER_OUTPUT: Values: 111.0 112.0 105.0 111.0 111.0 110.0 101.0 109.0 109.0 101.0 11:20:59 INFO - 2015-11-25 11:20:59,213 DEBUG : BROWSER_OUTPUT: 11:20:59 INFO - 2015-11-25 11:20:59,213 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.00 Median:151.50 stddev:15.66 (10.3%) stddev-sans-first:16.34 11:20:59 INFO - 2015-11-25 11:20:59,213 DEBUG : BROWSER_OUTPUT: Values: 151.0 122.0 153.0 160.0 132.0 148.0 152.0 125.0 163.0 124.0 11:20:59 INFO - 2015-11-25 11:20:59,213 DEBUG : BROWSER_OUTPUT: 11:20:59 INFO - 2015-11-25 11:20:59,214 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.63 (1.0%) stddev-sans-first:1.73 11:20:59 INFO - 2015-11-25 11:20:59,214 DEBUG : BROWSER_OUTPUT: Values: 161.0 160.0 164.0 161.0 159.0 161.0 159.0 162.0 160.0 163.0 11:20:59 INFO - 2015-11-25 11:20:59,214 DEBUG : BROWSER_OUTPUT: 11:20:59 INFO - 2015-11-25 11:20:59,214 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.30 Median:79.00 stddev:11.01 (13.9%) stddev-sans-first:11.26 11:20:59 INFO - 2015-11-25 11:20:59,214 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 86.0 95.0 70.0 72.0 71.0 95.0 91.0 71.0 11:20:59 INFO - 2015-11-25 11:20:59,215 DEBUG : BROWSER_OUTPUT: 11:20:59 INFO - 2015-11-25 11:20:59,215 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.20 Median:93.00 stddev:20.80 (22.4%) stddev-sans-first:21.59 11:20:59 INFO - 2015-11-25 11:20:59,215 DEBUG : BROWSER_OUTPUT: Values: 94.0 93.0 136.0 93.0 93.0 93.0 137.0 94.0 93.0 136.0 11:20:59 INFO - 2015-11-25 11:20:59,215 DEBUG : BROWSER_OUTPUT: 11:20:59 INFO - 2015-11-25 11:20:59,215 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 11:20:59 INFO - 2015-11-25 11:20:59,215 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 110.0 109.0 110.0 110.0 109.0 11:20:59 INFO - 2015-11-25 11:20:59,216 DEBUG : BROWSER_OUTPUT: 11:20:59 INFO - 2015-11-25 11:20:59,216 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 11:20:59 INFO - 2015-11-25 11:20:59,216 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 146.0 146.0 145.0 147.0 146.0 146.0 146.0 145.0 11:20:59 INFO - 2015-11-25 11:20:59,216 DEBUG : BROWSER_OUTPUT: 11:20:59 INFO - 2015-11-25 11:20:59,216 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 11:20:59 INFO - 2015-11-25 11:20:59,217 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 95.0 96.0 96.0 97.0 96.0 96.0 96.0 11:20:59 INFO - 2015-11-25 11:20:59,217 DEBUG : BROWSER_OUTPUT: 11:20:59 INFO - 2015-11-25 11:20:59,217 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.00 stddev:0.84 (1.5%) stddev-sans-first:0.88 11:20:59 INFO - 2015-11-25 11:20:59,217 DEBUG : BROWSER_OUTPUT: Values: 57.0 57.0 58.0 57.0 56.0 56.0 57.0 57.0 56.0 55.0 11:20:59 INFO - 2015-11-25 11:20:59,217 DEBUG : BROWSER_OUTPUT: 11:20:59 INFO - 2015-11-25 11:20:59,217 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:0.63 (0.9%) stddev-sans-first:0.60 11:20:59 INFO - 2015-11-25 11:20:59,217 DEBUG : BROWSER_OUTPUT: Values: 69.0 69.0 69.0 70.0 70.0 71.0 70.0 70.0 70.0 70.0 11:20:59 INFO - 2015-11-25 11:20:59,217 DEBUG : BROWSER_OUTPUT: 11:20:59 INFO - 2015-11-25 11:20:59,217 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:85.50 stddev:3.36 (3.9%) stddev-sans-first:3.47 11:20:59 INFO - 2015-11-25 11:20:59,217 DEBUG : BROWSER_OUTPUT: Values: 84.0 90.0 94.0 84.0 84.0 84.0 84.0 85.0 87.0 86.0 11:20:59 INFO - 2015-11-25 11:20:59,217 DEBUG : BROWSER_OUTPUT: 11:20:59 INFO - 2015-11-25 11:20:59,217 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:122.00 Median:122.50 stddev:2.83 (2.3%) stddev-sans-first:2.78 11:20:59 INFO - 2015-11-25 11:20:59,218 DEBUG : BROWSER_OUTPUT: Values: 119.0 122.0 121.0 123.0 126.0 121.0 125.0 125.0 117.0 121.0 11:20:59 INFO - 2015-11-25 11:20:59,218 DEBUG : BROWSER_OUTPUT: 11:20:59 INFO - 2015-11-25 11:20:59,218 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:147.00 Median:150.00 stddev:5.35 (3.6%) stddev-sans-first:4.83 11:20:59 INFO - 2015-11-25 11:20:59,218 DEBUG : BROWSER_OUTPUT: Values: 139.0 150.0 150.0 150.0 151.0 139.0 149.0 152.0 150.0 140.0 11:20:59 INFO - 2015-11-25 11:20:59,218 DEBUG : BROWSER_OUTPUT: 11:20:59 INFO - 2015-11-25 11:20:59,218 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:0.95 (1.4%) stddev-sans-first:0.97 11:20:59 INFO - 2015-11-25 11:20:59,218 DEBUG : BROWSER_OUTPUT: Values: 66.0 67.0 68.0 66.0 65.0 67.0 66.0 67.0 67.0 68.0 11:20:59 INFO - 2015-11-25 11:20:59,218 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 11:20:59 INFO - 2015-11-25 11:20:59,218 DEBUG : BROWSER_OUTPUT: 11:21:00 INFO - 2015-11-25 11:21:00,109 INFO : Browser exited with error code: 0 11:21:00 INFO - 2015-11-25 11:21:00,158 INFO : Completed test kraken (00:00:58) 11:21:00 INFO - 2015-11-25 11:21:00,158 INFO : Starting test v8_7 11:21:00 INFO - 2015-11-25 11:21:00,159 DEBUG : operating with platform_type : linux_ 11:21:00 INFO - 2015-11-25 11:21:00,159 INFO : Initialising browser for v8_7 test... 11:21:00 INFO - 2015-11-25 11:21:00,165 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfZVqSC/profile http://localhost:42995/getInfo.html 11:21:03 INFO - 2015-11-25 11:21:03,881 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 11:21:03 INFO - 2015-11-25 11:21:03,881 DEBUG : BROWSER_OUTPUT: colorDepth:24 11:21:03 INFO - 2015-11-25 11:21:03,881 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 11:21:03 INFO - 2015-11-25 11:21:03,881 DEBUG : BROWSER_OUTPUT: __metrics 11:21:04 INFO - 2015-11-25 11:21:04,022 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] 11:21:05 INFO - 2015-11-25 11:21:05,201 INFO : Browser initialized. 11:21:05 INFO - 2015-11-25 11:21:05,202 INFO : Running cycle 1/1 for v8_7 test... 11:21:05 INFO - 2015-11-25 11:21:05,202 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfZVqSC/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 11:21:16 INFO - 2015-11-25 11:21:16,933 DEBUG : BROWSER_OUTPUT: RSS: Main: 139657216 11:21:16 INFO - 2015-11-25 11:21:16,934 DEBUG : BROWSER_OUTPUT: 11:21:38 INFO - 2015-11-25 11:21:38,344 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42995/tests/v8_7/run.html (next: http://localhost:42995/tests/v8_7/run.html) 11:21:38 INFO - 2015-11-25 11:21:38,602 DEBUG : BROWSER_OUTPUT: RSS: Main: 623099904 11:21:38 INFO - 2015-11-25 11:21:38,602 DEBUG : BROWSER_OUTPUT: 11:21:38 INFO - 2015-11-25 11:21:38,602 DEBUG : BROWSER_OUTPUT: __start_tp_report 11:21:38 INFO - 2015-11-25 11:21:38,602 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 11:21:38 INFO - 2015-11-25 11:21:38,602 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 11:21:38 INFO - 2015-11-25 11:21:38,602 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 11:21:38 INFO - 2015-11-25 11:21:38,602 DEBUG : BROWSER_OUTPUT: |0;Richards;164.74464579901152 11:21:38 INFO - 2015-11-25 11:21:38,603 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;194.85580670303975 11:21:38 INFO - 2015-11-25 11:21:38,603 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.5270768358378 11:21:38 INFO - 2015-11-25 11:21:38,603 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5256.544502617801 11:21:38 INFO - 2015-11-25 11:21:38,603 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1117.31843575419 11:21:38 INFO - 2015-11-25 11:21:38,603 DEBUG : BROWSER_OUTPUT: |5;Earley;781.8608287724785 11:21:38 INFO - 2015-11-25 11:21:38,603 DEBUG : BROWSER_OUTPUT: |6;Boyer;12358.024691358025 11:21:38 INFO - 2015-11-25 11:21:38,603 DEBUG : BROWSER_OUTPUT: |7;RegExp;25200 11:21:38 INFO - 2015-11-25 11:21:38,603 DEBUG : BROWSER_OUTPUT: |8;Splay;651.2687052700065 11:21:38 INFO - 2015-11-25 11:21:38,603 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 11:21:38 INFO - 2015-11-25 11:21:38,603 DEBUG : BROWSER_OUTPUT: __end_tp_report 11:21:38 INFO - 2015-11-25 11:21:38,603 DEBUG : BROWSER_OUTPUT: __startTimestamp1448479298595__endTimestamp 11:21:38 INFO - 2015-11-25 11:21:38,603 DEBUG : BROWSER_OUTPUT: 11:21:38 INFO - 2015-11-25 11:21:38,603 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 11:21:38 INFO - 2015-11-25 11:21:38,603 DEBUG : BROWSER_OUTPUT: Number of tests: 10 11:21:38 INFO - 2015-11-25 11:21:38,603 DEBUG : BROWSER_OUTPUT: 11:21:38 INFO - 2015-11-25 11:21:38,603 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.74 Median:164.74 stddev:0.00 (0.0%) 11:21:38 INFO - 2015-11-25 11:21:38,604 DEBUG : BROWSER_OUTPUT: Values: 164.7 11:21:38 INFO - 2015-11-25 11:21:38,604 DEBUG : BROWSER_OUTPUT: 11:21:38 INFO - 2015-11-25 11:21:38,604 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:194.86 Median:194.86 stddev:0.00 (0.0%) 11:21:38 INFO - 2015-11-25 11:21:38,604 DEBUG : BROWSER_OUTPUT: Values: 194.9 11:21:38 INFO - 2015-11-25 11:21:38,604 DEBUG : BROWSER_OUTPUT: 11:21:38 INFO - 2015-11-25 11:21:38,604 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.53 Median:283.53 stddev:0.00 (0.0%) 11:21:38 INFO - 2015-11-25 11:21:38,604 DEBUG : BROWSER_OUTPUT: Values: 283.5 11:21:38 INFO - 2015-11-25 11:21:38,604 DEBUG : BROWSER_OUTPUT: 11:21:38 INFO - 2015-11-25 11:21:38,604 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5256.54 Median:5256.54 stddev:0.00 (0.0%) 11:21:38 INFO - 2015-11-25 11:21:38,604 DEBUG : BROWSER_OUTPUT: Values: 5256.5 11:21:38 INFO - 2015-11-25 11:21:38,604 DEBUG : BROWSER_OUTPUT: 11:21:38 INFO - 2015-11-25 11:21:38,604 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1117.32 Median:1117.32 stddev:0.00 (0.0%) 11:21:38 INFO - 2015-11-25 11:21:38,604 DEBUG : BROWSER_OUTPUT: Values: 1117.3 11:21:38 INFO - 2015-11-25 11:21:38,604 DEBUG : BROWSER_OUTPUT: 11:21:38 INFO - 2015-11-25 11:21:38,604 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:781.86 Median:781.86 stddev:0.00 (0.0%) 11:21:38 INFO - 2015-11-25 11:21:38,604 DEBUG : BROWSER_OUTPUT: Values: 781.9 11:21:38 INFO - 2015-11-25 11:21:38,604 DEBUG : BROWSER_OUTPUT: 11:21:38 INFO - 2015-11-25 11:21:38,605 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12358.02 Median:12358.02 stddev:0.00 (0.0%) 11:21:38 INFO - 2015-11-25 11:21:38,605 DEBUG : BROWSER_OUTPUT: Values: 12358.0 11:21:38 INFO - 2015-11-25 11:21:38,605 DEBUG : BROWSER_OUTPUT: 11:21:38 INFO - 2015-11-25 11:21:38,605 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25200.00 Median:25200.00 stddev:0.00 (0.0%) 11:21:38 INFO - 2015-11-25 11:21:38,605 DEBUG : BROWSER_OUTPUT: Values: 25200.0 11:21:38 INFO - 2015-11-25 11:21:38,605 DEBUG : BROWSER_OUTPUT: 11:21:38 INFO - 2015-11-25 11:21:38,605 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:651.27 Median:651.27 stddev:0.00 (0.0%) 11:21:38 INFO - 2015-11-25 11:21:38,605 DEBUG : BROWSER_OUTPUT: Values: 651.3 11:21:38 INFO - 2015-11-25 11:21:38,605 DEBUG : BROWSER_OUTPUT: 11:21:38 INFO - 2015-11-25 11:21:38,605 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 11:21:38 INFO - 2015-11-25 11:21:38,605 DEBUG : BROWSER_OUTPUT: Values: 4835.7 11:21:38 INFO - 2015-11-25 11:21:38,605 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 11:21:38 INFO - 2015-11-25 11:21:38,605 DEBUG : BROWSER_OUTPUT: 11:21:39 INFO - 2015-11-25 11:21:39,437 INFO : Browser exited with error code: 0 11:21:39 INFO - 2015-11-25 11:21:39,454 INFO : Completed test v8_7 (00:00:39) 11:21:39 INFO - 2015-11-25 11:21:39,599 INFO : Completed test suite (00:20:11) 11:21:39 INFO - 2015-11-25 11:21:39,599 DEBUG : Working with test: dromaeo_css 11:21:39 INFO - 2015-11-25 11:21:39,599 DEBUG : Generating results file: dromaeo_css 11:21:39 INFO - 2015-11-25 11:21:39,600 DEBUG : Working with test: kraken 11:21:39 INFO - 2015-11-25 11:21:39,600 DEBUG : Generating results file: kraken 11:21:39 INFO - 2015-11-25 11:21:39,600 INFO : javascript benchmark 11:21:39 INFO - 2015-11-25 11:21:39,600 DEBUG : Working with test: v8_7 11:21:39 INFO - 2015-11-25 11:21:39,601 DEBUG : Generating results file: v8_7 11:21:39 INFO - 2015-11-25 11:21:39,601 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 11:21:39 INFO - 2015-11-25 11:21:39,657 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,201,35]] 11:21:39 INFO - 2015-11-25 11:21:39,657 DEBUG : process_Request line: dromaeo_css 6935.89 graph.html#tests=[[72,201,35]] 11:21:39 INFO - 2015-11-25 11:21:39,657 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 11:21:39 INFO - 2015-11-25 11:21:39,780 DEBUG : process_Request line: kraken 1496.80 graph.html#tests=[[232,201,35]] 11:21:39 INFO - 2015-11-25 11:21:39,780 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 11:21:39 INFO - 2015-11-25 11:21:39,906 DEBUG : process_Request line: v8_7 20806.08 graph.html#tests=[[230,201,35]] 11:21:39 INFO - 2015-11-25 11:21:39,907 INFO : javascript benchmark 11:21:39 INFO - 2015-11-25 11:21:39,910 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3168.9225663274487, 3259.8696052157916, 3291.078498020555, 3302.8183449910243, 3316.6849149330815, 5275.841724748254, 5380.838574842751, 5428.972855135724, 5524.861878453035, 5538.390777014531, 5365.651232669874, 5416.972915135429, 5424.159255315422, 5480.451954804527, 5518.365387980383, 4944.5797107245835, 4991.550760431561, 5013.047520971022, 5037.874053148664, 5116.820911268106, 4834.4923783002805, 5063.898722025558, 5083.364579427572, 5121.155009423441, 5161.329027226455, 4879.8780030499165, 4959.553640172384, 4982.202847544412, 4991.151504244287, 5019.372578427697, 5042.3949126104735, 5068.239764035387, 5074.746262686851, 5089.923651145235, 5135.152699804527, 4850.708957462563, 5042.722650254234, 5043.0, 5059.038782631288, 5072.416672082684, 5171.948280517185, 5249.947500524984, 5253.86865328366, 5273.2617433559335, 5276.683398996034, 5959.046552551615, 5979.431953964367, 5986.461218490357, 5989.13157592151, 5995.280566332024, 5775.5957083004205, 5988.161657367972, 6010.519158467312, 6027.09593560965, 6032.607880487798, 5495.890082198333, 5527.0, 5578.219049333096, 5689.061304884648, 5702.287214098238, 5999.190109335271, 6004.0693692477735, 6022.879542409215, 6047.546434017467, 6052.727627256784, 5686.431356864363, 5769.653820770767, 5774.769009239583, 5775.595708300462, 5847.6783776891825, 5762.0, 5840.094785308284, 5900.704964751745, 5907.763689452459, 5988.371221021817, 5838.38696936824, 5898.292204935349, 5898.3216930053595, 5916.763329466773, 5964.522838172936, 5771.076627739541, 5886.411358864079, 5887.087501437284, 5889.4993925515955, 5900.321463031806, 4455.131249406335, 4506.81972721094, 4527.977360113178, 4542.704724192917, 4555.498895121534, 4029.879103626896, 4041.818118184689, 4051.9797401013398, 4074.7758873261664, 4093.038136038029, 5385.3268341457315, 5589.944100558942, 5591.384947655754, 5595.692236926927, 5599.048161812501, 5829.708514574254, 5870.295564532198, 5918.260217472816, 5954.07711804671, 5976.671283079472, 5663.0, 5674.886502269931, 5675.262215911987, 5678.545716342683, 5678.545716342765, 5182.274481572583, 5198.402183748917, 5200.063988482109, 5206.375234971828, 5260.684358938476, 5871.207387002785, 6001.7599296027665, 6024.03615421548, 6027.819165425044, 6173.81478555644, 5997.610155339889, 6020.759169633166, 6038.154658347923, 6042.697865106815, 6043.425874541911, 6139.386061393825, 6209.285932117684, 6209.348018457997, 6212.378762123752, 6216.7824126155365, 5769.105788602773, 5786.33457152416, 5884.411558844081, 5891.204687367151, 5909.5863289569315, 5963.552733545088, 6056.90914636272, 6073.058675905242, 6081.878362432815, 6082.330943596113, 5897.056470964625, 5900.262467191601, 5902.085176797603, 5903.852403689943, 5905.793297234576, 5690.829275121753, 5743.655380677173, 5760.884782304414, 5826.0, 5862.853428664317, 5676.545876329801, 5687.516561092353, 5689.971550142222, 5704.971475142598, 5740.770369185353, 5773.156862159255, 5799.623024503394, 5815.883682326415, 5823.7379317931645, 5887.0, 5631.577631677558, 5649.887002260014, 5710.057840456278, 5748.0, 5755.913661295168], "unit": "ms", "name": "dojo.html", "value": 5457.931629817471}, {"lowerIsBetter": false, "replicates": [563.2986931270566, 578.2944807335051, 599.7720866070895, 601.278465840991, 613.2947110822554, 31031.844840775793, 39099.60900390995, 39664.40503392444, 39698.404523932164, 39714.801425992904, 42306.365404518954, 42670.14659706812, 42788.572114278926, 42811.0, 42857.35713964293, 7427.554346739213, 7540.283673051052, 8742.213200811926, 8853.778655533632, 8912.0, 10507.2644914856, 10678.893211067907, 10730.409827459487, 10741.301815382014, 10755.623553175641, 10381.740456488611, 10481.109105725984, 10493.947530262376, 10497.422641754738, 10501.474926253695, 7732.110807257153, 7890.684372625088, 7904.249096335839, 10392.896071039306, 10514.106300964351, 10222.437765922872, 10363.378197308186, 10365.689029329133, 10370.0, 10397.272190946636, 39917.60082399168, 40591.39112913309, 40838.0, 40854.79572602118, 40982.79508602468, 3371.359441706068, 3912.6674232690257, 3913.902152446183, 3966.6430021297933, 3982.6614737747327, 29292.560611590845, 29644.258893527618, 30226.84886575575, 30258.243543911358, 30295.697043029508, 3739.9439008414897, 3843.8846834595006, 3867.9613203867884, 3914.8042597869994, 3998.52017757868, 276701.0, 277223.0, 279077.0, 281206.0, 281677.0, 61739.0, 62232.377676222655, 62239.0, 62469.68765156145, 62618.68690656609, 66657.33342666608, 66684.6665766668, 66774.66612666905, 66810.0, 66883.0, 16151.11168885699, 16336.509904702878, 16362.672746545002, 16432.424865129662, 16452.0, 12297.200681955645, 12316.384180790925, 12358.691032724075, 12361.752764944651, 12374.443150058269, 20032.0, 20744.79255207459, 20857.269995550083, 20924.16303347849, 20953.68569471459, 7677.347425468786, 7737.497062690907, 7782.0, 7831.334336581454, 7937.920620793718, 878.0517041595062, 893.0801274687083, 998.605550807427, 1009.3136667066466, 1036.6423583863555, 28280.434391312057, 28740.856295718804, 29051.41897162087, 29076.85461572721, 29427.264318391783, 1033.788073444942, 1069.2889228662852, 1110.9611163609236, 1121.9887801122047, 1157.1668398752884, 15003.249837508081, 15454.690906181813, 15616.76574851378, 15627.0, 15776.605584860241, 378581.0, 382310.0, 383055.0, 384315.0, 385033.0, 52455.213171802454, 52541.0, 52860.20709689358, 52986.47013529892, 53010.20484692733, 1141.560499207811, 1252.1110011891476, 1333.8399392073013, 1336.7927971164486, 1349.8852597529124, 29437.0, 30529.0, 31985.8400707995, 32861.34277314488, 33035.41972827853, 43448.34827477527, 49252.75373623109, 49597.504024960734, 49647.50352496429, 49839.75080124576, 286796.0, 290573.0, 296452.0, 296787.0, 297578.0, 38488.422673659355, 39379.80310098431, 39405.0, 39432.80283598564, 41069.0, 47112.0, 47311.0, 47785.76107119581, 48230.276545851135, 48391.75804120957, 37782.62217377901, 38766.629490958694, 39161.2167756649, 39615.0, 40074.59925400825, 38318.0, 39854.80072599618, 39989.80005099956, 41884.58115418807, 43298.567014329456, 36598.45102323414, 37026.62973370232, 37697.62302376941, 37744.24511509809, 37771.2445751078, 31536.842315788275, 31875.0, 33715.83142084274, 33908.15229619279, 33932.3213535723, 25204.369890752878, 26034.34914127162, 26072.496841558506, 26076.478470430866, 26344.73655263423], "unit": "ms", "name": "ext.html", "value": 19535.60070421536}, {"lowerIsBetter": false, "replicates": [358.998205008975, 359.2169071424297, 359.77693829825495, 360.0889748935194, 362.76420326787587, 14394.712105757879, 14420.143813291537, 16391.180440977947, 16668.749968750464, 17325.740113898286, 15963.281652325644, 16152.353905843782, 16289.755653665205, 16868.662626747457, 16906.66186676269, 5298.15229563269, 5450.482204190595, 5452.727363631822, 5455.890882182354, 5456.945430545703, 4033.415154802552, 4170.145120250357, 5383.057964856134, 5440.319960004999, 5827.417258274181, 5948.256467941507, 5974.074018527113, 5999.970000149993, 6003.969980150093, 6004.699765011732, 5133.563647090002, 5294.735263236823, 5306.73466326686, 5319.175527793198, 5943.762249510014, 5080.949190508085, 5103.897922041576, 5104.132297509433, 5127.2052831811125, 5382.865428364283, 12799.528054273691, 13016.739665206738, 13019.479220831156, 13777.862221377758, 20341.18635254588, 1965.6363572738946, 2079.1371580793952, 2119.141747592212, 2125.946851328714, 2209.7790220977936, 9430.10414010668, 9451.385659932152, 9557.856632150524, 10413.3751974881, 13375.665608359772, 1794.7666803315617, 1834.8899066056078, 1850.5928695686928, 1851.4075495841337, 1875.099952023023, 62257.0, 63231.36768632301, 63256.68371658112, 65038.3496165037, 65233.673831631015, 9701.51492425376, 9741.853872191923, 9908.456105748644, 9952.0, 9982.0, 13050.804237936438, 13053.804192937114, 13064.54274100402, 13069.673258168621, 13074.28091454979, 7343.742968996059, 7377.852442951111, 7411.592362420119, 7423.257674232534, 7468.439867009996, 7180.246074162243, 7217.566945983258, 7240.927590724025, 7243.384312333511, 7250.129984401906, 4191.434156388909, 4207.347861081537, 4241.448611680462, 4272.33778764292, 4275.8075886585175, 1649.128239448211, 1689.7211960026705, 1704.1479260369815, 1710.8716846236582, 1774.7870255569414, 400.28549039963525, 411.2802595457949, 470.543572734447, 879.3932186791094, 1045.5399624165343, 10238.436885971343, 10538.262321637565, 10618.203634727426, 10700.78598428027, 10770.299930504492, 389.2759467390651, 452.1612408981358, 452.7147896824979, 454.60676514815106, 458.562073220074, 5510.9448905511235, 5866.413358664185, 5900.911486327708, 5952.077427998667, 6066.332703402622, 584105.0, 593165.0, 595752.0, 597697.0, 598694.0, 79066.48938828982, 81750.59124704458, 82266.58866705628, 83906.0, 86742.56628716816, 505.6864743858831, 507.6091409614576, 508.59312549959884, 512.9640925135279, 513.9152039913372, 9922.553485093333, 10290.588376465157, 10292.382457052601, 10361.84457233127, 10392.42841643717, 34293.82853085719, 34994.0, 35091.473627895095, 35881.282374351846, 36023.819880900424, 47934.52065479301, 48605.27092093692, 48725.269120962505, 49102.75448622877, 49236.50763492319, 9533.952330238304, 9577.712668619952, 9787.51062446889, 9788.363756355815, 9799.804003919739, 14722.852771472577, 16965.236564354385, 17075.7438638423, 17239.3966211182, 18089.909550452165, 14290.642733931734, 14455.85544144545, 14755.55733328002, 14839.851601483848, 14932.402703892158, 13725.176489410667, 14055.22696251675, 14469.565913022627, 14482.34829432657, 14514.201718905138, 11163.944180279372, 14127.505537306144, 14443.205623690797, 14667.119972801665, 15042.849571504144, 9461.243100552078, 9513.524323783922, 9552.761180970254, 9759.804803904024, 9798.31411801167, 10338.328008679411, 10351.223658225814, 10405.011523905217, 13689.657758556115, 14691.41234350614], "unit": "ms", "name": "jquery.html", "value": 8006.0378004901895}, {"lowerIsBetter": false, "replicates": [1314.7173357728097, 1660.535050185948, 1716.4936343778584, 1722.9655406891864, 1750.8336708012741, 6696.598204107757, 7488.663010164549, 7664.693412263517, 7680.807979800507, 7682.807929801757, 7483.73806916758, 7640.235976402357, 7649.847003059936, 7658.38732901368, 7667.079950405945, 1917.808219178078, 1957.8825270483746, 2236.944076398087, 2275.48801519658, 2307.515421761432, 2307.157887371106, 2312.329424466911, 2322.071171531384, 2325.139698311627, 2326.2672258238686, 2216.1689366487567, 2269.103704036904, 2269.31920423873, 2269.682244485765, 2272.36373815332, 1931.1309910540242, 1971.3396012335884, 2224.1214720185517, 2261.378121016717, 2271.6138256496433, 2241.09235759516, 2257.255105815077, 2264.784845439681, 2278.0, 2284.0863654538152, 7703.46075774696, 7714.884276735854, 7764.767056988243, 7767.067951845758, 7784.922150778533, 4559.863204103882, 4628.722276663411, 4656.3946686930485, 4659.021605462857, 4661.184292748755, 2912.7961042727015, 2960.763138948879, 2989.925251868699, 2999.5350720638335, 3046.390721855638, 936.9437833729997, 947.3794664494767, 952.0812416018575, 984.6455276100598, 997.6757553795046, 10393.064624183784, 10408.635697750618, 10447.059764621144, 10459.372437653765, 10474.999999999924, 4208.705390622627, 4246.787660616957, 4265.829366825355, 4313.396124542566, 4366.759828209479, 9151.633934642672, 9155.450672959643, 9156.313276504288, 9156.404833685789, 9158.038405967278, 4636.327732478771, 4639.350490931272, 4643.721376717408, 4647.209974304376, 4650.930236046462, 4212.368144778307, 4216.156768646283, 4249.171411574714, 4254.978725106354, 4257.063446041866, 3726.5900750917376, 3765.6234376562124, 3766.9246615067545, 3780.773153610792, 3784.413415920537, 1169.351010189336, 1172.302480024384, 1177.528988404645, 1185.2236784905901, 1209.7701436726993, 1323.6955500235, 1327.502186679995, 1344.717609302038, 1349.0, 1371.7599420101442, 7277.2358902315555, 7319.707211711472, 7418.035655364876, 7432.256774322633, 7445.218252083561, 1023.6007956896816, 1068.2427406915735, 1148.9310641361378, 1151.988480115205, 1165.411467209071, 5809.68046757424, 5857.179994800731, 5863.384344643836, 5872.647641141545, 5877.412258774174, 9060.864087038699, 9080.182783549511, 9210.815783684422, 9223.53882305882, 9335.253179745605, 9083.50040747752, 9182.632694692138, 9188.448693078437, 9190.310726695525, 9190.816183676288, 7155.713771449188, 7163.391111755455, 7180.425565954711, 7227.421806255487, 7232.276772322831, 7097.538659987084, 7159.284071592799, 7159.749408770668, 7167.283271672791, 7167.534110282815, 7937.087234968052, 8172.959135204286, 8195.221453961864, 8234.135415781257, 8338.207870252316, 8162.265396114378, 8330.708425205088, 8351.20663536963, 8476.32189424857, 8494.74515764528, 6820.215675197416, 7067.787966361017, 7090.751823686146, 7093.432525397852, 7103.609301488468, 7606.923930760843, 7643.503172293783, 7647.349975251944, 7652.0, 7653.464257502144, 5753.367129615655, 6919.446444284345, 7071.328223818729, 7160.964195178991, 7197.1363436387965, 6869.416099631588, 6894.517383783288, 6909.1018167637885, 7021.087258656342, 7064.929350706427, 6962.582245065109, 7026.859462810818, 7056.717731290896, 7070.787876363717, 7076.150861896605, 6543.1821022372205, 6581.276059633532, 6581.440577550962, 6589.374009469093, 6594.439472644687, 6380.106785050004, 6435.485161187, 6445.709943052668, 6565.409113179837, 6574.506911981716], "unit": "ms", "name": "mootools.html", "value": 4476.371724128537}, {"lowerIsBetter": false, "replicates": [339.96430374810643, 342.9245565975485, 344.06929256361536, 349.5386090360725, 350.8667005572004, 5869.853253668655, 5875.148103524992, 5883.176355310255, 5927.992241318975, 5988.161657367961, 5660.518855897254, 5689.573282003846, 5764.682942438164, 5858.970705146479, 5879.559033072515, 4549.818007279705, 4563.338315944186, 4579.4046773919345, 4584.885377865555, 4600.194965880974, 4364.934525982113, 4531.093781243748, 4549.272116461367, 4581.3128030795315, 4586.082783443308, 4396.230659634567, 4409.5149533551285, 4471.642268618519, 4472.060867217888, 4473.351364052211, 4485.304777759453, 4490.550944905516, 4496.516149670633, 4500.684952053358, 4500.752458614775, 3927.8625248116177, 3954.723169378116, 3959.46547216125, 3966.9685881670684, 3970.68234541236, 5517.862053448655, 5617.634853734494, 5619.353774315965, 5628.971855140739, 5634.126710359901, 5225.0, 5226.686398816083, 5228.085085110129, 5230.032443997872, 5238.214267859814, 6212.502999760009, 6216.2851272103835, 6222.719977600974, 6222.81331560054, 6227.314995350508, 4369.978150109261, 4371.803268852909, 4376.037271800199, 4385.035292235703, 4387.692861499696, 6327.272363678144, 6397.232332120128, 6439.710213040425, 6466.321036291169, 6472.611643301417, 4455.376247325377, 4464.598186163261, 4473.686841921034, 4479.372887795711, 4480.820767169342, 4521.480029796616, 4525.298578720303, 4536.366907913149, 4537.38745269391, 4539.886502837389, 5215.817446389358, 5218.165093585008, 5219.217117432416, 5223.347081614798, 5225.869353266199, 6147.569670123048, 6250.343713909974, 6261.405166509175, 6266.122742816009, 6270.968645156836, 1619.408915745768, 1632.387854554542, 1633.918304084791, 1636.1819090454774, 1636.7544868269617, 1099.7910397024539, 1115.8995690387903, 1117.9888201118047, 1120.1039168665197, 1143.6054561176547, 6070.817875463743, 6078.149059131725, 6085.208922840003, 6089.421504957022, 6089.695515224221, 6469.870602587922, 6470.2559205691, 6473.06140609609, 6480.189976252968, 6485.643289619116, 5894.528437724972, 5944.138099975479, 6032.095185722177, 6032.13003968771, 6046.637201767908, 3464.6708562686504, 3464.7401444891734, 3471.409860323751, 3481.617022127564, 3492.6856582907153, 6584.637844918576, 6617.239017513048, 6627.204735431683, 6630.469562434993, 6636.767713130017, 6526.053722210253, 6676.6327851967635, 6684.16447944007, 6685.76599819004, 6700.02849586817, 6701.899471507932, 6734.259231484631, 6738.561993470409, 6739.393454589111, 6741.797746067626, 6510.056041873996, 6523.510736694672, 6524.380183882524, 6529.444997175293, 6540.313267106885, 6328.114064030948, 6443.130177425987, 6443.130177425987, 6444.581102228341, 6447.9355206447335, 6714.83212919681, 6724.596524208563, 6732.427743641845, 6733.93266067333, 6734.191896972395, 4536.410266665444, 4547.431571053618, 4552.294394368878, 4554.36238926544, 4557.065801510748, 6364.554481186273, 6509.674516274073, 6509.967450162719, 6514.0, 6515.967420162869, 6306.337834527492, 6324.367563243639, 6328.208973878265, 6336.683165841781, 6337.7148028337915, 6300.0, 6314.084457753508, 6314.084457753692, 6315.684215789284, 6325.177726895658, 6069.180660610761, 6193.194884664965, 6208.441240288395, 6210.192674952227, 6214.4407003369915, 6274.717637706405, 6307.842303942254, 6339.270983836917, 6343.619382837044, 6350.68246587678, 6182.258129024546, 6201.751929922752, 6207.6275423474735, 6211.440970312694, 6220.129181914445], "unit": "ms", "name": "prototype.html", "value": 4752.284899580386}, {"lowerIsBetter": false, "replicates": [790.9446338756289, 801.6032064128258, 805.8106345008923, 807.5397023696491, 810.1331575214521, 13137.540186093489, 13640.863591364083, 13644.522441714555, 13658.180509169431, 13695.0, 12600.621981340551, 12748.043896707737, 14424.0, 14530.128192308468, 14608.342624581892, 702.0837806662304, 706.9925356367182, 841.9621117049733, 855.2046596665099, 856.7429771068686, 838.0948575538434, 847.6694089305145, 848.0544193224541, 848.1772680499905, 848.5121055393142, 877.5042101212795, 894.2443635128307, 894.2935081285776, 894.3515950935584, 894.472261365794, 724.2033762860864, 728.2098922668911, 863.87905693203, 875.426595579893, 877.5085951866943, 643.5302229372537, 643.5430844100695, 643.9645819479927, 647.5531883000715, 650.7136859781681, 13491.797623035662, 13600.0, 13614.978876584197, 13654.93172534131, 13660.0, 11869.52521899123, 11905.940470297679, 12115.333656648882, 12252.264864108181, 12535.749285014248, 9724.75688107796, 9896.307258491837, 10101.49492525378, 10339.741506462398, 10519.00069493397, 3487.5291835602375, 3548.8048157351336, 3556.395412779834, 3562.5724913010345, 3563.055790215595, 48206.0, 48414.0, 48725.0, 48848.26727599053, 48966.0, 8977.820443591092, 9650.372725772802, 9688.12806847387, 9800.313978021613, 9882.703518894445, 17967.191476383596, 18233.361832335806, 18236.27054917815, 18253.087345632666, 18299.35952241692, 7269.89095163573, 7365.889511657329, 7472.589007604634, 7504.399648028254, 7535.246475352421, 4025.5571887092397, 4158.355454904495, 4161.0, 4179.770112643834, 4183.058811767328, 3092.2578581140815, 3098.2409309718814, 3113.346197298593, 3118.220444888778, 3133.013100873218, 557.1197507937449, 559.233849626015, 559.683778665061, 574.0872013498557, 578.5487319890492, 580.2022219448259, 591.6368122965306, 600.1417972299654, 669.1167658690482, 713.864365770502, 8044.477108987897, 8076.555789431638, 8091.126836694722, 8228.917710822934, 8378.958105209556, 509.93625796775405, 512.0833707663242, 513.4685600403639, 570.4894119762789, 577.3706659740818, 3559.8220088995445, 3584.258002714125, 3640.12636967126, 3653.159773252167, 3876.3604005338802, 57968.13047804286, 58594.707026464595, 58991.70504147452, 59657.403425966055, 63226.36773632205, 48868.266975995386, 49341.31257530529, 49381.75309123431, 50220.49779502231, 51201.74399128055, 44006.55993440089, 45008.24876268586, 45202.0, 45634.0, 45842.0, 7965.601719914097, 8067.394945379003, 8122.796930076795, 8144.433498512286, 8377.874331885148, 32464.0, 33509.497357540145, 34452.655473444946, 34649.0, 35490.64509354874, 43415.0, 44175.55824441843, 44332.77833610812, 44726.552734472236, 44794.77602611966, 9983.450910200008, 10189.796204075728, 10227.181825454094, 10548.367097974146, 10741.677749667522, 10746.946265268623, 10935.61725339641, 10942.72643183895, 11143.275687080313, 11335.206535542433, 10512.159027277952, 10640.148788097089, 10652.254342195973, 11243.943780281374, 11269.154813388865, 10747.785044299226, 11032.28290161137, 11257.268277561932, 11311.434428278388, 11331.150163737588, 9804.215662747105, 9853.507324633596, 9866.654667086617, 10060.446675432922, 10121.89878101239, 8764.1674040966, 8782.912170878464, 8860.86708699357, 9075.319351048565, 9102.0, 7664.271894170045, 7818.218178182136, 8209.384296177692, 9238.0, 9298.535073246447], "unit": "ms", "name": "yui.html", "value": 6130.161877147494}], "name": "dromaeo_css", "value": 6935.888582679854}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 112.0, 105.0, 111.0, 111.0, 110.0, 101.0, 109.0, 109.0, 101.0], "unit": "ms", "name": "ai-astar", "value": 108.0}, {"lowerIsBetter": true, "replicates": [151.0, 122.0, 153.0, 160.0, 132.0, 148.0, 152.0, 125.0, 163.0, 124.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.0}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 164.0, 161.0, 159.0, 161.0, 159.0, 162.0, 160.0, 163.0], "unit": "ms", "name": "audio-dft", "value": 161.0}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 86.0, 95.0, 70.0, 72.0, 71.0, 95.0, 91.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 79.3}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 136.0, 93.0, 93.0, 93.0, 137.0, 94.0, 93.0, 136.0], "unit": "ms", "name": "audio-oscillator", "value": 106.2}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 110.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 146.0, 146.0, 145.0, 147.0, 146.0, 146.0, 146.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.9}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 97.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.8}, {"lowerIsBetter": true, "replicates": [57.0, 57.0, 58.0, 57.0, 56.0, 56.0, 57.0, 57.0, 56.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 56.6}, {"lowerIsBetter": true, "replicates": [69.0, 69.0, 69.0, 70.0, 70.0, 71.0, 70.0, 70.0, 70.0, 70.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.8}, {"lowerIsBetter": true, "replicates": [84.0, 90.0, 94.0, 84.0, 84.0, 84.0, 84.0, 85.0, 87.0, 86.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.2}, {"lowerIsBetter": true, "replicates": [119.0, 122.0, 121.0, 123.0, 126.0, 121.0, 125.0, 125.0, 117.0, 121.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 122.0}, {"lowerIsBetter": true, "replicates": [139.0, 150.0, 150.0, 150.0, 151.0, 139.0, 149.0, 152.0, 150.0, 140.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 147.0}, {"lowerIsBetter": true, "replicates": [66.0, 67.0, 68.0, 66.0, 65.0, 67.0, 66.0, 67.0, 67.0, 68.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.7}], "name": "kraken", "value": 1496.8}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.74464579901152], "unit": "score", "name": "Richards", "value": 214.28313999999997}, {"lowerIsBetter": false, "replicates": [194.85580670303975], "unit": "score", "name": "DeltaBlue", "value": 339.3175759999999}, {"lowerIsBetter": false, "replicates": [283.5270768358378], "unit": "score", "name": "Encrypt", "value": 938.8203869999999}, {"lowerIsBetter": false, "replicates": [5256.544502617801], "unit": "score", "name": "Decrypt", "value": 50.6380189243028}, {"lowerIsBetter": false, "replicates": [1117.31843575419], "unit": "score", "name": "RayTrace", "value": 662.290155}, {"lowerIsBetter": false, "replicates": [781.8608287724785], "unit": "score", "name": "Earley", "value": 852.4061770000001}, {"lowerIsBetter": false, "replicates": [12358.024691358025], "unit": "score", "name": "Boyer", "value": 53.929573426573455}, {"lowerIsBetter": false, "replicates": [25200.0], "unit": "score", "name": "RegExp", "value": 36.150198412698415}, {"lowerIsBetter": false, "replicates": [651.2687052700065], "unit": "score", "name": "Splay", "value": 125.12654045954041}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 20806.075137452153}]} 11:21:39 INFO - RETURN: dromaeo_css: 6935.89 11:21:39 INFO - RETURN: kraken: 1496.80 11:21:39 INFO - RETURN: v8_7: 20806.08 11:21:39 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,201,35]]", "result": "20806.08"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,201,35]]", "result": "1496.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,201,35]]", "result": "6935.89"}}} 11:21:40 INFO - Return code: 0 11:21:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 11:21:40 INFO - # TBPL SUCCESS # 11:21:40 INFO - Running post-action listener: _resource_record_post_action 11:21:40 INFO - Running post-run listener: _resource_record_post_run 11:21:41 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 4096; Write bytes: 512004096; Read time: 8; Write time: 675628 11:21:41 INFO - install - Wall time: 15s; CPU: 17.0%; Read bytes: 0; Write bytes: 249528320; Read time: 0; Write time: 495040 11:21:41 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 4096; Write bytes: 262475776; Read time: 8; Write time: 180588 11:21:41 INFO - Running post-run listener: _upload_blobber_files 11:21:41 INFO - Blob upload gear active. 11:21:41 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 11:21:41 INFO - Copying logs to upload dir... 11:21:41 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1262.487465 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2015-11-25 11:21:41.130714) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-25 11:21:41.134511) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/rUmNhVeiREK9eCcJtc-CcQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009998 build_url: 'https://queue.taskcluster.net/v1/task/rUmNhVeiREK9eCcJtc-CcQ/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-25 11:21:41.186550) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 11:21:41.186908) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1448476262.130643-7438911 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004552 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 11:21:41.237213) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 11:21:41.237561) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 11:21:41.237882) ========= ========= Total master_lag: 0.23 =========