builder: b2g-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-029 starttime: 1448434538.36 results: success (0) buildid: 20151124203151 builduid: 97d1d63096b94d328b19d923f62ad239 revision: 94202eb8f17564d3127ca52ff4f57d286782a195 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 22:55:38.362201) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 22:55:38.362740) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 22:55:38.363053) ========= 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=1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006461 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 22:55:38.386861) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 22:55:38.387413) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 22:55:38.402412) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 22:55:38.403040) ========= 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=1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004661 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 22:55:38.455071) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 22:55:38.455624) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 22:55:38.456346) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 22:55:38.456862) ========= 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=1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-24 22:55:38-- 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% 223M=0s 2015-11-24 22:55:39 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.136772 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 22:55:38.609160) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 22:55:38.609754) ========= 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=1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022252 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 22:55:38.663699) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-24 22:55:38.664512) ========= bash -c 'python archiver_client.py mozharness --repo integration/b2g-inbound --rev 94202eb8f17564d3127ca52ff4f57d286782a195 --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 94202eb8f17564d3127ca52ff4f57d286782a195 --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=1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-24 22:55:39,148 truncating revision to first 12 chars 2015-11-24 22:55:39,148 Setting DEBUG logging. 2015-11-24 22:55:39,148 attempt 1/10 2015-11-24 22:55:39,148 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/94202eb8f175?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-24 22:55:40,125 unpacking tar archive at: b2g-inbound-94202eb8f175/testing/mozharness/ program finished with exit code 0 elapsedTime=1.292836 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-24 22:55:39.973732) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 22:55:39.974057) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 22:55:39.983206) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 22:55:39.983495) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 22:55:39.983919) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2015-11-24 22:55:39.984254) ========= /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=1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:55:40 INFO - MultiFileLogger online at 20151124 22:55:40 in /builds/slave/test-pgo 22:55:40 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 22:55:40 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 22:55:40 INFO - {'append_to_log': False, 22:55:40 INFO - 'base_work_dir': '/builds/slave/test-pgo', 22:55:40 INFO - 'blob_upload_branch': 'B2G-Inbound', 22:55:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 22:55:40 INFO - 'branch': 'B2G-Inbound', 22:55:40 INFO - 'buildbot_json_path': 'buildprops.json', 22:55:40 INFO - 'config_files': ('talos/linux_config.py',), 22:55:40 INFO - 'default_actions': ('clobber', 22:55:40 INFO - 'read-buildbot-config', 22:55:40 INFO - 'download-and-extract', 22:55:40 INFO - 'populate-webroot', 22:55:40 INFO - 'create-virtualenv', 22:55:40 INFO - 'install', 22:55:40 INFO - 'run-tests'), 22:55:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:55:40 INFO - 'download_minidump_stackwalk': True, 22:55:40 INFO - 'download_symbols': 'ondemand', 22:55:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:55:40 INFO - 'tooltool.py': '/tools/tooltool.py', 22:55:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:55:40 INFO - '/tools/misc-python/virtualenv.py')}, 22:55:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:55:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:55:40 INFO - 'installer_path': 'installer.exe', 22:55:40 INFO - 'log_level': 'info', 22:55:40 INFO - 'log_name': 'talos', 22:55:40 INFO - 'log_to_console': True, 22:55:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:55:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:55:40 INFO - 'opt_config_files': (), 22:55:40 INFO - 'pip_index': False, 22:55:40 INFO - 'sps_profile': False, 22:55:40 INFO - 'sps_profile_interval': 0, 22:55:40 INFO - 'suite': 'dromaeojs', 22:55:40 INFO - 'system_bits': '64', 22:55:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:55:40 INFO - 'title': 'talos-linux64-ix-029', 22:55:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:55:40 INFO - 'use_talos_json': True, 22:55:40 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 22:55:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:55:40 INFO - 'work_dir': 'build'} 22:55:40 INFO - ##### 22:55:40 INFO - ##### Running clobber step. 22:55:40 INFO - ##### 22:55:40 INFO - Running pre-action listener: _resource_record_pre_action 22:55:40 INFO - Running main action method: clobber 22:55:40 INFO - rmtree: /builds/slave/test-pgo/build 22:55:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 22:55:40 INFO - Running post-action listener: _resource_record_post_action 22:55:40 INFO - ##### 22:55:40 INFO - ##### Running read-buildbot-config step. 22:55:40 INFO - ##### 22:55:40 INFO - Running pre-action listener: _resource_record_pre_action 22:55:40 INFO - Running main action method: read_buildbot_config 22:55:40 INFO - Using buildbot properties: 22:55:40 INFO - { 22:55:40 INFO - "properties": { 22:55:40 INFO - "buildnumber": 1004, 22:55:40 INFO - "product": "firefox", 22:55:40 INFO - "script_repo_revision": "production", 22:55:40 INFO - "builddir": "b2g-inbound_ubuntu64_hw_test-dromaeojs", 22:55:40 INFO - "repository": "", 22:55:40 INFO - "buildername": "Ubuntu HW 12.04 x64 b2g-inbound pgo talos dromaeojs", 22:55:40 INFO - "buildid": "20151124203151", 22:55:40 INFO - "slavename": "talos-linux64-ix-029", 22:55:40 INFO - "pgo_build": "True", 22:55:40 INFO - "basedir": "/builds/slave/test-pgo", 22:55:40 INFO - "project": "", 22:55:40 INFO - "platform": "ubuntu64_hw", 22:55:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:55:40 INFO - "slavebuilddir": "test", 22:55:40 INFO - "scheduler": "tests-b2g-inbound-linux64-pgo-talos", 22:55:40 INFO - "branch": "b2g-inbound", 22:55:40 INFO - "repo_path": "integration/b2g-inbound", 22:55:40 INFO - "stage_platform": "linux64-pgo", 22:55:40 INFO - "builduid": "97d1d63096b94d328b19d923f62ad239", 22:55:40 INFO - "revision": "94202eb8f17564d3127ca52ff4f57d286782a195" 22:55:40 INFO - }, 22:55:40 INFO - "sourcestamp": { 22:55:40 INFO - "repository": "", 22:55:40 INFO - "hasPatch": false, 22:55:40 INFO - "project": "", 22:55:40 INFO - "branch": "b2g-inbound-linux64-pgo-talos", 22:55:40 INFO - "changes": [ 22:55:40 INFO - { 22:55:40 INFO - "category": null, 22:55:40 INFO - "files": [ 22:55:40 INFO - { 22:55:40 INFO - "url": null, 22:55:40 INFO - "name": "https://queue.taskcluster.net/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 22:55:40 INFO - } 22:55:40 INFO - ], 22:55:40 INFO - "repository": "", 22:55:40 INFO - "rev": "94202eb8f17564d3127ca52ff4f57d286782a195", 22:55:40 INFO - "who": "sendchange", 22:55:40 INFO - "when": 1448434513, 22:55:40 INFO - "number": 6704059, 22:55:40 INFO - "comments": "", 22:55:40 INFO - "project": "", 22:55:40 INFO - "at": "Tue 24 Nov 2015 22:55:13", 22:55:40 INFO - "branch": "b2g-inbound-linux64-pgo-talos", 22:55:40 INFO - "revlink": "", 22:55:40 INFO - "properties": [ 22:55:40 INFO - [ 22:55:40 INFO - "buildid", 22:55:40 INFO - "20151124203151", 22:55:40 INFO - "Change" 22:55:40 INFO - ], 22:55:40 INFO - [ 22:55:40 INFO - "builduid", 22:55:40 INFO - "97d1d63096b94d328b19d923f62ad239", 22:55:40 INFO - "Change" 22:55:40 INFO - ], 22:55:40 INFO - [ 22:55:40 INFO - "pgo_build", 22:55:40 INFO - "True", 22:55:40 INFO - "Change" 22:55:40 INFO - ] 22:55:40 INFO - ], 22:55:40 INFO - "revision": "94202eb8f17564d3127ca52ff4f57d286782a195" 22:55:40 INFO - } 22:55:40 INFO - ], 22:55:40 INFO - "revision": "94202eb8f17564d3127ca52ff4f57d286782a195" 22:55:40 INFO - } 22:55:40 INFO - } 22:55:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 22:55:40 INFO - Running post-action listener: _resource_record_post_action 22:55:40 INFO - ##### 22:55:40 INFO - ##### Running download-and-extract step. 22:55:40 INFO - ##### 22:55:40 INFO - Running pre-action listener: _resource_record_pre_action 22:55:40 INFO - Running main action method: download_and_extract 22:55:40 INFO - mkdir: /builds/slave/test-pgo/build/tests 22:55:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:55:40 INFO - https://queue.taskcluster.net/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 22:55:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/test_packages.json 22:55:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/test_packages.json 22:55:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 22:55:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 22:55:42 INFO - Downloaded 1302 bytes. 22:55:42 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 22:55:42 INFO - Using the following test package requirements: 22:55:42 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 22:55:42 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 22:55:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:55:42 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 22:55:42 INFO - u'jsshell-linux-x86_64.zip'], 22:55:42 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 22:55:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:55:42 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 22:55:42 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 22:55:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:55:42 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 22:55:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:55:42 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 22:55:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:55:42 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 22:55:42 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 22:55:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:55:42 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:55:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:55:42 INFO - https://queue.taskcluster.net/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:55:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 22:55:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 22:55:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/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 22:55:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/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 22:55:43 INFO - Downloaded 22530370 bytes. 22:55:43 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 22:55:43 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 22:55:43 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 22:55:44 INFO - Return code: 0 22:55:44 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:55:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:55:44 INFO - https://queue.taskcluster.net/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:55:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 22:55:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 22:55:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/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 22:55:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/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 22:55:47 INFO - Downloaded 22530370 bytes. 22:55:47 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 22:55:47 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 22:55:47 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 22:55:48 INFO - Return code: 0 22:55:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:55:48 INFO - https://queue.taskcluster.net/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:55:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 22:55:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 22:55:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/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 22:55:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/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 22:55:49 INFO - Downloaded 11131717 bytes. 22:55:49 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 22:55:49 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 22:55:49 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 22:55:49 INFO - Return code: 0 22:55:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:55:49 INFO - https://queue.taskcluster.net/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:55:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 22:55:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 22:55:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/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 22:55:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OUlmf2DyQFOYGaA-QEwz4g/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 22:55:53 INFO - Downloaded 58386599 bytes. 22:55:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 22:55:53 INFO - mkdir: /builds/slave/test-pgo/properties 22:55:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 22:55:53 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 22:55:53 INFO - Contents: 22:55:53 INFO - build_url:https://queue.taskcluster.net/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 22:55:53 INFO - Running post-action listener: _resource_record_post_action 22:55:53 INFO - Running post-action listener: set_extra_try_arguments 22:55:53 INFO - ##### 22:55:53 INFO - ##### Running populate-webroot step. 22:55:53 INFO - ##### 22:55:53 INFO - Running pre-action listener: _resource_record_pre_action 22:55:53 INFO - Running main action method: populate_webroot 22:55:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:55:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:55:53 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 22:55:53 INFO - '../fennec_ids.txt'], 22:55:53 INFO - 'tests': ['tcheck2']}, 22:55:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:55:53 INFO - '--tppagecycles', 22:55:53 INFO - '7'], 22:55:53 INFO - 'tests': ['tsvgm']}}, 22:55:53 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 22:55:53 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 22:55:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:55:53 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:55:53 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 22:55:53 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:55:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:55:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:55:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:55:53 INFO - 'talos_options': ['--e10s'], 22:55:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:55:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:55:53 INFO - 'tests': ['damp', 'tps']}, 22:55:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:55:53 INFO - 'talos_options': ['--e10s'], 22:55:53 INFO - 'tests': ['damp', 'tps']}, 22:55:53 INFO - 'g3': {'tests': ['dromaeo_dom']}, 22:55:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:55:53 INFO - 'other': {'tests': ['a11yr', 22:55:53 INFO - 'ts_paint', 22:55:53 INFO - 'tpaint', 22:55:53 INFO - 'sessionrestore', 22:55:53 INFO - 'sessionrestore_no_auto_restore']}, 22:55:53 INFO - 'other-e10s': {'talos_options': ['--e10s'], 22:55:53 INFO - 'tests': ['a11yr', 22:55:53 INFO - 'ts_paint', 22:55:53 INFO - 'tpaint', 22:55:53 INFO - 'sessionrestore', 22:55:53 INFO - 'sessionrestore_no_auto_restore']}, 22:55:53 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 22:55:53 INFO - 'tests': ['a11yr', 22:55:53 INFO - 'ts_paint', 22:55:53 INFO - 'tpaint', 22:55:53 INFO - 'sessionrestore', 22:55:53 INFO - 'sessionrestore_no_auto_restore']}, 22:55:53 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 22:55:53 INFO - 'tests': ['a11yr', 22:55:53 INFO - 'ts_paint', 22:55:53 INFO - 'tpaint', 22:55:53 INFO - 'sessionrestore', 22:55:53 INFO - 'sessionrestore_no_auto_restore']}, 22:55:53 INFO - 'other_l64': {'tests': ['a11yr', 22:55:53 INFO - 'ts_paint', 22:55:53 INFO - 'tpaint', 22:55:53 INFO - 'sessionrestore', 22:55:53 INFO - 'sessionrestore_no_auto_restore']}, 22:55:53 INFO - 'other_nol64': {'tests': ['a11yr', 22:55:53 INFO - 'ts_paint', 22:55:53 INFO - 'tpaint', 22:55:53 INFO - 'sessionrestore', 22:55:53 INFO - 'sessionrestore_no_auto_restore']}, 22:55:53 INFO - 'svgr': {'tests': ['tsvgx', 22:55:53 INFO - 'tsvgr_opacity', 22:55:53 INFO - 'tart', 22:55:53 INFO - 'tscrollx', 22:55:53 INFO - 'cart']}, 22:55:53 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 22:55:53 INFO - 'tests': ['tsvgx', 22:55:53 INFO - 'tsvgr_opacity', 22:55:53 INFO - 'tart', 22:55:53 INFO - 'tscrollx', 22:55:53 INFO - 'cart']}, 22:55:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:55:53 INFO - 'tests': ['tp5o']}, 22:55:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:55:53 INFO - 'talos_options': ['--e10s'], 22:55:53 INFO - 'tests': ['tp5o']}, 22:55:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:55:53 INFO - 'talos_options': ['--xperf_path', 22:55:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:55:53 INFO - 'tests': ['tp5n']}, 22:55:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:55:53 INFO - 'talos_options': ['--e10s', 22:55:53 INFO - '--xperf_path', 22:55:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:55:53 INFO - 'tests': ['tp5n']}}, 22:55:53 INFO - 'talos.zip': {'path': '', 22:55:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:55:53 INFO - Running post-action listener: _resource_record_post_action 22:55:53 INFO - ##### 22:55:53 INFO - ##### Running create-virtualenv step. 22:55:53 INFO - ##### 22:55:53 INFO - Running pre-action listener: _resource_record_pre_action 22:55:53 INFO - Running main action method: create_virtualenv 22:55:53 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 22:55:53 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 22:55:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 22:55:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:55:53 INFO - Using real prefix '/usr' 22:55:53 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 22:55:54 INFO - Installing distribute.............................................................................................................................................................................................done. 22:55:56 INFO - Installing pip.................done. 22:55:56 INFO - Return code: 0 22:55:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 22:55:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:55:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:55:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:55:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:56 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 0x17f3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a207d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aaf640>, '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 0x1ab0540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab09d0>, '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': '1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239', '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 22:55:56 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 22:55:56 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 22:55:56 INFO - Using env: {'DISPLAY': ':0', 22:55:56 INFO - 'HOME': '/home/cltbld', 22:55:56 INFO - 'LANG': 'en_US.UTF-8', 22:55:56 INFO - 'LANGUAGE': 'en_US:en', 22:55:56 INFO - 'LOGNAME': 'cltbld', 22:55:56 INFO - 'MAIL': '/var/mail/cltbld', 22:55:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:56 INFO - 'MOZ_NO_REMOTE': '1', 22:55:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:55:56 INFO - 'NO_EM_RESTART': '1', 22:55:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:55:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:55:56 INFO - 'PWD': '/builds/slave/test-pgo', 22:55:56 INFO - 'SHELL': '/bin/bash', 22:55:56 INFO - 'SHLVL': '1', 22:55:56 INFO - 'TERM': 'linux', 22:55:56 INFO - 'TMOUT': '86400', 22:55:56 INFO - 'USER': 'cltbld', 22:55:56 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239', 22:55:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:56 INFO - '_': '/tools/buildbot/bin/python'} 22:55:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:55:56 INFO - Cleaning up... 22:55:56 INFO - Return code: 0 22:55:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 22:55:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:55:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:55:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:55:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:56 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 0x17f3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a207d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aaf640>, '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 0x1ab0540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab09d0>, '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': '1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239', '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 22:55:56 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 22:55:56 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 22:55:56 INFO - Using env: {'DISPLAY': ':0', 22:55:56 INFO - 'HOME': '/home/cltbld', 22:55:56 INFO - 'LANG': 'en_US.UTF-8', 22:55:56 INFO - 'LANGUAGE': 'en_US:en', 22:55:56 INFO - 'LOGNAME': 'cltbld', 22:55:56 INFO - 'MAIL': '/var/mail/cltbld', 22:55:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:56 INFO - 'MOZ_NO_REMOTE': '1', 22:55:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:55:56 INFO - 'NO_EM_RESTART': '1', 22:55:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:55:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:55:56 INFO - 'PWD': '/builds/slave/test-pgo', 22:55:56 INFO - 'SHELL': '/bin/bash', 22:55:56 INFO - 'SHLVL': '1', 22:55:56 INFO - 'TERM': 'linux', 22:55:56 INFO - 'TMOUT': '86400', 22:55:56 INFO - 'USER': 'cltbld', 22:55:56 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239', 22:55:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:56 INFO - '_': '/tools/buildbot/bin/python'} 22:55:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:56 INFO - Downloading/unpacking psutil>=0.7.1 22:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:55:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:55:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:55:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:55:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:55:58 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 22:55:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 22:55:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 22:55:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:55:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:55:58 INFO - Installing collected packages: psutil 22:55:58 INFO - Running setup.py install for psutil 22:55:58 INFO - building 'psutil._psutil_linux' extension 22:55:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:55: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_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:55:58 INFO - building 'psutil._psutil_posix' extension 22:55:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:55:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:55:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:55:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:55:59 INFO - Successfully installed psutil 22:55:59 INFO - Cleaning up... 22:55:59 INFO - Return code: 0 22:55:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 22:55:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:55:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:55:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:55:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:55:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:59 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 0x17f3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a207d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aaf640>, '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 0x1ab0540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab09d0>, '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': '1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239', '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 22:55:59 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 22:55:59 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 22:55:59 INFO - Using env: {'DISPLAY': ':0', 22:55:59 INFO - 'HOME': '/home/cltbld', 22:55:59 INFO - 'LANG': 'en_US.UTF-8', 22:55:59 INFO - 'LANGUAGE': 'en_US:en', 22:55:59 INFO - 'LOGNAME': 'cltbld', 22:55:59 INFO - 'MAIL': '/var/mail/cltbld', 22:55:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:59 INFO - 'MOZ_NO_REMOTE': '1', 22:55:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:55:59 INFO - 'NO_EM_RESTART': '1', 22:55:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:55:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:55:59 INFO - 'PWD': '/builds/slave/test-pgo', 22:55:59 INFO - 'SHELL': '/bin/bash', 22:55:59 INFO - 'SHLVL': '1', 22:55:59 INFO - 'TERM': 'linux', 22:55:59 INFO - 'TMOUT': '86400', 22:55:59 INFO - 'USER': 'cltbld', 22:55:59 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239', 22:55:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:59 INFO - '_': '/tools/buildbot/bin/python'} 22:55:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:59 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:55:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:55:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:55:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:55:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:55:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:55:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:01 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:56:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 22:56:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:56:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:56:01 INFO - Installing collected packages: mozsystemmonitor 22:56:01 INFO - Running setup.py install for mozsystemmonitor 22:56:01 INFO - Successfully installed mozsystemmonitor 22:56:01 INFO - Cleaning up... 22:56:01 INFO - Return code: 0 22:56:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 22:56:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:01 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 0x17f3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a207d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aaf640>, '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 0x1ab0540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab09d0>, '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': '1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239', '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 22:56:01 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 22:56:01 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 22:56:01 INFO - Using env: {'DISPLAY': ':0', 22:56:01 INFO - 'HOME': '/home/cltbld', 22:56:01 INFO - 'LANG': 'en_US.UTF-8', 22:56:01 INFO - 'LANGUAGE': 'en_US:en', 22:56:01 INFO - 'LOGNAME': 'cltbld', 22:56:01 INFO - 'MAIL': '/var/mail/cltbld', 22:56:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:01 INFO - 'MOZ_NO_REMOTE': '1', 22:56:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:56:01 INFO - 'NO_EM_RESTART': '1', 22:56:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:56:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:56:01 INFO - 'PWD': '/builds/slave/test-pgo', 22:56:01 INFO - 'SHELL': '/bin/bash', 22:56:01 INFO - 'SHLVL': '1', 22:56:01 INFO - 'TERM': 'linux', 22:56:01 INFO - 'TMOUT': '86400', 22:56:01 INFO - 'USER': 'cltbld', 22:56:01 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239', 22:56:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:01 INFO - '_': '/tools/buildbot/bin/python'} 22:56:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:01 INFO - Downloading/unpacking blobuploader==1.2.4 22:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:03 INFO - Downloading blobuploader-1.2.4.tar.gz 22:56:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 22:56:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:56:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 22:56:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 22:56:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:03 INFO - Downloading docopt-0.6.1.tar.gz 22:56:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 22:56:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 22:56:03 INFO - Installing collected packages: blobuploader, requests, docopt 22:56:03 INFO - Running setup.py install for blobuploader 22:56:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:56:03 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 22:56:04 INFO - Running setup.py install for requests 22:56:04 INFO - Running setup.py install for docopt 22:56:04 INFO - Successfully installed blobuploader requests docopt 22:56:04 INFO - Cleaning up... 22:56:04 INFO - Return code: 0 22:56:04 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:04 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 0x17f3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a207d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aaf640>, '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 0x1ab0540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab09d0>, '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': '1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239', '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 22:56:04 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 22:56:04 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 22:56:04 INFO - Using env: {'DISPLAY': ':0', 22:56:04 INFO - 'HOME': '/home/cltbld', 22:56:04 INFO - 'LANG': 'en_US.UTF-8', 22:56:04 INFO - 'LANGUAGE': 'en_US:en', 22:56:04 INFO - 'LOGNAME': 'cltbld', 22:56:04 INFO - 'MAIL': '/var/mail/cltbld', 22:56:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:04 INFO - 'MOZ_NO_REMOTE': '1', 22:56:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:56:04 INFO - 'NO_EM_RESTART': '1', 22:56:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:56:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:56:04 INFO - 'PWD': '/builds/slave/test-pgo', 22:56:04 INFO - 'SHELL': '/bin/bash', 22:56:04 INFO - 'SHLVL': '1', 22:56:04 INFO - 'TERM': 'linux', 22:56:04 INFO - 'TMOUT': '86400', 22:56:04 INFO - 'USER': 'cltbld', 22:56:04 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239', 22:56:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:04 INFO - '_': '/tools/buildbot/bin/python'} 22:56:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:56:04 INFO - Running setup.py (path:/tmp/pip-vx4ma9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:56:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:56:04 INFO - Running setup.py (path:/tmp/pip-B9EMrO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:56:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:56:04 INFO - Running setup.py (path:/tmp/pip-9mCCne-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:56:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:56:04 INFO - Running setup.py (path:/tmp/pip-E1Wy4S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:56:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:56:05 INFO - Running setup.py (path:/tmp/pip-8l47Up-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:56:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:56:05 INFO - Running setup.py (path:/tmp/pip-pRmpqH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:56:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:56:05 INFO - Running setup.py (path:/tmp/pip-JEGcwN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:56:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:56:05 INFO - Running setup.py (path:/tmp/pip-notJZ2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:56:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:56:05 INFO - Running setup.py (path:/tmp/pip-bujZwu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:56:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:56:05 INFO - Running setup.py (path:/tmp/pip-CBLXrY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:56:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:56:05 INFO - Running setup.py (path:/tmp/pip-QfADcX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:56:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:56:05 INFO - Running setup.py (path:/tmp/pip-Gy8AlX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:56:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:56:05 INFO - Running setup.py (path:/tmp/pip-kUCIQi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:56:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:56:05 INFO - Running setup.py (path:/tmp/pip-kNDuqp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:56:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:56:05 INFO - Running setup.py (path:/tmp/pip-hIKV2g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:56:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:56:05 INFO - Running setup.py (path:/tmp/pip-kDE2Le-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:56:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:56:05 INFO - Running setup.py (path:/tmp/pip-NC8QKz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:56:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:56:05 INFO - Running setup.py install for manifestparser 22:56:06 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 22:56:06 INFO - Running setup.py install for mozcrash 22:56:06 INFO - Running setup.py install for mozdebug 22:56:06 INFO - Running setup.py install for mozdevice 22:56:06 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 22:56:06 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 22:56:06 INFO - Running setup.py install for mozfile 22:56:06 INFO - Running setup.py install for mozhttpd 22:56:06 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 22:56:06 INFO - Running setup.py install for mozinfo 22:56:06 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 22:56:06 INFO - Running setup.py install for mozInstall 22:56:06 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 22:56:06 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 22:56:06 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 22:56:06 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 22:56:06 INFO - Running setup.py install for mozleak 22:56:06 INFO - Running setup.py install for mozlog 22:56:06 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 22:56:06 INFO - Running setup.py install for moznetwork 22:56:07 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 22:56:07 INFO - Running setup.py install for mozprocess 22:56:07 INFO - Running setup.py install for mozprofile 22:56:07 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 22:56:07 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 22:56:07 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 22:56:07 INFO - Running setup.py install for mozrunner 22:56:07 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 22:56:07 INFO - Running setup.py install for mozscreenshot 22:56:07 INFO - Running setup.py install for moztest 22:56:07 INFO - Running setup.py install for mozversion 22:56:07 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 22:56:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:56:07 INFO - Cleaning up... 22:56:07 INFO - Return code: 0 22:56:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:07 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 0x17f3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a207d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aaf640>, '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 0x1ab0540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab09d0>, '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': '1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239', '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 22:56:07 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 22:56:07 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 22:56:07 INFO - Using env: {'DISPLAY': ':0', 22:56:07 INFO - 'HOME': '/home/cltbld', 22:56:07 INFO - 'LANG': 'en_US.UTF-8', 22:56:07 INFO - 'LANGUAGE': 'en_US:en', 22:56:07 INFO - 'LOGNAME': 'cltbld', 22:56:07 INFO - 'MAIL': '/var/mail/cltbld', 22:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:07 INFO - 'MOZ_NO_REMOTE': '1', 22:56:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:56:07 INFO - 'NO_EM_RESTART': '1', 22:56:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:56:07 INFO - 'PWD': '/builds/slave/test-pgo', 22:56:07 INFO - 'SHELL': '/bin/bash', 22:56:07 INFO - 'SHLVL': '1', 22:56:07 INFO - 'TERM': 'linux', 22:56:07 INFO - 'TMOUT': '86400', 22:56:07 INFO - 'USER': 'cltbld', 22:56:07 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239', 22:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:07 INFO - '_': '/tools/buildbot/bin/python'} 22:56:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:56:07 INFO - Running setup.py (path:/tmp/pip-XupMSS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:56:08 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)) 22:56:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:56:08 INFO - Running setup.py (path:/tmp/pip-EvEDHA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:56:08 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)) 22:56:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:56:08 INFO - Running setup.py (path:/tmp/pip-c3NN9f-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:56:08 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)) 22:56:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:56:08 INFO - Running setup.py (path:/tmp/pip-JzOk6n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:56:08 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)) 22:56:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:56:08 INFO - Running setup.py (path:/tmp/pip-WQWuR5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:56:08 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)) 22:56:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:56:08 INFO - Running setup.py (path:/tmp/pip-oc8d8l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:56:08 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)) 22:56:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:56:08 INFO - Running setup.py (path:/tmp/pip-pMWk00-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:56:08 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)) 22:56:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:56:08 INFO - Running setup.py (path:/tmp/pip-oyFczL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:56:08 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)) 22:56:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:56:08 INFO - Running setup.py (path:/tmp/pip-CUvuui-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:56:08 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)) 22:56:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:56:08 INFO - Running setup.py (path:/tmp/pip-0piFxp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:56:08 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)) 22:56:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:56:08 INFO - Running setup.py (path:/tmp/pip-fmQiJw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:56:08 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)) 22:56:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:56:08 INFO - Running setup.py (path:/tmp/pip-JLjYDI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:56: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)) 22:56:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:56:08 INFO - Running setup.py (path:/tmp/pip-Fb3hfU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:56: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)) 22:56:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:56:08 INFO - Running setup.py (path:/tmp/pip-RqhGCh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:56: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)) 22:56:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:56:08 INFO - Running setup.py (path:/tmp/pip-9BVSKU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:56:09 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)) 22:56:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:56:09 INFO - Running setup.py (path:/tmp/pip-vPTtfE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:56:09 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)) 22:56:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:56:09 INFO - Running setup.py (path:/tmp/pip-m8k9fW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:56:09 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)) 22:56:09 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)) 22:56:09 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)) 22:56:09 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)) 22:56:09 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)) 22:56:09 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)) 22:56:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 22:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:11 INFO - Downloading blessings-1.5.1.tar.gz 22:56:11 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 22:56:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 22:56:11 INFO - Installing collected packages: blessings 22:56:11 INFO - Running setup.py install for blessings 22:56:11 INFO - Successfully installed blessings 22:56:11 INFO - Cleaning up... 22:56:11 INFO - Return code: 0 22:56:11 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 22:56:11 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:56:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:56:11 INFO - Reading from file tmpfile_stdout 22:56:11 INFO - Current package versions: 22:56:11 INFO - argparse == 1.2.1 22:56:11 INFO - blessings == 1.5.1 22:56:11 INFO - blobuploader == 1.2.4 22:56:11 INFO - docopt == 0.6.1 22:56:11 INFO - manifestparser == 1.1 22:56:11 INFO - mozInstall == 1.12 22:56:11 INFO - mozcrash == 0.16 22:56:11 INFO - mozdebug == 0.1 22:56:11 INFO - mozdevice == 0.47 22:56:11 INFO - mozfile == 1.2 22:56:11 INFO - mozhttpd == 0.7 22:56:11 INFO - mozinfo == 0.9 22:56:11 INFO - mozleak == 0.1 22:56:11 INFO - mozlog == 3.1 22:56:11 INFO - moznetwork == 0.27 22:56:11 INFO - mozprocess == 0.22 22:56:11 INFO - mozprofile == 0.27 22:56:11 INFO - mozrunner == 6.11 22:56:11 INFO - mozscreenshot == 0.1 22:56:11 INFO - mozsystemmonitor == 0.0 22:56:11 INFO - moztest == 0.7 22:56:11 INFO - mozversion == 1.4 22:56:11 INFO - psutil == 3.1.1 22:56:11 INFO - requests == 1.2.3 22:56:11 INFO - wsgiref == 0.1.2 22:56:11 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:56:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:11 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 0x17f3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a207d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aaf640>, '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 0x1ab0540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab09d0>, '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': '1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239', '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 22:56:11 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 22:56:11 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 22:56:11 INFO - Using env: {'DISPLAY': ':0', 22:56:11 INFO - 'HOME': '/home/cltbld', 22:56:11 INFO - 'LANG': 'en_US.UTF-8', 22:56:11 INFO - 'LANGUAGE': 'en_US:en', 22:56:11 INFO - 'LOGNAME': 'cltbld', 22:56:11 INFO - 'MAIL': '/var/mail/cltbld', 22:56:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:11 INFO - 'MOZ_NO_REMOTE': '1', 22:56:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:56:11 INFO - 'NO_EM_RESTART': '1', 22:56:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:56:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:56:11 INFO - 'PWD': '/builds/slave/test-pgo', 22:56:11 INFO - 'SHELL': '/bin/bash', 22:56:11 INFO - 'SHLVL': '1', 22:56:11 INFO - 'TERM': 'linux', 22:56:11 INFO - 'TMOUT': '86400', 22:56:11 INFO - 'USER': 'cltbld', 22:56:11 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239', 22:56:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:11 INFO - '_': '/tools/buildbot/bin/python'} 22:56:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:11 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)) 22:56:11 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)) 22:56:11 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)) 22:56:11 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)) 22:56:11 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)) 22:56:11 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)) 22:56:11 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)) 22:56:11 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)) 22:56:11 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)) 22:56:11 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)) 22:56:11 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)) 22:56:11 INFO - Cleaning up... 22:56:11 INFO - Return code: 0 22:56:11 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 22:56:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:11 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 0x17f3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a207d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aaf640>, '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 0x1ab0540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab09d0>, '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': '1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239', '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 22:56:11 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 22:56:11 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 22:56:11 INFO - Using env: {'DISPLAY': ':0', 22:56:11 INFO - 'HOME': '/home/cltbld', 22:56:11 INFO - 'LANG': 'en_US.UTF-8', 22:56:11 INFO - 'LANGUAGE': 'en_US:en', 22:56:11 INFO - 'LOGNAME': 'cltbld', 22:56:11 INFO - 'MAIL': '/var/mail/cltbld', 22:56:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:11 INFO - 'MOZ_NO_REMOTE': '1', 22:56:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:56:11 INFO - 'NO_EM_RESTART': '1', 22:56:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:56:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:56:11 INFO - 'PWD': '/builds/slave/test-pgo', 22:56:11 INFO - 'SHELL': '/bin/bash', 22:56:11 INFO - 'SHLVL': '1', 22:56:11 INFO - 'TERM': 'linux', 22:56:11 INFO - 'TMOUT': '86400', 22:56:11 INFO - 'USER': 'cltbld', 22:56:11 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239', 22:56:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:11 INFO - '_': '/tools/buildbot/bin/python'} 22:56:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:11 INFO - Downloading/unpacking jsonschema 22:56: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 22:56: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 22:56: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 22:56: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 22:56: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 22:56: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 22:56:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:56:13 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 22:56:13 INFO - Downloading/unpacking functools32 (from jsonschema) 22:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:13 INFO - Downloading functools32-3.2.3-2.tar.gz 22:56:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 22:56:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 22:56:13 INFO - warning: no files found matching '*.txt' 22:56:13 INFO - no previously-included directories found matching 'build' 22:56:13 INFO - no previously-included directories found matching 'dist' 22:56:13 INFO - no previously-included directories found matching '.git*' 22:56:13 INFO - Installing collected packages: jsonschema, functools32 22:56:13 INFO - Running setup.py install for functools32 22:56:13 INFO - warning: no files found matching '*.txt' 22:56:13 INFO - no previously-included directories found matching 'build' 22:56:13 INFO - no previously-included directories found matching 'dist' 22:56:13 INFO - no previously-included directories found matching '.git*' 22:56:14 INFO - Successfully installed jsonschema functools32 22:56:14 INFO - Cleaning up... 22:56:14 INFO - Return code: 0 22:56:14 INFO - Running post-action listener: _resource_record_post_action 22:56:14 INFO - Running post-action listener: _start_resource_monitoring 22:56:14 INFO - Starting resource monitoring. 22:56:14 INFO - ##### 22:56:14 INFO - ##### Running install step. 22:56:14 INFO - ##### 22:56:14 INFO - Running pre-action listener: _resource_record_pre_action 22:56:14 INFO - Running main action method: install 22:56:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:56:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:56:14 INFO - Reading from file tmpfile_stdout 22:56:14 INFO - Detecting whether we're running mozinstall >=1.0... 22:56:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 22:56:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 22:56:14 INFO - Reading from file tmpfile_stdout 22:56:14 INFO - Output received: 22:56:14 INFO - Usage: mozinstall [options] installer 22:56:14 INFO - Options: 22:56:14 INFO - -h, --help show this help message and exit 22:56:14 INFO - -d DEST, --destination=DEST 22:56:14 INFO - Directory to install application into. [default: 22:56:14 INFO - "/builds/slave/test-pgo"] 22:56:14 INFO - --app=APP Application being installed. [default: firefox] 22:56:14 INFO - mkdir: /builds/slave/test-pgo/build/application 22:56:14 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'] 22:56:14 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 22:56:29 INFO - Reading from file tmpfile_stdout 22:56:29 INFO - Output received: 22:56:29 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 22:56:29 INFO - Running post-action listener: _resource_record_post_action 22:56:29 INFO - ##### 22:56:29 INFO - ##### Running run-tests step. 22:56:29 INFO - ##### 22:56:29 INFO - Running pre-action listener: _resource_record_pre_action 22:56:29 INFO - Running main action method: run_tests 22:56:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 22:56:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 22:56:29 INFO - Python 2.7.3 22:56:29 INFO - Return code: 0 22:56:29 INFO - grabbing minidump binary from tooltool 22:56:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:29 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 0x1aaf640>, '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 0x1ab0540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab09d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 22:56:29 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 22:56:29 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 22:56:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:56:29 INFO - Return code: 0 22:56:29 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 22:56:29 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 22:56:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 22:56:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 22:56:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 22:56:29 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/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 22:56:29 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/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --webServer localhost 22:56:29 INFO - Using env: {'DISPLAY': ':0', 22:56:29 INFO - 'HOME': '/home/cltbld', 22:56:29 INFO - 'LANG': 'en_US.UTF-8', 22:56:29 INFO - 'LANGUAGE': 'en_US:en', 22:56:29 INFO - 'LOGNAME': 'cltbld', 22:56:29 INFO - 'MAIL': '/var/mail/cltbld', 22:56:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:56:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 22:56:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:29 INFO - 'MOZ_NO_REMOTE': '1', 22:56:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:56:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:56:29 INFO - 'NO_EM_RESTART': '1', 22:56:29 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:56:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:56:29 INFO - 'PWD': '/builds/slave/test-pgo', 22:56:29 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 22:56:29 INFO - 'SHELL': '/bin/bash', 22:56:29 INFO - 'SHLVL': '1', 22:56:29 INFO - 'TERM': 'linux', 22:56:29 INFO - 'TMOUT': '86400', 22:56:29 INFO - 'USER': 'cltbld', 22:56:29 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239', 22:56:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:29 INFO - '_': '/tools/buildbot/bin/python'} 22:56:29 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/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost'] with output_timeout 3600 22:56:29 INFO - mozversion INFO | application_buildid: 20151124203151 22:56:29 INFO - mozversion INFO | application_changeset: 94202eb8f17564d3127ca52ff4f57d286782a195 22:56:29 INFO - mozversion INFO | application_display_name: Nightly 22:56:29 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:56:29 INFO - mozversion INFO | application_name: Firefox 22:56:29 INFO - mozversion INFO | application_remotingname: firefox 22:56:29 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/b2g-inbound 22:56:29 INFO - mozversion INFO | application_vendor: Mozilla 22:56:29 INFO - mozversion INFO | application_version: 45.0a1 22:56:29 INFO - mozversion INFO | platform_buildid: 20151124203151 22:56:29 INFO - mozversion INFO | platform_changeset: 94202eb8f17564d3127ca52ff4f57d286782a195 22:56:29 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/b2g-inbound 22:56:29 INFO - mozversion INFO | platform_version: 45.0a1 22:56:29 INFO - 2015-11-24 22:56:29,922 DEBUG : using testdate: 1448434589 22:56:29 INFO - 2015-11-24 22:56:29,922 DEBUG : actual date: 1448434589 22:56:29 INFO - 2015-11-24 22:56:29,932 INFO : starting webserver on 'localhost:57075' 22:56:29 INFO - 2015-11-24 22:56:29,933 INFO : Starting test suite talos-linux64-ix-029 22:56:29 INFO - 2015-11-24 22:56:29,933 INFO : Starting test dromaeo_css 22:56:29 INFO - 2015-11-24 22:56:29,933 DEBUG : operating with platform_type : linux_ 22:56:29 INFO - 2015-11-24 22:56:29,934 INFO : Initialising browser for dromaeo_css test... 22:56:29 INFO - 2015-11-24 22:56:29,947 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpV8Lspf/profile http://localhost:57075/getInfo.html 22:56:33 INFO - 2015-11-24 22:56:33,863 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:56:33 INFO - 2015-11-24 22:56:33,863 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:56:33 INFO - 2015-11-24 22:56:33,863 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 22:56:33 INFO - 2015-11-24 22:56:33,863 DEBUG : BROWSER_OUTPUT: __metrics 22:56:34 INFO - 2015-11-24 22:56:34,041 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] 22:56:35 INFO - 2015-11-24 22:56:35,267 INFO : Browser initialized. 22:56:35 INFO - 2015-11-24 22:56:35,267 INFO : Running cycle 1/1 for dromaeo_css test... 22:56:35 INFO - 2015-11-24 22:56:35,267 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpV8Lspf/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:56:46 INFO - 2015-11-24 22:56:46,982 DEBUG : BROWSER_OUTPUT: RSS: Main: 138952704 22:56:46 INFO - 2015-11-24 22:56:46,982 DEBUG : BROWSER_OUTPUT: 22:57:37 INFO - 2015-11-24 22:57:37,707 DEBUG : BROWSER_OUTPUT: 1448434657705 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:59:47 INFO - 2015-11-24 22:59:47,752 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57075/tests/dromaeo/cssquery-ext.html) 22:59:49 INFO - 2015-11-24 22:59:49,260 DEBUG : BROWSER_OUTPUT: RSS: Main: 301465600 22:59:49 INFO - 2015-11-24 22:59:49,260 DEBUG : BROWSER_OUTPUT: 23:02:51 INFO - 2015-11-24 23:02:51,794 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/dromaeo/cssquery-ext.html (next: http://localhost:57075/tests/dromaeo/cssquery-jquery.html) 23:02:52 INFO - 2015-11-24 23:02:52,360 DEBUG : BROWSER_OUTPUT: RSS: Main: 225271808 23:02:52 INFO - 2015-11-24 23:02:52,360 DEBUG : BROWSER_OUTPUT: 23:05:54 INFO - 2015-11-24 23:05:54,298 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57075/tests/dromaeo/cssquery-mootools.html) 23:05:54 INFO - 2015-11-24 23:05:54,864 DEBUG : BROWSER_OUTPUT: RSS: Main: 218071040 23:05:54 INFO - 2015-11-24 23:05:54,864 DEBUG : BROWSER_OUTPUT: 23:08:56 INFO - 2015-11-24 23:08:56,669 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57075/tests/dromaeo/cssquery-prototype.html) 23:08:57 INFO - 2015-11-24 23:08:57,236 DEBUG : BROWSER_OUTPUT: RSS: Main: 203096064 23:08:57 INFO - 2015-11-24 23:08:57,236 DEBUG : BROWSER_OUTPUT: 23:11:59 INFO - 2015-11-24 23:11:59,600 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57075/tests/dromaeo/cssquery-yui.html) 23:12:00 INFO - 2015-11-24 23:12:00,202 DEBUG : BROWSER_OUTPUT: RSS: Main: 206106624 23:12:00 INFO - 2015-11-24 23:12:00,202 DEBUG : BROWSER_OUTPUT: 23:15:02 INFO - 2015-11-24 23:15:02,624 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/dromaeo/cssquery-yui.html (next: http://localhost:57075/tests/dromaeo/cssquery-dojo.html) 23:15:02 INFO - 2015-11-24 23:15:02,883 DEBUG : BROWSER_OUTPUT: RSS: Main: 277327872 23:15:02 INFO - 2015-11-24 23:15:02,883 DEBUG : BROWSER_OUTPUT: 23:15:02 INFO - 2015-11-24 23:15:02,883 DEBUG : BROWSER_OUTPUT: __start_tp_report 23:15:02 INFO - 2015-11-24 23:15:02,883 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 23:15:02 INFO - 2015-11-24 23:15:02,883 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 23:15:02 INFO - 2015-11-24 23:15:02,883 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 23:15:02 INFO - 2015-11-24 23:15:02,884 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3219.951700724489;3263.2820779428516;3301.0261972718044;3304.9504257436142;3321.219513414347;5326.334208223972;5411.053065713494;5485.890282194358;5501.449855014496;5528.861778455541;5439.3200849893765;5462.644928079672;5507.504324610784;5508.9173662395;5594.216809646642;4976.402831660197;4993.300937868701;5031.647784655075;5038.7984480620735;5053.873653158664;4951.331570238026;5118.232265160237;5158.226266060102;5174.43081261061;5255.316808814849;4976.253561965698;5004.074246264453;5082.847514574569;5084.46613105626;5107.565856902168;5020.196768517057;5041.369828771403;5053.065182941167;5071.239314102877;5091.0072535855525;4813.470518242991;4918.04098200851;5025.145725226684;5026.29631851541;5039.974800126012;5336.626436149471;5394.487523685244;5409.675419474805;5413.350397952271;5438.53772429344;5990.101484777319;5995.6702381369005;5999.940000599981;6000.279966404016;6006.819795406145;5797.623154494944;5984.670843103628;6018.127371531147;6051.636901785907;6067.029275315928;5674.858128546819;5679.971600141973;5684.573656975744;5687.60186786921;5724.284464441945;6146.938530614637;6151.2618485781095;6160.7535698571555;6162.383761623802;6163.291221509494;5582.692951887685;5654.321481422174;5661.603687741901;5784.797532086357;5945.554083443672;5937.614055086406;5988.880222395528;5990.251218597676;6011.879762404727;6042.728077236535;5917.911231331534;5975.79084732041;5982;5987.491063259585;5987.64074155552;5594.888102238014;5662.462066103713;5685.545156387479;5997.610155339889;6003.7898673546215;4523.457185137804;4547.454305483363;4554.203014472454;4581.931271030937;4583.954160458419;4071.206114807643;4076.3477843544892;4083.1425400666194;4087.5912408758886;4114.074333274989;5392.433794451605;5493.862653433616;5508.476694713996;5626.634268772516;5634.239377684042;5993.670348130895;6001.309849367379;6004.519638428916;6008.759649613967;6062.605930614496;5532.6680399176175;5623.353314368818;5631.183478395692;5639.492445679908;5647.49172574462;4870.269459581091;5003.724795136302;5053.772580233898;5149.382074151126;5166.095933211673;6131.294901086343;6195.411435913581;6196.938030619636;6197.163382943245;6197.4732147767045;5972.104184372379;6016.699165041818;6029.78895738647;6033.12519684652;6041.728122234598;5809.2738407699035;5948.613340132878;5948.762049517971;6004.459598636144;6094.2652418614325;5832.883342333214;5880.558958078076;5920.911186332122;5923.792667256797;5928.881422371443;5972.313183983897;5996.2504686914135;6000.639961602318;6001.549883758648;6018.337982821974;5568.470995255444;5571.470710282679;5631.436856314336;5632.718364081861;5636.323641162922;5667.149927510742;5706.3152421709665;5717.285339332583;5719;5719.5138413235345;5600.411956744646;5643.830685079454;5663.773449061991;5664.37691853904;5666.405027472056;5820.970895145497;5865.3254875689845;5893.381194974638;5916.911246331222;5929.466348028698;5763.221965034834;5785.739641716217;5789.594728368974;5799.06740144139;5829.183914251923 23:15:02 INFO - 2015-11-24 23:15:02,884 DEBUG : BROWSER_OUTPUT: |1;ext.html;552.1795162637137;585.765693722511;588.4644973074502;602.3073465514655;621.962682239066;31080.378392432136;40193.59806401935;40731.18537629253;40856.79571602138;40921.386179207264;42991;43535;43563.346549801776;43700.78149609247;43727.56272437274;7703;7804.375649948019;8811.118888111201;9015.413998090104;10282.691519254397;10475.161987041056;10498.737531561696;10596.205284603648;10603.310784799001;10606.098481629071;10627;10716.464176791129;10728.570857165703;10729.463526823627;10729.517171727291;7839.25527074927;8054.355651547891;10729.08802751767;10988.890111098866;10989.780204395947;10304.587816487332;10353.74115647115;10480.528376223088;10480.94759526205;10484.947575262151;40326.59673403258;40619.7969010153;40902.795486022675;41111.383329250086;41240;3363.8822641207685;3480.042988178246;3522;3722.4230244311902;3933.272344616255;30371.848140759375;30651.846740766152;30886.22784430385;31086.844565777254;31642.841785791155;3637.181634132299;3644.3075815595216;3645.3620616392027;3756.7745935243975;3838.9232215355537;265462;265851;267460;267763;268249;51764;53143.73428132835;58599.70700146472;60697.69651151716;61308;60954.69522652358;61143.388566114656;61336.69331653402;61369.69315153485;61506.692466538276;15745.370185192463;15854.128022958848;16003.439879604159;16016.75974860378;16047.919760401122;11864.703382415508;11958.64124076279;11960.760784784256;11962.102842286864;12103.455344509519;19920.900395498218;20095.497612559804;20229.898850505946;20273.69589456159;20276.594468110554;7411.407087432993;7633.465657404039;7704.961475192588;7714;7729.652165652559;913.8263729891299;978.7308490165261;985.2905907746555;1035.546452580652;1107.3300653104916;28305;28341;28734.425311493655;28846.567301490493;28854.27864303367;1029.4955471818755;1034.1364960258268;1109.2290857853875;1133.348324713293;1200.7058270723728;13462.394192261372;13657.317134143452;15323.61690957735;15642.011287121712;15964.92017539905;305449;309957;310741;310959;311837;52620.473795261554;52820;52889.206661900105;52936.47063529315;53035.20447193295;1357.043284484436;1362.2303398579968;1388.5070799866105;1389.7498450279047;1396.2599822094305;23218.535629287657;27975.300617484725;28956.131316060553;29440.852795735886;29514.262143446587;43370.566294336655;43753.78123109364;43773.24907003684;43784.56215437805;43822.12355752931;213635;215180;215202;215470;215578;27539;28290;28574;31452.842735786173;32006.839965800023;37603.43594846025;38892.80553597214;39255;42509.57490425056;42892.78553607212;35798.8210058948;35976.460353095244;36183.09542261464;36193.63806362008;37190;28818.567721484615;35965.64034359623;36259.637403625624;36260.45609315809;36276.377161540004;30163.396732065674;30182.84908575443;35066.47400288947;35399.11502212465;35823.6264560318;28229.306241625567;30287;31777.682223178395;31854.522182167748;32044.679553205104;24562;24781.50436991214;24991.02011494276;25053.624195637443;25268.736563172137 23:15:02 INFO - 2015-11-24 23:15:02,884 DEBUG : BROWSER_OUTPUT: |2;jquery.html;357.8210894552724;358.54106743368465;360.9296187243487;360.9819509024548;363.03613905082;13555.932220338884;13599.028140919585;16446.42437514687;16654.666906661874;17220.65558688827;14812.851871481282;15783.131927743969;16192.75710864335;16209.594760131002;16216.675666486663;5214.504622060907;5369.979703856275;5371.113766228567;5378.973105134469;5379.193121031857;3802.3345914464994;3967.3453880109746;5311.654742441729;5330.786768529274;5801.129830525413;5891.587588868781;5900.586958912878;5911.46796788289;5919.112133180014;5953.910691339634;5782.884342313151;5896.528277737771;5899;5920.348761636238;6014.909776353336;5193.143131383317;5211.583073354161;5229.921551176735;5230.555402790767;5234.031704134747;19700.802991970184;20206;20214.292499762436;20230.089645965967;20516.794832051637;2170.066871245364;2281.7376001759844;2331.6502524621274;2449.9877500612383;2453.939087602459;13144.14563053408;13196.406161722649;13293.135946163566;13382;13444.126131801402;1740.4865564658455;1828.4331857124334;1842.9631407371778;1870.326682394337;1880.6802843516498;57428.71285643587;59780.701096494675;60100;60265.39734602642;60350.698246508924;9600.32797704154;9742.366746161477;9778.266630002636;9809.607615695293;9937.10566049059;12731.363431828371;12751.936240318739;12772.169808962388;12798.424070916832;12799.936000319938;7218.061651985313;7246;7461.962690186514;7508.3993280537425;7538.944547763317;6932.826679333057;6946.16646002483;7315.122185337794;7384.483086184023;7401.851962960711;5867.266591676041;5901.29184497863;5958.136070269873;5987.730552125165;6078.270607527125;1650.7853978982896;1718.407149533409;1728.3864228198809;1754.5964428181335;1781.153951872876;950.2160717408166;969.3795970578835;983.7737320416344;989.6585677941099;1004.4425343934041;13141.211527308393;14186.304836194953;17718.46844594664;17965;18334.266629334677;897.946123232595;906.6735975048977;917.0600134861687;950.6292545907102;975.2929126383457;9264.11990860867;9457.905420945703;9463;9553.474558899326;9654.951725241328;399889;410468;410685;410951;548948;73066.63466682633;77627;78251.21748782553;78252.21747782563;78379.60810195912;1114.6098865397046;1137.5449820072038;1149.448264832885;1150.0454622663337;1152.5217034930577;15085.698286034438;15249.542513724606;15426.382944682087;15452.381904723685;15756.76364854551;34724.30551389009;34945.82527087348;34972.30055398827;35146.47280290747;35252.118697032776;43739.34390984201;44299.82719181641;45525;45873;48437.27344089771;9420.811583768149;9467.526623668928;9625.229981601595;9638.084381983701;9646.119006793888;11479.196456247982;11493.367864767184;11522.481488332878;11585.304881707125;11635.709107272385;12905.741885162432;14174.07868488545;14215.857841421866;14328.785068224193;14471.204083775494;13809.240491772647;14049.227292498601;14201.786973195616;14470.565883023526;14542.127472351693;11538.82691759641;11893.702657433634;13848.930755346562;14540.49108281205;14744.410223590936;8503.8724419135;8854.955725221333;9444.763880903032;9446.29152813528;9460.716178514656;9936.552855121392;12895.871041289467;13786.862131378557;14104.78842817338;14385.496507622183 23:15:02 INFO - 2015-11-24 23:15:02,886 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1319.8944084473244;1463.926803659817;1521.5587479630908;1654.4871089962112;1765.8499027582654;6713.496487763419;7315.268473152682;7483.73806916758;7496.287852653996;7510.962445187766;7288.927110728891;7470.439717021218;7478.887816682754;7491.625418729055;7495.362894154004;1953.8632295739303;1989.4926793667667;1990.6516359637078;2198.824094072478;2227.465408302012;2275.351524815428;2326.697529321186;2327.208749025331;2329.6389059695775;2329.6971393718877;2215.523662412581;2224.7552769195377;2287.9084836606517;2288.382136823057;2290.9312720618323;1960.4706729183113;2009.7688765791902;2180.226019762993;2234.0393630738777;2261.8642881427004;2254.616715158427;2256.6953461282847;2271.2958982715413;2284.8743319117443;2286.4855407533337;7481.7007319707145;7549.0563679540055;7558.281963213486;7561.319481246659;7561.697532098709;4539.977300113478;4601.654875884322;4638.605718513897;4663.48701642819;4665.183592871234;3076.7230949214677;3128.8592013359453;3168.6197656281165;3170.7621928355466;3189.1389324882152;996.9252306077074;1009.439760932682;1016.2682868334787;1032.7160030991463;1071.3357718214756;9991.700248992542;10109.494525273707;10109.696709098738;10124.493775311279;10130.189584833195;4011.498562679665;4124.876253712333;4129.194807012604;4133.8966525837095;4169.332906734908;8269;8305.875411868827;8324.583770811436;8326.750197494084;8333;4584.174848527297;4587.885302867388;4590.540945905383;4594.287885377772;4614.884627884263;4202.789860506962;4238.0888109056705;4243.872683819491;4243.978780106079;4247.617714405656;3385.7291416686608;3434.587849458081;3479.8260086995547;3481.5822101347494;3486.8605255789994;1059.682095371401;1077.6174458067273;1083.712816103733;1090.3566895531517;1090.5310716392028;905.5879574793453;1116.5143162724241;1327.5419980106847;1363.8567950365268;1364.0860623382357;7135.322144396274;7178.78463646081;7195.316444937723;7227.89158162628;7233.89149162763;494.8515445366448;496.8186611886708;503.3129777853277;504.0448350376017;505.7496539213113;5617.747201375948;5640.689762063043;5677.943220567741;5679.091345384719;5698.202251684767;8512.233898949;8651.437656552303;8658.04761476237;8766.254868336135;8771.692990745294;8881.111888811067;8932.553372331358;8998.685046023358;9006.774830629287;9043.276537876955;6889.311068893071;7031.40233080194;7058.082449281561;7122.252163522756;7122.786316410516;7002.404795592432;7011.929880701127;7032.5077244592385;7038.331358520933;7047.823804404931;7861.331786797958;7885.84228315419;7975.641096150571;8050.959745201237;8073.878891816745;8047.87928181066;8094.635741391534;8112.391570632344;8138.796530086795;8141.633626486941;6679.332066793282;6872.518923675295;6890.621015844176;6897.137857767779;6904.171499420102;7321.084864391951;7345.118585769742;7362.5214361066355;7366.263373662591;7388.371988381048;6734.259231484631;6895.620740859301;6903.689333980084;7017.228104908354;7049.718011279491;5796.62321949072;6592.736290548324;6662.233843107911;6766.560173588701;6844.828879278058;6369.299377068612;6437.7103030362805;6594.043863639841;6619.801405957829;6620.569662971891;6216.968915155395;6242.65665388408;6270.49836013127;6274.152989346379;6275.748970041147;6194.783182588588;6227.719752611234;6230.283517395376;6267.811965641038;6278.4349408551625 23:15:02 INFO - 2015-11-24 23:15:02,887 DEBUG : BROWSER_OUTPUT: |4;prototype.html;340.1343580587405;342.06786506769055;349.4531058892831;350.56880037553816;352.71782573940857;5957.582969192158;6042.305134909487;6062.302835173957;6084.087386891968;6109.9999999999945;5966.552508561854;5969.552283578738;5974.641521508713;5976;5983.341832398432;4427.512973572904;4671.22924717422;4675.812967481296;4675.906481870361;4679.344891715163;4514.9774251128865;4528.864134075967;4539.137563862872;4541.182587134314;4546.590806827385;4460.264056430701;4477.619402350804;4482.193205223075;4482.932756008662;4485.461744590653;4360.193364227628;4430.933535996963;4434.68957172998;4467.972366355756;4472.127935052666;3971.8609848655447;3984.2230765000836;3992.680585553149;4004.899877503057;4008.979955100235;5651.745671444794;5655.5475561954945;5664.433556644344;5666.546676265889;5676.375598684142;4981.701097934099;5114.207297868837;5116.155834287338;5141.460146684582;5253.894922101536;6330.177076979963;6338.461230795388;6344.619322840645;6345.428911397997;6346.873062538723;4344.152890186415;4434.224010798098;4434.977825110886;4437.223485889988;4439.311906654474;6413.358664133596;6489.480841532666;6562.868742625121;6566.277709452004;6573.868522629568;4497.123061003138;4502.909941801146;4503.482099558592;4504.031633198878;4505.639548836085;4555.726656400627;4563.680542362069;4568.200564901129;4569.109023740339;4570.291604801261;5270.235815806686;5276.551493123051;5279.260903473517;5279.260903473517;5281.181416880389;6216.813495595139;6371.904421433682;6376.266729326094;6380.393862583047;6387.616742995435;1604.2860926887656;1620.9108499032432;1621.1407953784512;1622.7160246956735;1623.9431619893483;1083.7507373304184;1085.8045551800594;1088.1349327284827;1093.475131936659;1110.8555887734542;6171.938280617226;6176.413240742122;6187.3503282154725;6187.938120618826;6190.969045154746;6636.502262330248;6637.336266373324;6646.202455705346;6649.4347980421235;6650.600963942179;6030.668313242726;6112.297085835097;6174.135621013062;6179.196704428486;6185.659788711574;3558;3584.4981702561663;3588.6052534221217;3589.766665166756;3591.569011718558;6744.966275168691;6768.864622707617;6772.627505487147;6786;6791.252962174157;6521.739130434825;6524.706388212542;6726.932730672728;6736.05695202672;6739.427148692318;6718.0930574372815;6768.9661551691925;6827.624480653512;6858.862822743617;6862.656867156622;6616;6650.767223147167;6655.367740064686;6657.3009833966735;6664.233613134552;6380.298167201511;6517.315681853527;6544.4764418845425;6546.803595892131;6555.049517819984;6704.966475167593;6750.628715420699;6763.3236676333945;6764.289749576224;6845.965770171118;4537.8184872604725;4555.088982203506;4556.772161391984;4561.794719237576;4566.292224705176;6480.708368123352;6601.9669901652105;6625.238097618835;6626.171728533934;6632.436242919213;6462.515311351762;6465.870682586228;6468.902966455413;6470.029495575702;6472.902906456501;6434.806955791334;6435.871282574416;6440.25937017249;6446.129772480655;6446.871062578628;6187.443130118311;6326.936730632635;6336.081268216175;6342.080398342307;6342.460890824332;6474.417302442803;6477.546571739933;6484.351564843478;6489.3510648934725;6505.479561634963;6332.081848131903;6333.398327158912;6339.144215530844;6341.841453963688;6342.112104305494 23:15:02 INFO - 2015-11-24 23:15:02,887 DEBUG : BROWSER_OUTPUT: |5;yui.html;734.7869117955793;772.4013889235844;802.8956235689361;850.4982060584254;892.2549671024694;15134.243287835605;15430.608255720354;15431.614209644737;15453.459128930504;15495.457658981937;14333;14434.5669629911;14468.782968255484;14478.782818257709;14485.855141448583;687.9759208427706;700.1108592088045;813.5403497024178;815.1115284340068;817.8282560662268;820.0487434575894;828.2297463359074;833.6832003838533;834.9833003339929;835.0105125426359;869.2611280411663;878.0341624213346;878.2271600991119;878.661715239631;879.8416285068685;713.9964300178492;725.891116332549;873.8033942362981;873.9082396348388;895.9820803583892;633.6578247746212;633.8573820890309;638.119395234578;638.4062821575933;639.7217210513395;13009.349532523336;13235.941124710096;13278.402471888692;13301.467941282337;13302.933485332609;11799.410029498576;12262.386880655931;12435.813462798065;12717.30054846983;13073.607791766262;9439.622415103388;9652.324337296392;10220.693379198561;10426.478676066166;10666.253362264644;3540.486629438717;3607.512985746917;3633.128049268183;3635.94546081809;3678.1724112074835;49451.505484945046;49939.25091123636;50557.747211264075;50703.49296507025;50751.23873141906;8662.436941598777;8770.298376129895;8994.86507702385;9047.81904361909;9068.455892646462;18597;18612.348567800334;18629.44111676652;18634.440966771017;18646.720299195524;7526.623668816537;7637.579933103621;7754;7868.763937081897;8026.7591972240925;4400.559944005574;4428.047969686533;4431.158079964796;4467.7319360838455;4470.530594287553;3131.5146152346424;3161.1780936956557;3165.1612322734495;3165.936681266408;3172.5558421820965;545.5935328180451;551.1099574187658;556.9749361278753;575.7955925646419;575.8733078722674;517.5755880178218;525.858018335055;536.8738346488572;538.163156291962;548.3447280499764;7896.68413263463;8467.661293548312;8543.658253669908;8579.14208579137;8860.113988601217;551.4032569420415;620.8013435700623;637.7544645311589;642.5566359212129;672.9226138994078;3608.8736894208578;3843.385058390644;4060.614241647039;4141.399497072907;4173.83304667816;56943;57255.42744572501;57420;57786.42213577894;57946;49966;50498.2425263614;50905.490945090816;51027.23459148116;51072.744636276584;46361.5363846364;46741.29888051682;46947.765261173474;47221.5277847224;47495.76252118786;8135.389845761473;8265.214804593554;8383.62273697673;8429.578521074045;8581.399302049047;33530.994070177934;34017.14957125993;34200.48699269563;34640.653593464755;35058.82470587631;43176;44695.55304447044;44920;45201;45323.32015019843;10080;10155.7461063474;10351.482425878525;10537.104346130665;10754.784904302027;11245.775084498428;11254.212205145888;11308.151888608552;11352.886471135183;11381.43092845371;10045.447500387256;10224.897751022394;10244.59021639156;10351.223658225512;10771;10824.729381765519;10991.61529346469;11116.722081947693;11343.092552595608;11460.82808757886;10009.849852252077;10144.188464922936;10196.847047294148;10312.226583006455;10329;9053.004793408063;9281;9383.296252780932;9439.905600944177;9460.432374057578;8275.917240827515;8698.21716045559;8834.779130521789;8912.73261802147;9311.674091406769 23:15:02 INFO - 2015-11-24 23:15:02,887 DEBUG : BROWSER_OUTPUT: __end_tp_report 23:15:02 INFO - 2015-11-24 23:15:02,887 DEBUG : BROWSER_OUTPUT: __start_cc_report 23:15:02 INFO - 2015-11-24 23:15:02,887 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1292 23:15:02 INFO - 2015-11-24 23:15:02,887 DEBUG : BROWSER_OUTPUT: __end_cc_report 23:15:02 INFO - 2015-11-24 23:15:02,887 DEBUG : BROWSER_OUTPUT: __startTimestamp1448435702876__endTimestamp 23:15:02 INFO - 2015-11-24 23:15:02,888 DEBUG : BROWSER_OUTPUT: 23:15:02 INFO - 2015-11-24 23:15:02,888 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 23:15:02 INFO - 2015-11-24 23:15:02,888 DEBUG : BROWSER_OUTPUT: Number of tests: 6 23:15:02 INFO - 2015-11-24 23:15:02,888 DEBUG : BROWSER_OUTPUT: 23:15:02 INFO - 2015-11-24 23:15:02,888 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5506.55 Median:5663.77 stddev:622.15 (11.0%) stddev-sans-first:597.64 23:15:02 INFO - 2015-11-24 23:15:02,888 DEBUG : BROWSER_OUTPUT: Values: 3220.0 3263.3 3301.0 3305.0 3321.2 5326.3 5411.1 5485.9 5501.4 5528.9 5439.3 5462.6 5507.5 5508.9 5594.2 4976.4 4993.3 5031.6 5038.8 5053.9 4951.3 5118.2 5158.2 5174.4 5255.3 4976.3 5004.1 5082.8 5084.5 5107.6 5020.2 5041.4 5053.1 5071.2 5091.0 4813.5 4918.0 5025.1 5026.3 5040.0 5336.6 5394.5 5409.7 5413.4 5438.5 5990.1 5995.7 5999.9 6000.3 6006.8 5797.6 5984.7 6018.1 6051.6 6067.0 5674.9 5680.0 5684.6 5687.6 5724.3 6146.9 6151.3 6160.8 6162.4 6163.3 5582.7 5654.3 5661.6 5784.8 5945.6 5937.6 5988.9 5990.3 6011.9 6042.7 5917.9 5975.8 5982.0 5987.5 5987.6 5594.9 5662.5 5685.5 5997.6 6003.8 4523.5 4547.5 4554.2 4581.9 4584.0 4071.2 4076.3 4083.1 4087.6 4114.1 5392.4 5493.9 5508.5 5626.6 5634.2 5993.7 6001.3 6004.5 6008.8 6062.6 5532.7 5623.4 5631.2 5639.5 5647.5 4870.3 5003.7 5053.8 5149.4 5166.1 6131.3 6195.4 6196.9 6197.2 6197.5 5972.1 6016.7 6029.8 6033.1 6041.7 5809.3 5948.6 5948.8 6004.5 6094.3 5832.9 5880.6 5920.9 5923.8 5928.9 5972.3 5996.3 6000.6 6001.5 6018.3 5568.5 5571.5 5631.4 5632.7 5636.3 5667.1 5706.3 5717.3 5719.0 5719.5 5600.4 5643.8 5663.8 5664.4 5666.4 5821.0 5865.3 5893.4 5916.9 5929.5 5763.2 5785.7 5789.6 5799.1 5829.2 23:15:02 INFO - 2015-11-24 23:15:02,888 DEBUG : BROWSER_OUTPUT: 23:15:02 INFO - 2015-11-24 23:15:02,890 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:43862.68 Median:31083.61 stddev:69499.61 (223.6%) stddev-sans-first:69618.49 23:15:02 INFO - 2015-11-24 23:15:02,890 DEBUG : BROWSER_OUTPUT: Values: 552.2 585.8 588.5 602.3 622.0 31080.4 40193.6 40731.2 40856.8 40921.4 42991.0 43535.0 43563.3 43700.8 43727.6 7703.0 7804.4 8811.1 9015.4 10282.7 10475.2 10498.7 10596.2 10603.3 10606.1 10627.0 10716.5 10728.6 10729.5 10729.5 7839.3 8054.4 10729.1 10988.9 10989.8 10304.6 10353.7 10480.5 10480.9 10484.9 40326.6 40619.8 40902.8 41111.4 41240.0 3363.9 3480.0 3522.0 3722.4 3933.3 30371.8 30651.8 30886.2 31086.8 31642.8 3637.2 3644.3 3645.4 3756.8 3838.9 265462.0 265851.0 267460.0 267763.0 268249.0 51764.0 53143.7 58599.7 60697.7 61308.0 60954.7 61143.4 61336.7 61369.7 61506.7 15745.4 15854.1 16003.4 16016.8 16047.9 11864.7 11958.6 11960.8 11962.1 12103.5 19920.9 20095.5 20229.9 20273.7 20276.6 7411.4 7633.5 7705.0 7714.0 7729.7 913.8 978.7 985.3 1035.5 1107.3 28305.0 28341.0 28734.4 28846.6 28854.3 1029.5 1034.1 1109.2 1133.3 1200.7 13462.4 13657.3 15323.6 15642.0 15964.9 305449.0 309957.0 310741.0 310959.0 311837.0 52620.5 52820.0 52889.2 52936.5 53035.2 1357.0 1362.2 1388.5 1389.7 1396.3 23218.5 27975.3 28956.1 29440.9 29514.3 43370.6 43753.8 43773.2 43784.6 43822.1 213635.0 215180.0 215202.0 215470.0 215578.0 27539.0 28290.0 28574.0 31452.8 32006.8 37603.4 38892.8 39255.0 42509.6 42892.8 35798.8 35976.5 36183.1 36193.6 37190.0 28818.6 35965.6 36259.6 36260.5 36276.4 30163.4 30182.8 35066.5 35399.1 35823.6 28229.3 30287.0 31777.7 31854.5 32044.7 24562.0 24781.5 24991.0 25053.6 25268.7 23:15:02 INFO - 2015-11-24 23:15:02,890 DEBUG : BROWSER_OUTPUT: 23:15:02 INFO - 2015-11-24 23:15:02,890 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25662.64 Median:3884.84 stddev:72051.67 (1854.7%) stddev-sans-first:72228.74 23:15:02 INFO - 2015-11-24 23:15:02,890 DEBUG : BROWSER_OUTPUT: Values: 357.8 358.5 360.9 361.0 363.0 13555.9 13599.0 16446.4 16654.7 17220.7 14812.9 15783.1 16192.8 16209.6 16216.7 5214.5 5370.0 5371.1 5379.0 5379.2 3802.3 3967.3 5311.7 5330.8 5801.1 5891.6 5900.6 5911.5 5919.1 5953.9 5782.9 5896.5 5899.0 5920.3 6014.9 5193.1 5211.6 5229.9 5230.6 5234.0 19700.8 20206.0 20214.3 20230.1 20516.8 2170.1 2281.7 2331.7 2450.0 2453.9 13144.1 13196.4 13293.1 13382.0 13444.1 1740.5 1828.4 1843.0 1870.3 1880.7 57428.7 59780.7 60100.0 60265.4 60350.7 9600.3 9742.4 9778.3 9809.6 9937.1 12731.4 12751.9 12772.2 12798.4 12799.9 7218.1 7246.0 7462.0 7508.4 7538.9 6932.8 6946.2 7315.1 7384.5 7401.9 5867.3 5901.3 5958.1 5987.7 6078.3 1650.8 1718.4 1728.4 1754.6 1781.2 950.2 969.4 983.8 989.7 1004.4 13141.2 14186.3 17718.5 17965.0 18334.3 897.9 906.7 917.1 950.6 975.3 9264.1 9457.9 9463.0 9553.5 9655.0 399889.0 410468.0 410685.0 410951.0 548948.0 73066.6 77627.0 78251.2 78252.2 78379.6 1114.6 1137.5 1149.4 1150.0 1152.5 15085.7 15249.5 15426.4 15452.4 15756.8 34724.3 34945.8 34972.3 35146.5 35252.1 43739.3 44299.8 45525.0 45873.0 48437.3 9420.8 9467.5 9625.2 9638.1 9646.1 11479.2 11493.4 11522.5 11585.3 11635.7 12905.7 14174.1 14215.9 14328.8 14471.2 13809.2 14049.2 14201.8 14470.6 14542.1 11538.8 11893.7 13848.9 14540.5 14744.4 8503.9 8855.0 9444.8 9446.3 9460.7 9936.6 12895.9 13786.9 14104.8 14385.5 23:15:02 INFO - 2015-11-24 23:15:02,890 DEBUG : BROWSER_OUTPUT: 23:15:02 INFO - 2015-11-24 23:15:02,890 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5239.23 Median:6229.00 stddev:2688.99 (43.2%) stddev-sans-first:2680.40 23:15:02 INFO - 2015-11-24 23:15:02,892 DEBUG : BROWSER_OUTPUT: Values: 1319.9 1463.9 1521.6 1654.5 1765.8 6713.5 7315.3 7483.7 7496.3 7511.0 7288.9 7470.4 7478.9 7491.6 7495.4 1953.9 1989.5 1990.7 2198.8 2227.5 2275.4 2326.7 2327.2 2329.6 2329.7 2215.5 2224.8 2287.9 2288.4 2290.9 1960.5 2009.8 2180.2 2234.0 2261.9 2254.6 2256.7 2271.3 2284.9 2286.5 7481.7 7549.1 7558.3 7561.3 7561.7 4540.0 4601.7 4638.6 4663.5 4665.2 3076.7 3128.9 3168.6 3170.8 3189.1 996.9 1009.4 1016.3 1032.7 1071.3 9991.7 10109.5 10109.7 10124.5 10130.2 4011.5 4124.9 4129.2 4133.9 4169.3 8269.0 8305.9 8324.6 8326.8 8333.0 4584.2 4587.9 4590.5 4594.3 4614.9 4202.8 4238.1 4243.9 4244.0 4247.6 3385.7 3434.6 3479.8 3481.6 3486.9 1059.7 1077.6 1083.7 1090.4 1090.5 905.6 1116.5 1327.5 1363.9 1364.1 7135.3 7178.8 7195.3 7227.9 7233.9 494.9 496.8 503.3 504.0 505.7 5617.7 5640.7 5677.9 5679.1 5698.2 8512.2 8651.4 8658.0 8766.3 8771.7 8881.1 8932.6 8998.7 9006.8 9043.3 6889.3 7031.4 7058.1 7122.3 7122.8 7002.4 7011.9 7032.5 7038.3 7047.8 7861.3 7885.8 7975.6 8051.0 8073.9 8047.9 8094.6 8112.4 8138.8 8141.6 6679.3 6872.5 6890.6 6897.1 6904.2 7321.1 7345.1 7362.5 7366.3 7388.4 6734.3 6895.6 6903.7 7017.2 7049.7 5796.6 6592.7 6662.2 6766.6 6844.8 6369.3 6437.7 6594.0 6619.8 6620.6 6217.0 6242.7 6270.5 6274.2 6275.7 6194.8 6227.7 6230.3 6267.8 6278.4 23:15:02 INFO - 2015-11-24 23:15:02,892 DEBUG : BROWSER_OUTPUT: 23:15:02 INFO - 2015-11-24 23:15:02,892 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5327.53 Median:6111.15 stddev:1612.86 (26.4%) stddev-sans-first:1573.34 23:15:02 INFO - 2015-11-24 23:15:02,892 DEBUG : BROWSER_OUTPUT: Values: 340.1 342.1 349.5 350.6 352.7 5957.6 6042.3 6062.3 6084.1 6110.0 5966.6 5969.6 5974.6 5976.0 5983.3 4427.5 4671.2 4675.8 4675.9 4679.3 4515.0 4528.9 4539.1 4541.2 4546.6 4460.3 4477.6 4482.2 4482.9 4485.5 4360.2 4430.9 4434.7 4468.0 4472.1 3971.9 3984.2 3992.7 4004.9 4009.0 5651.7 5655.5 5664.4 5666.5 5676.4 4981.7 5114.2 5116.2 5141.5 5253.9 6330.2 6338.5 6344.6 6345.4 6346.9 4344.2 4434.2 4435.0 4437.2 4439.3 6413.4 6489.5 6562.9 6566.3 6573.9 4497.1 4502.9 4503.5 4504.0 4505.6 4555.7 4563.7 4568.2 4569.1 4570.3 5270.2 5276.6 5279.3 5279.3 5281.2 6216.8 6371.9 6376.3 6380.4 6387.6 1604.3 1620.9 1621.1 1622.7 1623.9 1083.8 1085.8 1088.1 1093.5 1110.9 6171.9 6176.4 6187.4 6187.9 6191.0 6636.5 6637.3 6646.2 6649.4 6650.6 6030.7 6112.3 6174.1 6179.2 6185.7 3558.0 3584.5 3588.6 3589.8 3591.6 6745.0 6768.9 6772.6 6786.0 6791.3 6521.7 6524.7 6726.9 6736.1 6739.4 6718.1 6769.0 6827.6 6858.9 6862.7 6616.0 6650.8 6655.4 6657.3 6664.2 6380.3 6517.3 6544.5 6546.8 6555.0 6705.0 6750.6 6763.3 6764.3 6846.0 4537.8 4555.1 4556.8 4561.8 4566.3 6480.7 6602.0 6625.2 6626.2 6632.4 6462.5 6465.9 6468.9 6470.0 6472.9 6434.8 6435.9 6440.3 6446.1 6446.9 6187.4 6326.9 6336.1 6342.1 6342.5 6474.4 6477.5 6484.4 6489.4 6505.5 6332.1 6333.4 6339.1 6341.8 6342.1 23:15:02 INFO - 2015-11-24 23:15:02,892 DEBUG : BROWSER_OUTPUT: 23:15:02 INFO - 2015-11-24 23:15:02,892 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13755.49 Median:50232.12 stddev:16091.87 (32.0%) stddev-sans-first:16107.31 23:15:02 INFO - 2015-11-24 23:15:02,892 DEBUG : BROWSER_OUTPUT: Values: 734.8 772.4 802.9 850.5 892.3 15134.2 15430.6 15431.6 15453.5 15495.5 14333.0 14434.6 14468.8 14478.8 14485.9 688.0 700.1 813.5 815.1 817.8 820.0 828.2 833.7 835.0 835.0 869.3 878.0 878.2 878.7 879.8 714.0 725.9 873.8 873.9 896.0 633.7 633.9 638.1 638.4 639.7 13009.3 13235.9 13278.4 13301.5 13302.9 11799.4 12262.4 12435.8 12717.3 13073.6 9439.6 9652.3 10220.7 10426.5 10666.3 3540.5 3607.5 3633.1 3635.9 3678.2 49451.5 49939.3 50557.7 50703.5 50751.2 8662.4 8770.3 8994.9 9047.8 9068.5 18597.0 18612.3 18629.4 18634.4 18646.7 7526.6 7637.6 7754.0 7868.8 8026.8 4400.6 4428.0 4431.2 4467.7 4470.5 3131.5 3161.2 3165.2 3165.9 3172.6 545.6 551.1 557.0 575.8 575.9 517.6 525.9 536.9 538.2 548.3 7896.7 8467.7 8543.7 8579.1 8860.1 551.4 620.8 637.8 642.6 672.9 3608.9 3843.4 4060.6 4141.4 4173.8 56943.0 57255.4 57420.0 57786.4 57946.0 49966.0 50498.2 50905.5 51027.2 51072.7 46361.5 46741.3 46947.8 47221.5 47495.8 8135.4 8265.2 8383.6 8429.6 8581.4 33531.0 34017.1 34200.5 34640.7 35058.8 43176.0 44695.6 44920.0 45201.0 45323.3 10080.0 10155.7 10351.5 10537.1 10754.8 11245.8 11254.2 11308.2 11352.9 11381.4 10045.4 10224.9 10244.6 10351.2 10771.0 10824.7 10991.6 11116.7 11343.1 11460.8 10009.8 10144.2 10196.8 10312.2 10329.0 9053.0 9281.0 9383.3 9439.9 9460.4 8275.9 8698.2 8834.8 8912.7 9311.7 23:15:02 INFO - 2015-11-24 23:15:02,892 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 23:15:02 INFO - 2015-11-24 23:15:02,892 DEBUG : BROWSER_OUTPUT: 23:15:03 INFO - 2015-11-24 23:15:03,537 INFO : Browser exited with error code: 0 23:15:03 INFO - 2015-11-24 23:15:03,553 INFO : Completed test dromaeo_css (00:18:33) 23:15:03 INFO - 2015-11-24 23:15:03,553 INFO : Starting test kraken 23:15:03 INFO - 2015-11-24 23:15:03,554 DEBUG : operating with platform_type : linux_ 23:15:03 INFO - 2015-11-24 23:15:03,554 INFO : Initialising browser for kraken test... 23:15:03 INFO - 2015-11-24 23:15:03,560 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCnTTyT/profile http://localhost:57075/getInfo.html 23:15:07 INFO - 2015-11-24 23:15:07,465 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 23:15:07 INFO - 2015-11-24 23:15:07,465 DEBUG : BROWSER_OUTPUT: colorDepth:24 23:15:07 INFO - 2015-11-24 23:15:07,465 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 23:15:07 INFO - 2015-11-24 23:15:07,465 DEBUG : BROWSER_OUTPUT: __metrics 23:15:07 INFO - 2015-11-24 23:15:07,622 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] 23:15:08 INFO - 2015-11-24 23:15:08,879 INFO : Browser initialized. 23:15:08 INFO - 2015-11-24 23:15:08,879 INFO : Running cycle 1/1 for kraken test... 23:15:08 INFO - 2015-11-24 23:15:08,879 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCnTTyT/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 23:15:20 INFO - 2015-11-24 23:15:20,633 DEBUG : BROWSER_OUTPUT: RSS: Main: 139227136 23:15:20 INFO - 2015-11-24 23:15:20,633 DEBUG : BROWSER_OUTPUT: 23:15:23 INFO - 2015-11-24 23:15:23,096 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57075/tests/kraken/driver.html?testName=audio-beat-detection) 23:15:23 INFO - 2015-11-24 23:15:23,678 DEBUG : BROWSER_OUTPUT: RSS: Main: 175804416 23:15:23 INFO - 2015-11-24 23:15:23,678 DEBUG : BROWSER_OUTPUT: 23:15:26 INFO - 2015-11-24 23:15:26,253 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57075/tests/kraken/driver.html?testName=audio-dft) 23:15:26 INFO - 2015-11-24 23:15:26,818 DEBUG : BROWSER_OUTPUT: RSS: Main: 165662720 23:15:26 INFO - 2015-11-24 23:15:26,819 DEBUG : BROWSER_OUTPUT: 23:15:29 INFO - 2015-11-24 23:15:29,675 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57075/tests/kraken/driver.html?testName=audio-fft) 23:15:30 INFO - 2015-11-24 23:15:30,228 DEBUG : BROWSER_OUTPUT: RSS: Main: 150413312 23:15:30 INFO - 2015-11-24 23:15:30,228 DEBUG : BROWSER_OUTPUT: 23:15:32 INFO - 2015-11-24 23:15:32,200 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57075/tests/kraken/driver.html?testName=audio-oscillator) 23:15:32 INFO - 2015-11-24 23:15:32,762 DEBUG : BROWSER_OUTPUT: RSS: Main: 154296320 23:15:32 INFO - 2015-11-24 23:15:32,763 DEBUG : BROWSER_OUTPUT: 23:15:34 INFO - 2015-11-24 23:15:34,917 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57075/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:15:35 INFO - 2015-11-24 23:15:35,469 DEBUG : BROWSER_OUTPUT: RSS: Main: 154845184 23:15:35 INFO - 2015-11-24 23:15:35,469 DEBUG : BROWSER_OUTPUT: 23:15:38 INFO - 2015-11-24 23:15:38,367 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57075/tests/kraken/driver.html?testName=imaging-darkroom) 23:15:38 INFO - 2015-11-24 23:15:38,933 DEBUG : BROWSER_OUTPUT: RSS: Main: 155885568 23:15:38 INFO - 2015-11-24 23:15:38,933 DEBUG : BROWSER_OUTPUT: 23:15:42 INFO - 2015-11-24 23:15:42,319 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57075/tests/kraken/driver.html?testName=imaging-desaturate) 23:15:42 INFO - 2015-11-24 23:15:42,899 DEBUG : BROWSER_OUTPUT: RSS: Main: 156467200 23:15:42 INFO - 2015-11-24 23:15:42,899 DEBUG : BROWSER_OUTPUT: 23:15:45 INFO - 2015-11-24 23:15:45,644 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57075/tests/kraken/driver.html?testName=json-parse-financial) 23:15:46 INFO - 2015-11-24 23:15:46,217 DEBUG : BROWSER_OUTPUT: RSS: Main: 154869760 23:15:46 INFO - 2015-11-24 23:15:46,217 DEBUG : BROWSER_OUTPUT: 23:15:47 INFO - 2015-11-24 23:15:47,765 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57075/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:15:48 INFO - 2015-11-24 23:15:48,326 DEBUG : BROWSER_OUTPUT: RSS: Main: 166076416 23:15:48 INFO - 2015-11-24 23:15:48,327 DEBUG : BROWSER_OUTPUT: 23:15:50 INFO - 2015-11-24 23:15:50,129 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57075/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:15:50 INFO - 2015-11-24 23:15:50,737 DEBUG : BROWSER_OUTPUT: RSS: Main: 164888576 23:15:50 INFO - 2015-11-24 23:15:50,738 DEBUG : BROWSER_OUTPUT: 23:15:52 INFO - 2015-11-24 23:15:52,773 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57075/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:15:53 INFO - 2015-11-24 23:15:53,346 DEBUG : BROWSER_OUTPUT: RSS: Main: 173682688 23:15:53 INFO - 2015-11-24 23:15:53,347 DEBUG : BROWSER_OUTPUT: 23:15:55 INFO - 2015-11-24 23:15:55,746 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57075/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:15:56 INFO - 2015-11-24 23:15:56,333 DEBUG : BROWSER_OUTPUT: RSS: Main: 180076544 23:15:56 INFO - 2015-11-24 23:15:56,333 DEBUG : BROWSER_OUTPUT: 23:15:58 INFO - 2015-11-24 23:15:58,867 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57075/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:15:59 INFO - 2015-11-24 23:15:59,441 DEBUG : BROWSER_OUTPUT: RSS: Main: 176644096 23:15:59 INFO - 2015-11-24 23:15:59,441 DEBUG : BROWSER_OUTPUT: 23:16:01 INFO - 2015-11-24 23:16:01,109 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57075/tests/kraken/driver.html?testName=ai-astar) 23:16:01 INFO - 2015-11-24 23:16:01,355 DEBUG : BROWSER_OUTPUT: RSS: Main: 235982848 23:16:01 INFO - 2015-11-24 23:16:01,356 DEBUG : BROWSER_OUTPUT: 23:16:01 INFO - 2015-11-24 23:16:01,359 DEBUG : BROWSER_OUTPUT: __start_tp_report 23:16:01 INFO - 2015-11-24 23:16:01,359 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 23:16:01 INFO - 2015-11-24 23:16:01,359 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 23:16:01 INFO - 2015-11-24 23:16:01,359 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 23:16:01 INFO - 2015-11-24 23:16:01,360 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;103;110;109;111;101;109;108;100 23:16:01 INFO - 2015-11-24 23:16:01,360 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;148;133;149;159;130;145;148;126;171;124 23:16:01 INFO - 2015-11-24 23:16:01,360 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;160;161;162;159;158;159;163;160;162 23:16:01 INFO - 2015-11-24 23:16:01,360 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;91;105;71;71;73;92;96;72 23:16:01 INFO - 2015-11-24 23:16:01,360 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;94;141;92;94;92;134;94;93;137 23:16:01 INFO - 2015-11-24 23:16:01,360 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;110;110;110;110;109;109;110;110 23:16:01 INFO - 2015-11-24 23:16:01,360 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;145;145;145;145;146;145;145;145 23:16:01 INFO - 2015-11-24 23:16:01,361 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;95;96;95;96;96;95;96;95 23:16:01 INFO - 2015-11-24 23:16:01,361 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;57;57;56;57;57;57;57;60;55 23:16:01 INFO - 2015-11-24 23:16:01,361 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;71;70;70;70;70;71;71;71;70;71 23:16:01 INFO - 2015-11-24 23:16:01,361 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;83;84;85;85;85;84;84;93;86 23:16:01 INFO - 2015-11-24 23:16:01,361 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;114;118;127;115;124;132;114;115;117;126 23:16:01 INFO - 2015-11-24 23:16:01,361 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;139;140;147;157;137;149;149;147;138 23:16:01 INFO - 2015-11-24 23:16:01,361 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;67;66;65;66;68;66;66;66;65 23:16:01 INFO - 2015-11-24 23:16:01,361 DEBUG : BROWSER_OUTPUT: __end_tp_report 23:16:01 INFO - 2015-11-24 23:16:01,361 DEBUG : BROWSER_OUTPUT: __start_cc_report 23:16:01 INFO - 2015-11-24 23:16:01,361 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,931 23:16:01 INFO - 2015-11-24 23:16:01,361 DEBUG : BROWSER_OUTPUT: __end_cc_report 23:16:01 INFO - 2015-11-24 23:16:01,361 DEBUG : BROWSER_OUTPUT: __startTimestamp1448435761357__endTimestamp 23:16:01 INFO - 2015-11-24 23:16:01,362 DEBUG : BROWSER_OUTPUT: 23:16:01 INFO - 2015-11-24 23:16:01,363 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 23:16:01 INFO - 2015-11-24 23:16:01,363 DEBUG : BROWSER_OUTPUT: Number of tests: 14 23:16:01 INFO - 2015-11-24 23:16:01,363 DEBUG : BROWSER_OUTPUT: 23:16:01 INFO - 2015-11-24 23:16:01,363 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.90 Median:109.00 stddev:4.01 (3.7%) stddev-sans-first:4.24 23:16:01 INFO - 2015-11-24 23:16:01,363 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 103.0 110.0 109.0 111.0 101.0 109.0 108.0 100.0 23:16:01 INFO - 2015-11-24 23:16:01,363 DEBUG : BROWSER_OUTPUT: 23:16:01 INFO - 2015-11-24 23:16:01,363 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.30 Median:148.00 stddev:15.09 (10.2%) stddev-sans-first:15.90 23:16:01 INFO - 2015-11-24 23:16:01,363 DEBUG : BROWSER_OUTPUT: Values: 148.0 133.0 149.0 159.0 130.0 145.0 148.0 126.0 171.0 124.0 23:16:01 INFO - 2015-11-24 23:16:01,363 DEBUG : BROWSER_OUTPUT: 23:16:01 INFO - 2015-11-24 23:16:01,364 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:1.64 (1.0%) stddev-sans-first:1.67 23:16:01 INFO - 2015-11-24 23:16:01,364 DEBUG : BROWSER_OUTPUT: Values: 159.0 160.0 161.0 162.0 159.0 158.0 159.0 163.0 160.0 162.0 23:16:01 INFO - 2015-11-24 23:16:01,364 DEBUG : BROWSER_OUTPUT: 23:16:01 INFO - 2015-11-24 23:16:01,364 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.50 Median:72.50 stddev:13.02 (18.0%) stddev-sans-first:13.35 23:16:01 INFO - 2015-11-24 23:16:01,364 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 91.0 105.0 71.0 71.0 73.0 92.0 96.0 72.0 23:16:01 INFO - 2015-11-24 23:16:01,364 DEBUG : BROWSER_OUTPUT: 23:16:01 INFO - 2015-11-24 23:16:01,364 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.30 Median:92.50 stddev:21.49 (23.2%) stddev-sans-first:22.17 23:16:01 INFO - 2015-11-24 23:16:01,364 DEBUG : BROWSER_OUTPUT: Values: 92.0 94.0 141.0 92.0 94.0 92.0 134.0 94.0 93.0 137.0 23:16:01 INFO - 2015-11-24 23:16:01,364 DEBUG : BROWSER_OUTPUT: 23:16:01 INFO - 2015-11-24 23:16:01,364 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.70 Median:110.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 23:16:01 INFO - 2015-11-24 23:16:01,365 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 110.0 110.0 110.0 110.0 109.0 109.0 110.0 110.0 23:16:01 INFO - 2015-11-24 23:16:01,365 DEBUG : BROWSER_OUTPUT: 23:16:01 INFO - 2015-11-24 23:16:01,366 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.10 Median:145.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 23:16:01 INFO - 2015-11-24 23:16:01,366 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 145.0 145.0 145.0 145.0 146.0 145.0 145.0 145.0 23:16:01 INFO - 2015-11-24 23:16:01,366 DEBUG : BROWSER_OUTPUT: 23:16:01 INFO - 2015-11-24 23:16:01,367 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 23:16:01 INFO - 2015-11-24 23:16:01,367 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 95.0 96.0 95.0 96.0 96.0 95.0 96.0 95.0 23:16:01 INFO - 2015-11-24 23:16:01,367 DEBUG : BROWSER_OUTPUT: 23:16:01 INFO - 2015-11-24 23:16:01,367 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.00 stddev:1.25 (2.2%) stddev-sans-first:1.32 23:16:01 INFO - 2015-11-24 23:16:01,367 DEBUG : BROWSER_OUTPUT: Values: 57.0 57.0 57.0 56.0 57.0 57.0 57.0 57.0 60.0 55.0 23:16:01 INFO - 2015-11-24 23:16:01,368 DEBUG : BROWSER_OUTPUT: 23:16:01 INFO - 2015-11-24 23:16:01,368 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:71.00 stddev:0.53 (0.7%) stddev-sans-first:0.53 23:16:01 INFO - 2015-11-24 23:16:01,368 DEBUG : BROWSER_OUTPUT: Values: 71.0 70.0 70.0 70.0 70.0 71.0 71.0 71.0 70.0 71.0 23:16:01 INFO - 2015-11-24 23:16:01,368 DEBUG : BROWSER_OUTPUT: 23:16:01 INFO - 2015-11-24 23:16:01,368 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:85.00 stddev:2.83 (3.3%) stddev-sans-first:2.96 23:16:01 INFO - 2015-11-24 23:16:01,369 DEBUG : BROWSER_OUTPUT: Values: 84.0 83.0 84.0 85.0 85.0 85.0 84.0 84.0 93.0 86.0 23:16:01 INFO - 2015-11-24 23:16:01,369 DEBUG : BROWSER_OUTPUT: 23:16:01 INFO - 2015-11-24 23:16:01,369 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:120.20 Median:121.00 stddev:6.49 (5.4%) stddev-sans-first:6.49 23:16:01 INFO - 2015-11-24 23:16:01,369 DEBUG : BROWSER_OUTPUT: Values: 114.0 118.0 127.0 115.0 124.0 132.0 114.0 115.0 117.0 126.0 23:16:01 INFO - 2015-11-24 23:16:01,370 DEBUG : BROWSER_OUTPUT: 23:16:01 INFO - 2015-11-24 23:16:01,370 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:144.20 Median:147.00 stddev:6.56 (4.5%) stddev-sans-first:6.69 23:16:01 INFO - 2015-11-24 23:16:01,370 DEBUG : BROWSER_OUTPUT: Values: 139.0 139.0 140.0 147.0 157.0 137.0 149.0 149.0 147.0 138.0 23:16:01 INFO - 2015-11-24 23:16:01,370 DEBUG : BROWSER_OUTPUT: 23:16:01 INFO - 2015-11-24 23:16:01,370 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.00 stddev:0.92 (1.4%) stddev-sans-first:0.93 23:16:01 INFO - 2015-11-24 23:16:01,371 DEBUG : BROWSER_OUTPUT: Values: 67.0 67.0 66.0 65.0 66.0 68.0 66.0 66.0 66.0 65.0 23:16:01 INFO - 2015-11-24 23:16:01,371 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 23:16:01 INFO - 2015-11-24 23:16:01,371 DEBUG : BROWSER_OUTPUT: 23:16:02 INFO - 2015-11-24 23:16:02,201 INFO : Browser exited with error code: 0 23:16:02 INFO - 2015-11-24 23:16:02,252 INFO : Completed test kraken (00:00:58) 23:16:02 INFO - 2015-11-24 23:16:02,252 INFO : Starting test v8_7 23:16:02 INFO - 2015-11-24 23:16:02,252 DEBUG : operating with platform_type : linux_ 23:16:02 INFO - 2015-11-24 23:16:02,252 INFO : Initialising browser for v8_7 test... 23:16:02 INFO - 2015-11-24 23:16:02,258 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpYBeDUG/profile http://localhost:57075/getInfo.html 23:16:05 INFO - 2015-11-24 23:16:05,990 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 23:16:05 INFO - 2015-11-24 23:16:05,990 DEBUG : BROWSER_OUTPUT: colorDepth:24 23:16:05 INFO - 2015-11-24 23:16:05,990 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 23:16:05 INFO - 2015-11-24 23:16:05,990 DEBUG : BROWSER_OUTPUT: __metrics 23:16:06 INFO - 2015-11-24 23:16:06,139 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] 23:16:07 INFO - 2015-11-24 23:16:07,377 INFO : Browser initialized. 23:16:07 INFO - 2015-11-24 23:16:07,377 INFO : Running cycle 1/1 for v8_7 test... 23:16:07 INFO - 2015-11-24 23:16:07,378 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpYBeDUG/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 23:16:19 INFO - 2015-11-24 23:16:19,056 DEBUG : BROWSER_OUTPUT: RSS: Main: 139079680 23:16:19 INFO - 2015-11-24 23:16:19,057 DEBUG : BROWSER_OUTPUT: 23:16:40 INFO - 2015-11-24 23:16:40,516 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57075/tests/v8_7/run.html (next: http://localhost:57075/tests/v8_7/run.html) 23:16:40 INFO - 2015-11-24 23:16:40,766 DEBUG : BROWSER_OUTPUT: RSS: Main: 620797952 23:16:40 INFO - 2015-11-24 23:16:40,766 DEBUG : BROWSER_OUTPUT: 23:16:40 INFO - 2015-11-24 23:16:40,767 DEBUG : BROWSER_OUTPUT: __start_tp_report 23:16:40 INFO - 2015-11-24 23:16:40,767 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 23:16:40 INFO - 2015-11-24 23:16:40,767 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 23:16:40 INFO - 2015-11-24 23:16:40,767 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 23:16:40 INFO - 2015-11-24 23:16:40,767 DEBUG : BROWSER_OUTPUT: |0;Richards;164.446637066272 23:16:40 INFO - 2015-11-24 23:16:40,767 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;194.62826002335538 23:16:40 INFO - 2015-11-24 23:16:40,767 DEBUG : BROWSER_OUTPUT: |2;Encrypt;286.368843069874 23:16:40 INFO - 2015-11-24 23:16:40,767 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5181.347150259067 23:16:40 INFO - 2015-11-24 23:16:40,767 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1084.5986984815618 23:16:40 INFO - 2015-11-24 23:16:40,767 DEBUG : BROWSER_OUTPUT: |5;Earley;793.1854199683042 23:16:40 INFO - 2015-11-24 23:16:40,767 DEBUG : BROWSER_OUTPUT: |6;Boyer;12047.619047619048 23:16:40 INFO - 2015-11-24 23:16:40,767 DEBUG : BROWSER_OUTPUT: |7;RegExp;25450 23:16:40 INFO - 2015-11-24 23:16:40,768 DEBUG : BROWSER_OUTPUT: |8;Splay;650.3541532517708 23:16:40 INFO - 2015-11-24 23:16:40,768 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 23:16:40 INFO - 2015-11-24 23:16:40,768 DEBUG : BROWSER_OUTPUT: __end_tp_report 23:16:40 INFO - 2015-11-24 23:16:40,768 DEBUG : BROWSER_OUTPUT: __startTimestamp1448435800766__endTimestamp 23:16:40 INFO - 2015-11-24 23:16:40,768 DEBUG : BROWSER_OUTPUT: 23:16:40 INFO - 2015-11-24 23:16:40,768 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 23:16:40 INFO - 2015-11-24 23:16:40,768 DEBUG : BROWSER_OUTPUT: Number of tests: 10 23:16:40 INFO - 2015-11-24 23:16:40,768 DEBUG : BROWSER_OUTPUT: 23:16:40 INFO - 2015-11-24 23:16:40,768 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.45 Median:164.45 stddev:0.00 (0.0%) 23:16:40 INFO - 2015-11-24 23:16:40,768 DEBUG : BROWSER_OUTPUT: Values: 164.4 23:16:40 INFO - 2015-11-24 23:16:40,768 DEBUG : BROWSER_OUTPUT: 23:16:40 INFO - 2015-11-24 23:16:40,769 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:194.63 Median:194.63 stddev:0.00 (0.0%) 23:16:40 INFO - 2015-11-24 23:16:40,769 DEBUG : BROWSER_OUTPUT: Values: 194.6 23:16:40 INFO - 2015-11-24 23:16:40,769 DEBUG : BROWSER_OUTPUT: 23:16:40 INFO - 2015-11-24 23:16:40,769 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:286.37 Median:286.37 stddev:0.00 (0.0%) 23:16:40 INFO - 2015-11-24 23:16:40,769 DEBUG : BROWSER_OUTPUT: Values: 286.4 23:16:40 INFO - 2015-11-24 23:16:40,769 DEBUG : BROWSER_OUTPUT: 23:16:40 INFO - 2015-11-24 23:16:40,770 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5181.35 Median:5181.35 stddev:0.00 (0.0%) 23:16:40 INFO - 2015-11-24 23:16:40,771 DEBUG : BROWSER_OUTPUT: Values: 5181.3 23:16:40 INFO - 2015-11-24 23:16:40,771 DEBUG : BROWSER_OUTPUT: 23:16:40 INFO - 2015-11-24 23:16:40,771 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1084.60 Median:1084.60 stddev:0.00 (0.0%) 23:16:40 INFO - 2015-11-24 23:16:40,771 DEBUG : BROWSER_OUTPUT: Values: 1084.6 23:16:40 INFO - 2015-11-24 23:16:40,771 DEBUG : BROWSER_OUTPUT: 23:16:40 INFO - 2015-11-24 23:16:40,771 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:793.19 Median:793.19 stddev:0.00 (0.0%) 23:16:40 INFO - 2015-11-24 23:16:40,771 DEBUG : BROWSER_OUTPUT: Values: 793.2 23:16:40 INFO - 2015-11-24 23:16:40,772 DEBUG : BROWSER_OUTPUT: 23:16:40 INFO - 2015-11-24 23:16:40,772 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12047.62 Median:12047.62 stddev:0.00 (0.0%) 23:16:40 INFO - 2015-11-24 23:16:40,772 DEBUG : BROWSER_OUTPUT: Values: 12047.6 23:16:40 INFO - 2015-11-24 23:16:40,772 DEBUG : BROWSER_OUTPUT: 23:16:40 INFO - 2015-11-24 23:16:40,772 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25450.00 Median:25450.00 stddev:0.00 (0.0%) 23:16:40 INFO - 2015-11-24 23:16:40,772 DEBUG : BROWSER_OUTPUT: Values: 25450.0 23:16:40 INFO - 2015-11-24 23:16:40,772 DEBUG : BROWSER_OUTPUT: 23:16:40 INFO - 2015-11-24 23:16:40,772 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:650.35 Median:650.35 stddev:0.00 (0.0%) 23:16:40 INFO - 2015-11-24 23:16:40,773 DEBUG : BROWSER_OUTPUT: Values: 650.4 23:16:40 INFO - 2015-11-24 23:16:40,773 DEBUG : BROWSER_OUTPUT: 23:16:40 INFO - 2015-11-24 23:16:40,773 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 23:16:40 INFO - 2015-11-24 23:16:40,773 DEBUG : BROWSER_OUTPUT: Values: 4835.7 23:16:40 INFO - 2015-11-24 23:16:40,773 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 23:16:40 INFO - 2015-11-24 23:16:40,773 DEBUG : BROWSER_OUTPUT: 23:16:41 INFO - 2015-11-24 23:16:41,593 INFO : Browser exited with error code: 0 23:16:41 INFO - 2015-11-24 23:16:41,612 INFO : Completed test v8_7 (00:00:39) 23:16:41 INFO - 2015-11-24 23:16:41,717 INFO : Completed test suite (00:20:11) 23:16:41 INFO - 2015-11-24 23:16:41,717 DEBUG : Working with test: dromaeo_css 23:16:41 INFO - 2015-11-24 23:16:41,717 DEBUG : Generating results file: dromaeo_css 23:16:41 INFO - 2015-11-24 23:16:41,718 DEBUG : Working with test: kraken 23:16:41 INFO - 2015-11-24 23:16:41,718 DEBUG : Generating results file: kraken 23:16:41 INFO - 2015-11-24 23:16:41,719 INFO : javascript benchmark 23:16:41 INFO - 2015-11-24 23:16:41,719 DEBUG : Working with test: v8_7 23:16:41 INFO - 2015-11-24 23:16:41,719 DEBUG : Generating results file: v8_7 23:16:41 INFO - 2015-11-24 23:16:41,719 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:16:41 INFO - 2015-11-24 23:16:41,770 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,201,35]] 23:16:41 INFO - 2015-11-24 23:16:41,770 DEBUG : process_Request line: dromaeo_css 6956.67 graph.html#tests=[[72,201,35]] 23:16:41 INFO - 2015-11-24 23:16:41,770 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:16:41 INFO - 2015-11-24 23:16:41,901 DEBUG : process_Request line: kraken 1492.00 graph.html#tests=[[232,201,35]] 23:16:41 INFO - 2015-11-24 23:16:41,902 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:16:41 INFO - 2015-11-24 23:16:41,937 DEBUG : process_Request line: v8_7 20888.23 graph.html#tests=[[230,201,35]] 23:16:41 INFO - 2015-11-24 23:16:41,941 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3219.951700724489, 3263.2820779428516, 3301.0261972718044, 3304.9504257436142, 3321.219513414347, 5326.334208223972, 5411.053065713494, 5485.890282194358, 5501.449855014496, 5528.861778455541, 5439.3200849893765, 5462.644928079672, 5507.504324610784, 5508.9173662395, 5594.216809646642, 4976.402831660197, 4993.300937868701, 5031.647784655075, 5038.7984480620735, 5053.873653158664, 4951.331570238026, 5118.232265160237, 5158.226266060102, 5174.43081261061, 5255.316808814849, 4976.253561965698, 5004.074246264453, 5082.847514574569, 5084.46613105626, 5107.565856902168, 5020.196768517057, 5041.369828771403, 5053.065182941167, 5071.239314102877, 5091.0072535855525, 4813.470518242991, 4918.04098200851, 5025.145725226684, 5026.29631851541, 5039.974800126012, 5336.626436149471, 5394.487523685244, 5409.675419474805, 5413.350397952271, 5438.53772429344, 5990.101484777319, 5995.6702381369005, 5999.940000599981, 6000.279966404016, 6006.819795406145, 5797.623154494944, 5984.670843103628, 6018.127371531147, 6051.636901785907, 6067.029275315928, 5674.858128546819, 5679.971600141973, 5684.573656975744, 5687.60186786921, 5724.284464441945, 6146.938530614637, 6151.2618485781095, 6160.7535698571555, 6162.383761623802, 6163.291221509494, 5582.692951887685, 5654.321481422174, 5661.603687741901, 5784.797532086357, 5945.554083443672, 5937.614055086406, 5988.880222395528, 5990.251218597676, 6011.879762404727, 6042.728077236535, 5917.911231331534, 5975.79084732041, 5982.0, 5987.491063259585, 5987.64074155552, 5594.888102238014, 5662.462066103713, 5685.545156387479, 5997.610155339889, 6003.7898673546215, 4523.457185137804, 4547.454305483363, 4554.203014472454, 4581.931271030937, 4583.954160458419, 4071.206114807643, 4076.3477843544892, 4083.1425400666194, 4087.5912408758886, 4114.074333274989, 5392.433794451605, 5493.862653433616, 5508.476694713996, 5626.634268772516, 5634.239377684042, 5993.670348130895, 6001.309849367379, 6004.519638428916, 6008.759649613967, 6062.605930614496, 5532.6680399176175, 5623.353314368818, 5631.183478395692, 5639.492445679908, 5647.49172574462, 4870.269459581091, 5003.724795136302, 5053.772580233898, 5149.382074151126, 5166.095933211673, 6131.294901086343, 6195.411435913581, 6196.938030619636, 6197.163382943245, 6197.4732147767045, 5972.104184372379, 6016.699165041818, 6029.78895738647, 6033.12519684652, 6041.728122234598, 5809.2738407699035, 5948.613340132878, 5948.762049517971, 6004.459598636144, 6094.2652418614325, 5832.883342333214, 5880.558958078076, 5920.911186332122, 5923.792667256797, 5928.881422371443, 5972.313183983897, 5996.2504686914135, 6000.639961602318, 6001.549883758648, 6018.337982821974, 5568.470995255444, 5571.470710282679, 5631.436856314336, 5632.718364081861, 5636.323641162922, 5667.149927510742, 5706.3152421709665, 5717.285339332583, 5719.0, 5719.5138413235345, 5600.411956744646, 5643.830685079454, 5663.773449061991, 5664.37691853904, 5666.405027472056, 5820.970895145497, 5865.3254875689845, 5893.381194974638, 5916.911246331222, 5929.466348028698, 5763.221965034834, 5785.739641716217, 5789.594728368974, 5799.06740144139, 5829.183914251923], "unit": "ms", "name": "dojo.html", "value": 5465.609883330973}, {"lowerIsBetter": false, "replicates": [552.1795162637137, 585.765693722511, 588.4644973074502, 602.3073465514655, 621.962682239066, 31080.378392432136, 40193.59806401935, 40731.18537629253, 40856.79571602138, 40921.386179207264, 42991.0, 43535.0, 43563.346549801776, 43700.78149609247, 43727.56272437274, 7703.0, 7804.375649948019, 8811.118888111201, 9015.413998090104, 10282.691519254397, 10475.161987041056, 10498.737531561696, 10596.205284603648, 10603.310784799001, 10606.098481629071, 10627.0, 10716.464176791129, 10728.570857165703, 10729.463526823627, 10729.517171727291, 7839.25527074927, 8054.355651547891, 10729.08802751767, 10988.890111098866, 10989.780204395947, 10304.587816487332, 10353.74115647115, 10480.528376223088, 10480.94759526205, 10484.947575262151, 40326.59673403258, 40619.7969010153, 40902.795486022675, 41111.383329250086, 41240.0, 3363.8822641207685, 3480.042988178246, 3522.0, 3722.4230244311902, 3933.272344616255, 30371.848140759375, 30651.846740766152, 30886.22784430385, 31086.844565777254, 31642.841785791155, 3637.181634132299, 3644.3075815595216, 3645.3620616392027, 3756.7745935243975, 3838.9232215355537, 265462.0, 265851.0, 267460.0, 267763.0, 268249.0, 51764.0, 53143.73428132835, 58599.70700146472, 60697.69651151716, 61308.0, 60954.69522652358, 61143.388566114656, 61336.69331653402, 61369.69315153485, 61506.692466538276, 15745.370185192463, 15854.128022958848, 16003.439879604159, 16016.75974860378, 16047.919760401122, 11864.703382415508, 11958.64124076279, 11960.760784784256, 11962.102842286864, 12103.455344509519, 19920.900395498218, 20095.497612559804, 20229.898850505946, 20273.69589456159, 20276.594468110554, 7411.407087432993, 7633.465657404039, 7704.961475192588, 7714.0, 7729.652165652559, 913.8263729891299, 978.7308490165261, 985.2905907746555, 1035.546452580652, 1107.3300653104916, 28305.0, 28341.0, 28734.425311493655, 28846.567301490493, 28854.27864303367, 1029.4955471818755, 1034.1364960258268, 1109.2290857853875, 1133.348324713293, 1200.7058270723728, 13462.394192261372, 13657.317134143452, 15323.61690957735, 15642.011287121712, 15964.92017539905, 305449.0, 309957.0, 310741.0, 310959.0, 311837.0, 52620.473795261554, 52820.0, 52889.206661900105, 52936.47063529315, 53035.20447193295, 1357.043284484436, 1362.2303398579968, 1388.5070799866105, 1389.7498450279047, 1396.2599822094305, 23218.535629287657, 27975.300617484725, 28956.131316060553, 29440.852795735886, 29514.262143446587, 43370.566294336655, 43753.78123109364, 43773.24907003684, 43784.56215437805, 43822.12355752931, 213635.0, 215180.0, 215202.0, 215470.0, 215578.0, 27539.0, 28290.0, 28574.0, 31452.842735786173, 32006.839965800023, 37603.43594846025, 38892.80553597214, 39255.0, 42509.57490425056, 42892.78553607212, 35798.8210058948, 35976.460353095244, 36183.09542261464, 36193.63806362008, 37190.0, 28818.567721484615, 35965.64034359623, 36259.637403625624, 36260.45609315809, 36276.377161540004, 30163.396732065674, 30182.84908575443, 35066.47400288947, 35399.11502212465, 35823.6264560318, 28229.306241625567, 30287.0, 31777.682223178395, 31854.522182167748, 32044.679553205104, 24562.0, 24781.50436991214, 24991.02011494276, 25053.624195637443, 25268.736563172137], "unit": "ms", "name": "ext.html", "value": 18585.57224373665}, {"lowerIsBetter": false, "replicates": [357.8210894552724, 358.54106743368465, 360.9296187243487, 360.9819509024548, 363.03613905082, 13555.932220338884, 13599.028140919585, 16446.42437514687, 16654.666906661874, 17220.65558688827, 14812.851871481282, 15783.131927743969, 16192.75710864335, 16209.594760131002, 16216.675666486663, 5214.504622060907, 5369.979703856275, 5371.113766228567, 5378.973105134469, 5379.193121031857, 3802.3345914464994, 3967.3453880109746, 5311.654742441729, 5330.786768529274, 5801.129830525413, 5891.587588868781, 5900.586958912878, 5911.46796788289, 5919.112133180014, 5953.910691339634, 5782.884342313151, 5896.528277737771, 5899.0, 5920.348761636238, 6014.909776353336, 5193.143131383317, 5211.583073354161, 5229.921551176735, 5230.555402790767, 5234.031704134747, 19700.802991970184, 20206.0, 20214.292499762436, 20230.089645965967, 20516.794832051637, 2170.066871245364, 2281.7376001759844, 2331.6502524621274, 2449.9877500612383, 2453.939087602459, 13144.14563053408, 13196.406161722649, 13293.135946163566, 13382.0, 13444.126131801402, 1740.4865564658455, 1828.4331857124334, 1842.9631407371778, 1870.326682394337, 1880.6802843516498, 57428.71285643587, 59780.701096494675, 60100.0, 60265.39734602642, 60350.698246508924, 9600.32797704154, 9742.366746161477, 9778.266630002636, 9809.607615695293, 9937.10566049059, 12731.363431828371, 12751.936240318739, 12772.169808962388, 12798.424070916832, 12799.936000319938, 7218.061651985313, 7246.0, 7461.962690186514, 7508.3993280537425, 7538.944547763317, 6932.826679333057, 6946.16646002483, 7315.122185337794, 7384.483086184023, 7401.851962960711, 5867.266591676041, 5901.29184497863, 5958.136070269873, 5987.730552125165, 6078.270607527125, 1650.7853978982896, 1718.407149533409, 1728.3864228198809, 1754.5964428181335, 1781.153951872876, 950.2160717408166, 969.3795970578835, 983.7737320416344, 989.6585677941099, 1004.4425343934041, 13141.211527308393, 14186.304836194953, 17718.46844594664, 17965.0, 18334.266629334677, 897.946123232595, 906.6735975048977, 917.0600134861687, 950.6292545907102, 975.2929126383457, 9264.11990860867, 9457.905420945703, 9463.0, 9553.474558899326, 9654.951725241328, 399889.0, 410468.0, 410685.0, 410951.0, 548948.0, 73066.63466682633, 77627.0, 78251.21748782553, 78252.21747782563, 78379.60810195912, 1114.6098865397046, 1137.5449820072038, 1149.448264832885, 1150.0454622663337, 1152.5217034930577, 15085.698286034438, 15249.542513724606, 15426.382944682087, 15452.381904723685, 15756.76364854551, 34724.30551389009, 34945.82527087348, 34972.30055398827, 35146.47280290747, 35252.118697032776, 43739.34390984201, 44299.82719181641, 45525.0, 45873.0, 48437.27344089771, 9420.811583768149, 9467.526623668928, 9625.229981601595, 9638.084381983701, 9646.119006793888, 11479.196456247982, 11493.367864767184, 11522.481488332878, 11585.304881707125, 11635.709107272385, 12905.741885162432, 14174.07868488545, 14215.857841421866, 14328.785068224193, 14471.204083775494, 13809.240491772647, 14049.227292498601, 14201.786973195616, 14470.565883023526, 14542.127472351693, 11538.82691759641, 11893.702657433634, 13848.930755346562, 14540.49108281205, 14744.410223590936, 8503.8724419135, 8854.955725221333, 9444.763880903032, 9446.29152813528, 9460.716178514656, 9936.552855121392, 12895.871041289467, 13786.862131378557, 14104.78842817338, 14385.496507622183], "unit": "ms", "name": "jquery.html", "value": 8774.831550128361}, {"lowerIsBetter": false, "replicates": [1319.8944084473244, 1463.926803659817, 1521.5587479630908, 1654.4871089962112, 1765.8499027582654, 6713.496487763419, 7315.268473152682, 7483.73806916758, 7496.287852653996, 7510.962445187766, 7288.927110728891, 7470.439717021218, 7478.887816682754, 7491.625418729055, 7495.362894154004, 1953.8632295739303, 1989.4926793667667, 1990.6516359637078, 2198.824094072478, 2227.465408302012, 2275.351524815428, 2326.697529321186, 2327.208749025331, 2329.6389059695775, 2329.6971393718877, 2215.523662412581, 2224.7552769195377, 2287.9084836606517, 2288.382136823057, 2290.9312720618323, 1960.4706729183113, 2009.7688765791902, 2180.226019762993, 2234.0393630738777, 2261.8642881427004, 2254.616715158427, 2256.6953461282847, 2271.2958982715413, 2284.8743319117443, 2286.4855407533337, 7481.7007319707145, 7549.0563679540055, 7558.281963213486, 7561.319481246659, 7561.697532098709, 4539.977300113478, 4601.654875884322, 4638.605718513897, 4663.48701642819, 4665.183592871234, 3076.7230949214677, 3128.8592013359453, 3168.6197656281165, 3170.7621928355466, 3189.1389324882152, 996.9252306077074, 1009.439760932682, 1016.2682868334787, 1032.7160030991463, 1071.3357718214756, 9991.700248992542, 10109.494525273707, 10109.696709098738, 10124.493775311279, 10130.189584833195, 4011.498562679665, 4124.876253712333, 4129.194807012604, 4133.8966525837095, 4169.332906734908, 8269.0, 8305.875411868827, 8324.583770811436, 8326.750197494084, 8333.0, 4584.174848527297, 4587.885302867388, 4590.540945905383, 4594.287885377772, 4614.884627884263, 4202.789860506962, 4238.0888109056705, 4243.872683819491, 4243.978780106079, 4247.617714405656, 3385.7291416686608, 3434.587849458081, 3479.8260086995547, 3481.5822101347494, 3486.8605255789994, 1059.682095371401, 1077.6174458067273, 1083.712816103733, 1090.3566895531517, 1090.5310716392028, 905.5879574793453, 1116.5143162724241, 1327.5419980106847, 1363.8567950365268, 1364.0860623382357, 7135.322144396274, 7178.78463646081, 7195.316444937723, 7227.89158162628, 7233.89149162763, 494.8515445366448, 496.8186611886708, 503.3129777853277, 504.0448350376017, 505.7496539213113, 5617.747201375948, 5640.689762063043, 5677.943220567741, 5679.091345384719, 5698.202251684767, 8512.233898949, 8651.437656552303, 8658.04761476237, 8766.254868336135, 8771.692990745294, 8881.111888811067, 8932.553372331358, 8998.685046023358, 9006.774830629287, 9043.276537876955, 6889.311068893071, 7031.40233080194, 7058.082449281561, 7122.252163522756, 7122.786316410516, 7002.404795592432, 7011.929880701127, 7032.5077244592385, 7038.331358520933, 7047.823804404931, 7861.331786797958, 7885.84228315419, 7975.641096150571, 8050.959745201237, 8073.878891816745, 8047.87928181066, 8094.635741391534, 8112.391570632344, 8138.796530086795, 8141.633626486941, 6679.332066793282, 6872.518923675295, 6890.621015844176, 6897.137857767779, 6904.171499420102, 7321.084864391951, 7345.118585769742, 7362.5214361066355, 7366.263373662591, 7388.371988381048, 6734.259231484631, 6895.620740859301, 6903.689333980084, 7017.228104908354, 7049.718011279491, 5796.62321949072, 6592.736290548324, 6662.233843107911, 6766.560173588701, 6844.828879278058, 6369.299377068612, 6437.7103030362805, 6594.043863639841, 6619.801405957829, 6620.569662971891, 6216.968915155395, 6242.65665388408, 6270.49836013127, 6274.152989346379, 6275.748970041147, 6194.783182588588, 6227.719752611234, 6230.283517395376, 6267.811965641038, 6278.4349408551625], "unit": "ms", "name": "mootools.html", "value": 4257.73091307945}, {"lowerIsBetter": false, "replicates": [340.1343580587405, 342.06786506769055, 349.4531058892831, 350.56880037553816, 352.71782573940857, 5957.582969192158, 6042.305134909487, 6062.302835173957, 6084.087386891968, 6109.9999999999945, 5966.552508561854, 5969.552283578738, 5974.641521508713, 5976.0, 5983.341832398432, 4427.512973572904, 4671.22924717422, 4675.812967481296, 4675.906481870361, 4679.344891715163, 4514.9774251128865, 4528.864134075967, 4539.137563862872, 4541.182587134314, 4546.590806827385, 4460.264056430701, 4477.619402350804, 4482.193205223075, 4482.932756008662, 4485.461744590653, 4360.193364227628, 4430.933535996963, 4434.68957172998, 4467.972366355756, 4472.127935052666, 3971.8609848655447, 3984.2230765000836, 3992.680585553149, 4004.899877503057, 4008.979955100235, 5651.745671444794, 5655.5475561954945, 5664.433556644344, 5666.546676265889, 5676.375598684142, 4981.701097934099, 5114.207297868837, 5116.155834287338, 5141.460146684582, 5253.894922101536, 6330.177076979963, 6338.461230795388, 6344.619322840645, 6345.428911397997, 6346.873062538723, 4344.152890186415, 4434.224010798098, 4434.977825110886, 4437.223485889988, 4439.311906654474, 6413.358664133596, 6489.480841532666, 6562.868742625121, 6566.277709452004, 6573.868522629568, 4497.123061003138, 4502.909941801146, 4503.482099558592, 4504.031633198878, 4505.639548836085, 4555.726656400627, 4563.680542362069, 4568.200564901129, 4569.109023740339, 4570.291604801261, 5270.235815806686, 5276.551493123051, 5279.260903473517, 5279.260903473517, 5281.181416880389, 6216.813495595139, 6371.904421433682, 6376.266729326094, 6380.393862583047, 6387.616742995435, 1604.2860926887656, 1620.9108499032432, 1621.1407953784512, 1622.7160246956735, 1623.9431619893483, 1083.7507373304184, 1085.8045551800594, 1088.1349327284827, 1093.475131936659, 1110.8555887734542, 6171.938280617226, 6176.413240742122, 6187.3503282154725, 6187.938120618826, 6190.969045154746, 6636.502262330248, 6637.336266373324, 6646.202455705346, 6649.4347980421235, 6650.600963942179, 6030.668313242726, 6112.297085835097, 6174.135621013062, 6179.196704428486, 6185.659788711574, 3558.0, 3584.4981702561663, 3588.6052534221217, 3589.766665166756, 3591.569011718558, 6744.966275168691, 6768.864622707617, 6772.627505487147, 6786.0, 6791.252962174157, 6521.739130434825, 6524.706388212542, 6726.932730672728, 6736.05695202672, 6739.427148692318, 6718.0930574372815, 6768.9661551691925, 6827.624480653512, 6858.862822743617, 6862.656867156622, 6616.0, 6650.767223147167, 6655.367740064686, 6657.3009833966735, 6664.233613134552, 6380.298167201511, 6517.315681853527, 6544.4764418845425, 6546.803595892131, 6555.049517819984, 6704.966475167593, 6750.628715420699, 6763.3236676333945, 6764.289749576224, 6845.965770171118, 4537.8184872604725, 4555.088982203506, 4556.772161391984, 4561.794719237576, 4566.292224705176, 6480.708368123352, 6601.9669901652105, 6625.238097618835, 6626.171728533934, 6632.436242919213, 6462.515311351762, 6465.870682586228, 6468.902966455413, 6470.029495575702, 6472.902906456501, 6434.806955791334, 6435.871282574416, 6440.25937017249, 6446.129772480655, 6446.871062578628, 6187.443130118311, 6326.936730632635, 6336.081268216175, 6342.080398342307, 6342.460890824332, 6474.417302442803, 6477.546571739933, 6484.351564843478, 6489.3510648934725, 6505.479561634963, 6332.081848131903, 6333.398327158912, 6339.144215530844, 6341.841453963688, 6342.112104305494], "unit": "ms", "name": "prototype.html", "value": 4807.747989779969}, {"lowerIsBetter": false, "replicates": [734.7869117955793, 772.4013889235844, 802.8956235689361, 850.4982060584254, 892.2549671024694, 15134.243287835605, 15430.608255720354, 15431.614209644737, 15453.459128930504, 15495.457658981937, 14333.0, 14434.5669629911, 14468.782968255484, 14478.782818257709, 14485.855141448583, 687.9759208427706, 700.1108592088045, 813.5403497024178, 815.1115284340068, 817.8282560662268, 820.0487434575894, 828.2297463359074, 833.6832003838533, 834.9833003339929, 835.0105125426359, 869.2611280411663, 878.0341624213346, 878.2271600991119, 878.661715239631, 879.8416285068685, 713.9964300178492, 725.891116332549, 873.8033942362981, 873.9082396348388, 895.9820803583892, 633.6578247746212, 633.8573820890309, 638.119395234578, 638.4062821575933, 639.7217210513395, 13009.349532523336, 13235.941124710096, 13278.402471888692, 13301.467941282337, 13302.933485332609, 11799.410029498576, 12262.386880655931, 12435.813462798065, 12717.30054846983, 13073.607791766262, 9439.622415103388, 9652.324337296392, 10220.693379198561, 10426.478676066166, 10666.253362264644, 3540.486629438717, 3607.512985746917, 3633.128049268183, 3635.94546081809, 3678.1724112074835, 49451.505484945046, 49939.25091123636, 50557.747211264075, 50703.49296507025, 50751.23873141906, 8662.436941598777, 8770.298376129895, 8994.86507702385, 9047.81904361909, 9068.455892646462, 18597.0, 18612.348567800334, 18629.44111676652, 18634.440966771017, 18646.720299195524, 7526.623668816537, 7637.579933103621, 7754.0, 7868.763937081897, 8026.7591972240925, 4400.559944005574, 4428.047969686533, 4431.158079964796, 4467.7319360838455, 4470.530594287553, 3131.5146152346424, 3161.1780936956557, 3165.1612322734495, 3165.936681266408, 3172.5558421820965, 545.5935328180451, 551.1099574187658, 556.9749361278753, 575.7955925646419, 575.8733078722674, 517.5755880178218, 525.858018335055, 536.8738346488572, 538.163156291962, 548.3447280499764, 7896.68413263463, 8467.661293548312, 8543.658253669908, 8579.14208579137, 8860.113988601217, 551.4032569420415, 620.8013435700623, 637.7544645311589, 642.5566359212129, 672.9226138994078, 3608.8736894208578, 3843.385058390644, 4060.614241647039, 4141.399497072907, 4173.83304667816, 56943.0, 57255.42744572501, 57420.0, 57786.42213577894, 57946.0, 49966.0, 50498.2425263614, 50905.490945090816, 51027.23459148116, 51072.744636276584, 46361.5363846364, 46741.29888051682, 46947.765261173474, 47221.5277847224, 47495.76252118786, 8135.389845761473, 8265.214804593554, 8383.62273697673, 8429.578521074045, 8581.399302049047, 33530.994070177934, 34017.14957125993, 34200.48699269563, 34640.653593464755, 35058.82470587631, 43176.0, 44695.55304447044, 44920.0, 45201.0, 45323.32015019843, 10080.0, 10155.7461063474, 10351.482425878525, 10537.104346130665, 10754.784904302027, 11245.775084498428, 11254.212205145888, 11308.151888608552, 11352.886471135183, 11381.43092845371, 10045.447500387256, 10224.897751022394, 10244.59021639156, 10351.223658225512, 10771.0, 10824.729381765519, 10991.61529346469, 11116.722081947693, 11343.092552595608, 11460.82808757886, 10009.849852252077, 10144.188464922936, 10196.847047294148, 10312.226583006455, 10329.0, 9053.004793408063, 9281.0, 9383.296252780932, 9439.905600944177, 9460.432374057578, 8275.917240827515, 8698.21716045559, 8834.779130521789, 8912.73261802147, 9311.674091406769], "unit": "ms", "name": "yui.html", "value": 6211.494710716544}], "name": "dromaeo_css"}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 103.0, 110.0, 109.0, 111.0, 101.0, 109.0, 108.0, 100.0], "unit": "ms", "name": "ai-astar", "value": 106.9}, {"lowerIsBetter": true, "replicates": [148.0, 133.0, 149.0, 159.0, 130.0, 145.0, 148.0, 126.0, 171.0, 124.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.3}, {"lowerIsBetter": true, "replicates": [159.0, 160.0, 161.0, 162.0, 159.0, 158.0, 159.0, 163.0, 160.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 160.3}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 91.0, 105.0, 71.0, 71.0, 73.0, 92.0, 96.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 81.5}, {"lowerIsBetter": true, "replicates": [92.0, 94.0, 141.0, 92.0, 94.0, 92.0, 134.0, 94.0, 93.0, 137.0], "unit": "ms", "name": "audio-oscillator", "value": 106.3}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0, 109.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.7}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.1}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [57.0, 57.0, 57.0, 56.0, 57.0, 57.0, 57.0, 57.0, 60.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 57.0}, {"lowerIsBetter": true, "replicates": [71.0, 70.0, 70.0, 70.0, 70.0, 71.0, 71.0, 71.0, 70.0, 71.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 70.5}, {"lowerIsBetter": true, "replicates": [84.0, 83.0, 84.0, 85.0, 85.0, 85.0, 84.0, 84.0, 93.0, 86.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.3}, {"lowerIsBetter": true, "replicates": [114.0, 118.0, 127.0, 115.0, 124.0, 132.0, 114.0, 115.0, 117.0, 126.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 120.2}, {"lowerIsBetter": true, "replicates": [139.0, 139.0, 140.0, 147.0, 157.0, 137.0, 149.0, 149.0, 147.0, 138.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 144.2}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 66.0, 65.0, 66.0, 68.0, 66.0, 66.0, 66.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.2}], "name": "kraken"}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.446637066272], "unit": "score", "name": "Richards", "value": 214.67146200000008}, {"lowerIsBetter": false, "replicates": [194.62826002335538], "unit": "score", "name": "DeltaBlue", "value": 339.714284}, {"lowerIsBetter": false, "replicates": [286.368843069874], "unit": "score", "name": "Encrypt", "value": 929.5040519999998}, {"lowerIsBetter": false, "replicates": [5181.347150259067], "unit": "score", "name": "Decrypt", "value": 51.372933}, {"lowerIsBetter": false, "replicates": [1084.5986984815618], "unit": "score", "name": "RayTrace", "value": 682.2698579999999}, {"lowerIsBetter": false, "replicates": [793.1854199683042], "unit": "score", "name": "Earley", "value": 840.23606993007}, {"lowerIsBetter": false, "replicates": [12047.619047619048], "unit": "score", "name": "Boyer", "value": 55.31906324110671}, {"lowerIsBetter": false, "replicates": [25450.0], "unit": "score", "name": "RegExp", "value": 35.79508840864438}, {"lowerIsBetter": false, "replicates": [650.3541532517708], "unit": "score", "name": "Splay", "value": 125.30249801980203}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7"}]} 23:16:41 INFO - RETURN: dromaeo_css: 6956.67 23:16:41 INFO - RETURN: kraken: 1492.00 23:16:41 INFO - RETURN: v8_7: 20888.23 23:16:41 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,201,35]]", "result": "20888.23"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,201,35]]", "result": "1492.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,201,35]]", "result": "6956.67"}}} 23:16:42 INFO - Return code: 0 23:16:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 23:16:42 INFO - # TBPL SUCCESS # 23:16:42 INFO - Running post-action listener: _resource_record_post_action 23:16:42 INFO - Running post-run listener: _resource_record_post_run 23:16:43 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 4096; Write bytes: 504082432; Read time: 12; Write time: 586152 23:16:43 INFO - install - Wall time: 16s; CPU: 16.0%; Read bytes: 0; Write bytes: 245563392; Read time: 0; Write time: 396536 23:16:43 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 0; Write bytes: 258514944; Read time: 0; Write time: 189564 23:16:43 INFO - Running post-run listener: _upload_blobber_files 23:16:43 INFO - Blob upload gear active. 23:16:43 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 23:16:43 INFO - Copying logs to upload dir... 23:16:43 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1262.765826 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2015-11-24 23:16:42.762008) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 23:16:42.765652) ========= 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=1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010061 build_url: 'https://queue.taskcluster.net/v1/task/OUlmf2DyQFOYGaA-QEwz4g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 23:16:42.817762) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 23:16:42.818107) ========= 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=1d09c2d9237d126f9c84bbb400000198-1448431410.315602-1858169239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004515 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 23:16:42.868213) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 23:16:42.868565) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 23:16:42.868884) ========= ========= Total master_lag: 0.23 =========