builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-058 starttime: 1448473178.85 results: success (0) buildid: 20151125073054 builduid: d1a4de90f76048d686df1a4d620e1d2c revision: 35916735b8afc5b0732e00f9aeb56bf846bba7f4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:38.851531) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:38.852063) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:38.852438) ========= bash -c pwd in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006637 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:38.874796) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:38.875156) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:38.899332) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:38.899728) ========= rm -rf properties in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.011870 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:38.948911) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:38.949324) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:38.949765) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:38.950191) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-25 09:39: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% 47.1M=0s 2015-11-25 09:39:39 (47.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.162465 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:39.125499) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:39.125855) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.105991 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:39.241521) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:39.241941) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 35916735b8afc5b0732e00f9aeb56bf846bba7f4 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 35916735b8afc5b0732e00f9aeb56bf846bba7f4 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-25 09:39:39,274 truncating revision to first 12 chars 2015-11-25 09:39:39,274 Setting DEBUG logging. 2015-11-25 09:39:39,274 attempt 1/10 2015-11-25 09:39:39,274 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/35916735b8af?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-25 09:39:39,501 unpacking tar archive at: mozilla-central-35916735b8af/testing/mozharness/ program finished with exit code 0 elapsedTime=0.550620 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:39.887849) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:39.888171) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:39.921898) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:39.922225) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 09:39:39.922640) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2015-11-25 09:39:39.922965) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:39:40 INFO - MultiFileLogger online at 20151125 09:39:40 in /builds/slave/test-pgo 09:39:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:39:40 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:39:40 INFO - {'append_to_log': False, 09:39:40 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:39:40 INFO - 'blob_upload_branch': 'Firefox', 09:39:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:39:40 INFO - 'branch': 'Firefox', 09:39:40 INFO - 'buildbot_json_path': 'buildprops.json', 09:39:40 INFO - 'config_files': ('talos/linux_config.py',), 09:39:40 INFO - 'default_actions': ('clobber', 09:39:40 INFO - 'read-buildbot-config', 09:39:40 INFO - 'download-and-extract', 09:39:40 INFO - 'populate-webroot', 09:39:40 INFO - 'create-virtualenv', 09:39:40 INFO - 'install', 09:39:40 INFO - 'run-tests'), 09:39:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:39:40 INFO - 'download_minidump_stackwalk': True, 09:39:40 INFO - 'download_symbols': 'ondemand', 09:39:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:39:40 INFO - 'tooltool.py': '/tools/tooltool.py', 09:39:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:39:40 INFO - '/tools/misc-python/virtualenv.py')}, 09:39:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:39:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:39:40 INFO - 'installer_path': 'installer.exe', 09:39:40 INFO - 'log_level': 'info', 09:39:40 INFO - 'log_name': 'talos', 09:39:40 INFO - 'log_to_console': True, 09:39:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:39:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:39:40 INFO - 'opt_config_files': (), 09:39:40 INFO - 'pip_index': False, 09:39:40 INFO - 'sps_profile': False, 09:39:40 INFO - 'sps_profile_interval': 0, 09:39:40 INFO - 'suite': 'dromaeojs-e10s', 09:39:40 INFO - 'system_bits': '64', 09:39:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:39:40 INFO - 'title': 'talos-linux64-ix-058', 09:39:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:39:40 INFO - 'use_talos_json': True, 09:39:40 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:39:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:39:40 INFO - 'work_dir': 'build'} 09:39:40 INFO - ##### 09:39:40 INFO - ##### Running clobber step. 09:39:40 INFO - ##### 09:39:40 INFO - Running pre-action listener: _resource_record_pre_action 09:39:40 INFO - Running main action method: clobber 09:39:40 INFO - rmtree: /builds/slave/test-pgo/build 09:39:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:39:41 INFO - Running post-action listener: _resource_record_post_action 09:39:41 INFO - ##### 09:39:41 INFO - ##### Running read-buildbot-config step. 09:39:41 INFO - ##### 09:39:41 INFO - Running pre-action listener: _resource_record_pre_action 09:39:41 INFO - Running main action method: read_buildbot_config 09:39:41 INFO - Using buildbot properties: 09:39:41 INFO - { 09:39:41 INFO - "properties": { 09:39:41 INFO - "buildnumber": 563, 09:39:41 INFO - "product": "firefox", 09:39:41 INFO - "script_repo_revision": "production", 09:39:41 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 09:39:41 INFO - "repository": "", 09:39:41 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 09:39:41 INFO - "buildid": "20151125073054", 09:39:41 INFO - "slavename": "talos-linux64-ix-058", 09:39:41 INFO - "pgo_build": "True", 09:39:41 INFO - "basedir": "/builds/slave/test-pgo", 09:39:41 INFO - "project": "", 09:39:41 INFO - "platform": "ubuntu64_hw", 09:39:41 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:39:41 INFO - "slavebuilddir": "test", 09:39:41 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:39:41 INFO - "branch": "mozilla-central", 09:39:41 INFO - "repo_path": "mozilla-central", 09:39:41 INFO - "stage_platform": "linux64-pgo", 09:39:41 INFO - "builduid": "d1a4de90f76048d686df1a4d620e1d2c", 09:39:41 INFO - "revision": "35916735b8afc5b0732e00f9aeb56bf846bba7f4" 09:39:41 INFO - }, 09:39:41 INFO - "sourcestamp": { 09:39:41 INFO - "repository": "", 09:39:41 INFO - "hasPatch": false, 09:39:41 INFO - "project": "", 09:39:41 INFO - "branch": "mozilla-central-linux64-pgo-talos", 09:39:41 INFO - "changes": [ 09:39:41 INFO - { 09:39:41 INFO - "category": null, 09:39:41 INFO - "files": [ 09:39:41 INFO - { 09:39:41 INFO - "url": null, 09:39:41 INFO - "name": "https://queue.taskcluster.net/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 09:39:41 INFO - } 09:39:41 INFO - ], 09:39:41 INFO - "repository": "", 09:39:41 INFO - "rev": "35916735b8afc5b0732e00f9aeb56bf846bba7f4", 09:39:41 INFO - "who": "sendchange", 09:39:41 INFO - "when": 1448473159, 09:39:41 INFO - "number": 6706690, 09:39:41 INFO - "comments": "", 09:39:41 INFO - "project": "", 09:39:41 INFO - "at": "Wed 25 Nov 2015 09:39:19", 09:39:41 INFO - "branch": "mozilla-central-linux64-pgo-talos", 09:39:41 INFO - "revlink": "", 09:39:41 INFO - "properties": [ 09:39:41 INFO - [ 09:39:41 INFO - "buildid", 09:39:41 INFO - "20151125073054", 09:39:41 INFO - "Change" 09:39:41 INFO - ], 09:39:41 INFO - [ 09:39:41 INFO - "builduid", 09:39:41 INFO - "d1a4de90f76048d686df1a4d620e1d2c", 09:39:41 INFO - "Change" 09:39:41 INFO - ], 09:39:41 INFO - [ 09:39:41 INFO - "pgo_build", 09:39:41 INFO - "True", 09:39:41 INFO - "Change" 09:39:41 INFO - ] 09:39:41 INFO - ], 09:39:41 INFO - "revision": "35916735b8afc5b0732e00f9aeb56bf846bba7f4" 09:39:41 INFO - } 09:39:41 INFO - ], 09:39:41 INFO - "revision": "35916735b8afc5b0732e00f9aeb56bf846bba7f4" 09:39:41 INFO - } 09:39:41 INFO - } 09:39:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 09:39:41 INFO - Running post-action listener: _resource_record_post_action 09:39:41 INFO - ##### 09:39:41 INFO - ##### Running download-and-extract step. 09:39:41 INFO - ##### 09:39:41 INFO - Running pre-action listener: _resource_record_pre_action 09:39:41 INFO - Running main action method: download_and_extract 09:39:41 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:39:41 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')]} 09:39:41 INFO - https://queue.taskcluster.net/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:39:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/test_packages.json 09:39:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/test_packages.json 09:39:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:39:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:39:42 INFO - Downloaded 1302 bytes. 09:39:42 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:39:42 INFO - Using the following test package requirements: 09:39:42 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 09:39:42 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:39:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:39:42 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:39:42 INFO - u'jsshell-linux-x86_64.zip'], 09:39:42 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:39:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:39:42 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 09:39:42 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:39:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:39:42 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:39:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:39:42 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:39:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:39:42 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 09:39:42 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:39:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:39:42 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:39: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')]} 09:39:42 INFO - https://queue.taskcluster.net/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:39:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:39:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:39:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/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 09:39:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/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 09:39:44 INFO - Downloaded 22753713 bytes. 09:39:44 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 09:39:44 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:39:44 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 09:39:45 INFO - Return code: 0 09:39:45 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 09:39:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:39:45 INFO - https://queue.taskcluster.net/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:39:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:39:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:39:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/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 09:39:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/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 09:39:49 INFO - Downloaded 22753713 bytes. 09:39:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:39:49 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:39:49 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:39:50 INFO - Return code: 0 09:39:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:39:50 INFO - https://queue.taskcluster.net/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:39:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 09:39:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 09:39:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/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 09:39:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/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 09:39:52 INFO - Downloaded 11131717 bytes. 09:39:52 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 09:39:52 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 09:39:52 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 09:39:53 INFO - Return code: 0 09:39:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:39:53 INFO - https://queue.taskcluster.net/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:39:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:39:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:39:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/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 09:39:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2KBKgtMR8mI7PNv_gtU7g/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 09:39:54 INFO - Downloaded 58390218 bytes. 09:39:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:39:54 INFO - mkdir: /builds/slave/test-pgo/properties 09:39:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:39:54 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:39:54 INFO - Contents: 09:39:54 INFO - build_url:https://queue.taskcluster.net/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:39:54 INFO - Running post-action listener: _resource_record_post_action 09:39:54 INFO - Running post-action listener: set_extra_try_arguments 09:39:54 INFO - ##### 09:39:54 INFO - ##### Running populate-webroot step. 09:39:54 INFO - ##### 09:39:54 INFO - Running pre-action listener: _resource_record_pre_action 09:39:54 INFO - Running main action method: populate_webroot 09:39:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:39:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:39:54 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 09:39:54 INFO - '../fennec_ids.txt'], 09:39:54 INFO - 'tests': ['tcheck2']}, 09:39:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:39:54 INFO - '--tppagecycles', 09:39:54 INFO - '7'], 09:39:54 INFO - 'tests': ['tsvgm']}}, 09:39:54 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:39:54 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:39:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:39:54 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:39:54 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:39:54 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:39:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:39:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:54 INFO - 'talos_options': ['--e10s'], 09:39:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:39:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:54 INFO - 'tests': ['damp', 'tps']}, 09:39:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:54 INFO - 'talos_options': ['--e10s'], 09:39:54 INFO - 'tests': ['damp', 'tps']}, 09:39:54 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:39:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:39:54 INFO - 'other': {'tests': ['a11yr', 09:39:54 INFO - 'ts_paint', 09:39:54 INFO - 'tpaint', 09:39:54 INFO - 'sessionrestore', 09:39:54 INFO - 'sessionrestore_no_auto_restore']}, 09:39:54 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:39:54 INFO - 'tests': ['a11yr', 09:39:54 INFO - 'ts_paint', 09:39:54 INFO - 'tpaint', 09:39:54 INFO - 'sessionrestore', 09:39:54 INFO - 'sessionrestore_no_auto_restore']}, 09:39:54 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:39:54 INFO - 'tests': ['a11yr', 09:39:54 INFO - 'ts_paint', 09:39:54 INFO - 'tpaint', 09:39:54 INFO - 'sessionrestore', 09:39:54 INFO - 'sessionrestore_no_auto_restore']}, 09:39:54 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:39:54 INFO - 'tests': ['a11yr', 09:39:54 INFO - 'ts_paint', 09:39:54 INFO - 'tpaint', 09:39:54 INFO - 'sessionrestore', 09:39:54 INFO - 'sessionrestore_no_auto_restore']}, 09:39:54 INFO - 'other_l64': {'tests': ['a11yr', 09:39:54 INFO - 'ts_paint', 09:39:54 INFO - 'tpaint', 09:39:54 INFO - 'sessionrestore', 09:39:54 INFO - 'sessionrestore_no_auto_restore']}, 09:39:54 INFO - 'other_nol64': {'tests': ['a11yr', 09:39:54 INFO - 'ts_paint', 09:39:54 INFO - 'tpaint', 09:39:54 INFO - 'sessionrestore', 09:39:54 INFO - 'sessionrestore_no_auto_restore']}, 09:39:54 INFO - 'svgr': {'tests': ['tsvgx', 09:39:54 INFO - 'tsvgr_opacity', 09:39:54 INFO - 'tart', 09:39:54 INFO - 'tscrollx', 09:39:54 INFO - 'cart']}, 09:39:54 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:39:54 INFO - 'tests': ['tsvgx', 09:39:54 INFO - 'tsvgr_opacity', 09:39:54 INFO - 'tart', 09:39:54 INFO - 'tscrollx', 09:39:54 INFO - 'cart']}, 09:39:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:54 INFO - 'tests': ['tp5o']}, 09:39:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:54 INFO - 'talos_options': ['--e10s'], 09:39:54 INFO - 'tests': ['tp5o']}, 09:39:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:54 INFO - 'talos_options': ['--xperf_path', 09:39:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:39:54 INFO - 'tests': ['tp5n']}, 09:39:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:54 INFO - 'talos_options': ['--e10s', 09:39:54 INFO - '--xperf_path', 09:39:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:39:54 INFO - 'tests': ['tp5n']}}, 09:39:54 INFO - 'talos.zip': {'path': '', 09:39:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:39:54 INFO - Running post-action listener: _resource_record_post_action 09:39:54 INFO - ##### 09:39:54 INFO - ##### Running create-virtualenv step. 09:39:54 INFO - ##### 09:39:54 INFO - Running pre-action listener: _resource_record_pre_action 09:39:54 INFO - Running main action method: create_virtualenv 09:39:54 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:39:54 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 09:39:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:39:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:39:54 INFO - Using real prefix '/usr' 09:39:54 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:39:55 INFO - Installing distribute.............................................................................................................................................................................................done. 09:39:57 INFO - Installing pip.................done. 09:39:57 INFO - Return code: 0 09:39:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:39:57 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')]} 09:39:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:57 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')]} 09:39:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:57 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 0x11f5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14227d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b1640>, '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 0x14b2540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b29d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279', '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 09:39:57 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 09:39:57 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 09:39:57 INFO - Using env: {'DISPLAY': ':0', 09:39:57 INFO - 'HOME': '/home/cltbld', 09:39:57 INFO - 'LANG': 'en_US.UTF-8', 09:39:57 INFO - 'LANGUAGE': 'en_US:en', 09:39:57 INFO - 'LOGNAME': 'cltbld', 09:39:57 INFO - 'MAIL': '/var/mail/cltbld', 09:39:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:57 INFO - 'MOZ_NO_REMOTE': '1', 09:39:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:39:57 INFO - 'NO_EM_RESTART': '1', 09:39:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:39:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:39:57 INFO - 'PWD': '/builds/slave/test-pgo', 09:39:57 INFO - 'SHELL': '/bin/bash', 09:39:57 INFO - 'SHLVL': '1', 09:39:57 INFO - 'TERM': 'linux', 09:39:57 INFO - 'TMOUT': '86400', 09:39:57 INFO - 'USER': 'cltbld', 09:39:57 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279', 09:39:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:57 INFO - '_': '/tools/buildbot/bin/python'} 09:39:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:57 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 09:39:57 INFO - Cleaning up... 09:39:57 INFO - Return code: 0 09:39:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:39:57 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')]} 09:39:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:57 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')]} 09:39:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:57 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 0x11f5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14227d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b1640>, '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 0x14b2540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b29d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279', '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 09:39:57 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 09:39:57 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 09:39:57 INFO - Using env: {'DISPLAY': ':0', 09:39:57 INFO - 'HOME': '/home/cltbld', 09:39:57 INFO - 'LANG': 'en_US.UTF-8', 09:39:57 INFO - 'LANGUAGE': 'en_US:en', 09:39:57 INFO - 'LOGNAME': 'cltbld', 09:39:57 INFO - 'MAIL': '/var/mail/cltbld', 09:39:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:57 INFO - 'MOZ_NO_REMOTE': '1', 09:39:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:39:57 INFO - 'NO_EM_RESTART': '1', 09:39:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:39:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:39:57 INFO - 'PWD': '/builds/slave/test-pgo', 09:39:57 INFO - 'SHELL': '/bin/bash', 09:39:57 INFO - 'SHLVL': '1', 09:39:57 INFO - 'TERM': 'linux', 09:39:57 INFO - 'TMOUT': '86400', 09:39:57 INFO - 'USER': 'cltbld', 09:39:57 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279', 09:39:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:57 INFO - '_': '/tools/buildbot/bin/python'} 09:39:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:57 INFO - Downloading/unpacking psutil>=0.7.1 09:39:57 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 09:39:57 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 09:39:57 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 09:39:57 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 09:39:57 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 09:39:57 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 09:39:59 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 09:39:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 09:39:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:39:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:39:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:39:59 INFO - Installing collected packages: psutil 09:39:59 INFO - Running setup.py install for psutil 09:39:59 INFO - building 'psutil._psutil_linux' extension 09:39:59 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 09:39: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_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:39:59 INFO - building 'psutil._psutil_posix' extension 09:39:59 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 09:40:00 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 09:40:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:40:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:40:00 INFO - Successfully installed psutil 09:40:00 INFO - Cleaning up... 09:40:00 INFO - Return code: 0 09:40:00 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:40:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:00 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11f5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14227d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b1640>, '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 0x14b2540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b29d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279', '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 09:40:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 09:40:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:40:00 INFO - Using env: {'DISPLAY': ':0', 09:40:00 INFO - 'HOME': '/home/cltbld', 09:40:00 INFO - 'LANG': 'en_US.UTF-8', 09:40:00 INFO - 'LANGUAGE': 'en_US:en', 09:40:00 INFO - 'LOGNAME': 'cltbld', 09:40:00 INFO - 'MAIL': '/var/mail/cltbld', 09:40:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:00 INFO - 'MOZ_NO_REMOTE': '1', 09:40:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:40:00 INFO - 'NO_EM_RESTART': '1', 09:40:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:40:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:40:00 INFO - 'PWD': '/builds/slave/test-pgo', 09:40:00 INFO - 'SHELL': '/bin/bash', 09:40:00 INFO - 'SHLVL': '1', 09:40:00 INFO - 'TERM': 'linux', 09:40:00 INFO - 'TMOUT': '86400', 09:40:00 INFO - 'USER': 'cltbld', 09:40:00 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279', 09:40:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:00 INFO - '_': '/tools/buildbot/bin/python'} 09:40:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:00 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:40:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:02 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:40:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 09:40:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:40:02 INFO - Installing collected packages: mozsystemmonitor 09:40:02 INFO - Running setup.py install for mozsystemmonitor 09:40:02 INFO - Successfully installed mozsystemmonitor 09:40:02 INFO - Cleaning up... 09:40:02 INFO - Return code: 0 09:40:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:40:02 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')]} 09:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:02 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')]} 09:40:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:02 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 0x11f5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14227d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b1640>, '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 0x14b2540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b29d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279', '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 09:40:02 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 09:40:02 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 09:40:02 INFO - Using env: {'DISPLAY': ':0', 09:40:02 INFO - 'HOME': '/home/cltbld', 09:40:02 INFO - 'LANG': 'en_US.UTF-8', 09:40:02 INFO - 'LANGUAGE': 'en_US:en', 09:40:02 INFO - 'LOGNAME': 'cltbld', 09:40:02 INFO - 'MAIL': '/var/mail/cltbld', 09:40:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:02 INFO - 'MOZ_NO_REMOTE': '1', 09:40:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:40:02 INFO - 'NO_EM_RESTART': '1', 09:40:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:40:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:40:02 INFO - 'PWD': '/builds/slave/test-pgo', 09:40:02 INFO - 'SHELL': '/bin/bash', 09:40:02 INFO - 'SHLVL': '1', 09:40:02 INFO - 'TERM': 'linux', 09:40:02 INFO - 'TMOUT': '86400', 09:40:02 INFO - 'USER': 'cltbld', 09:40:02 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279', 09:40:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:02 INFO - '_': '/tools/buildbot/bin/python'} 09:40:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:02 INFO - Downloading/unpacking blobuploader==1.2.4 09:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:04 INFO - Downloading blobuploader-1.2.4.tar.gz 09:40:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 09:40:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:40:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:40:04 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 09:40:04 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 09:40:04 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 09:40:04 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 09:40:04 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 09:40:04 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 09:40:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 09:40:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:40:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:40:04 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 09:40:04 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 09:40:04 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 09:40:04 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 09:40:04 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 09:40:04 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 09:40:04 INFO - Downloading docopt-0.6.1.tar.gz 09:40:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 09:40:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:40:04 INFO - Installing collected packages: blobuploader, requests, docopt 09:40:04 INFO - Running setup.py install for blobuploader 09:40:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:40:05 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:40:05 INFO - Running setup.py install for requests 09:40:05 INFO - Running setup.py install for docopt 09:40:05 INFO - Successfully installed blobuploader requests docopt 09:40:05 INFO - Cleaning up... 09:40:05 INFO - Return code: 0 09:40:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:05 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 0x11f5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14227d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b1640>, '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 0x14b2540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b29d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279', '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 09:40:05 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 09:40:05 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 09:40:05 INFO - Using env: {'DISPLAY': ':0', 09:40:05 INFO - 'HOME': '/home/cltbld', 09:40:05 INFO - 'LANG': 'en_US.UTF-8', 09:40:05 INFO - 'LANGUAGE': 'en_US:en', 09:40:05 INFO - 'LOGNAME': 'cltbld', 09:40:05 INFO - 'MAIL': '/var/mail/cltbld', 09:40:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:05 INFO - 'MOZ_NO_REMOTE': '1', 09:40:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:40:05 INFO - 'NO_EM_RESTART': '1', 09:40:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:40:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:40:05 INFO - 'PWD': '/builds/slave/test-pgo', 09:40:05 INFO - 'SHELL': '/bin/bash', 09:40:05 INFO - 'SHLVL': '1', 09:40:05 INFO - 'TERM': 'linux', 09:40:05 INFO - 'TMOUT': '86400', 09:40:05 INFO - 'USER': 'cltbld', 09:40:05 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279', 09:40:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:05 INFO - '_': '/tools/buildbot/bin/python'} 09:40:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:40:05 INFO - Running setup.py (path:/tmp/pip-vCaTHL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:40:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:40:05 INFO - Running setup.py (path:/tmp/pip-NbMG8P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:40:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:40:05 INFO - Running setup.py (path:/tmp/pip-ova5z1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:40:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:40:05 INFO - Running setup.py (path:/tmp/pip-dV3xWf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:40:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:40:06 INFO - Running setup.py (path:/tmp/pip-8moPc_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:40:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:40:06 INFO - Running setup.py (path:/tmp/pip-OjKZUt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:40:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:40:06 INFO - Running setup.py (path:/tmp/pip-AHDSsh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:40:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:40:06 INFO - Running setup.py (path:/tmp/pip-gkCz0O-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:40:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:40:06 INFO - Running setup.py (path:/tmp/pip-DMlkOp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:40:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:40:06 INFO - Running setup.py (path:/tmp/pip-MoM94H-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:40:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:40:06 INFO - Running setup.py (path:/tmp/pip-1HPJ0s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:40:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:40:06 INFO - Running setup.py (path:/tmp/pip-tyX85K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:40:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:40:06 INFO - Running setup.py (path:/tmp/pip-NQfAGo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:40:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:40:06 INFO - Running setup.py (path:/tmp/pip-vuWecU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:40:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:40:06 INFO - Running setup.py (path:/tmp/pip-P0eVQr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:40:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:40:06 INFO - Running setup.py (path:/tmp/pip-Us6tFo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:40:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:40:06 INFO - Running setup.py (path:/tmp/pip-tFTP4m-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:40:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:40:07 INFO - Running setup.py install for manifestparser 09:40:07 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:40:07 INFO - Running setup.py install for mozcrash 09:40:07 INFO - Running setup.py install for mozdebug 09:40:07 INFO - Running setup.py install for mozdevice 09:40:07 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:40:07 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:40:07 INFO - Running setup.py install for mozfile 09:40:07 INFO - Running setup.py install for mozhttpd 09:40:07 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:40:07 INFO - Running setup.py install for mozinfo 09:40:07 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:40:07 INFO - Running setup.py install for mozInstall 09:40:07 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:40:07 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:40:07 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:40:07 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:40:07 INFO - Running setup.py install for mozleak 09:40:07 INFO - Running setup.py install for mozlog 09:40:08 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:40:08 INFO - Running setup.py install for moznetwork 09:40:08 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:40:08 INFO - Running setup.py install for mozprocess 09:40:08 INFO - Running setup.py install for mozprofile 09:40:08 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:40:08 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:40:08 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:40:08 INFO - Running setup.py install for mozrunner 09:40:08 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:40:08 INFO - Running setup.py install for mozscreenshot 09:40:08 INFO - Running setup.py install for moztest 09:40:08 INFO - Running setup.py install for mozversion 09:40:08 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:40:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:40:08 INFO - Cleaning up... 09:40:08 INFO - Return code: 0 09:40:08 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:08 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 0x11f5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14227d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b1640>, '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 0x14b2540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b29d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279', '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 09:40:08 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 09:40:08 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 09:40:08 INFO - Using env: {'DISPLAY': ':0', 09:40:08 INFO - 'HOME': '/home/cltbld', 09:40:08 INFO - 'LANG': 'en_US.UTF-8', 09:40:08 INFO - 'LANGUAGE': 'en_US:en', 09:40:08 INFO - 'LOGNAME': 'cltbld', 09:40:08 INFO - 'MAIL': '/var/mail/cltbld', 09:40:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:08 INFO - 'MOZ_NO_REMOTE': '1', 09:40:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:40:08 INFO - 'NO_EM_RESTART': '1', 09:40:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:40:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:40:08 INFO - 'PWD': '/builds/slave/test-pgo', 09:40:08 INFO - 'SHELL': '/bin/bash', 09:40:08 INFO - 'SHLVL': '1', 09:40:08 INFO - 'TERM': 'linux', 09:40:08 INFO - 'TMOUT': '86400', 09:40:08 INFO - 'USER': 'cltbld', 09:40:08 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279', 09:40:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:08 INFO - '_': '/tools/buildbot/bin/python'} 09:40:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:40:08 INFO - Running setup.py (path:/tmp/pip-1Y2Sj2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:40:09 INFO - Running setup.py (path:/tmp/pip-KYPJeV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:40:09 INFO - Running setup.py (path:/tmp/pip-EfrYK8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:40:09 INFO - Running setup.py (path:/tmp/pip-6LNLTI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:40:09 INFO - Running setup.py (path:/tmp/pip-vCf8iE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:40:09 INFO - Running setup.py (path:/tmp/pip-55tozT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:40:09 INFO - Running setup.py (path:/tmp/pip-pN92fb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:40:09 INFO - Running setup.py (path:/tmp/pip-JWLlJz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:40:09 INFO - Running setup.py (path:/tmp/pip-wmSWLA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:40:09 INFO - Running setup.py (path:/tmp/pip-UOvxX6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:40:09 INFO - Running setup.py (path:/tmp/pip-plkize-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:40:09 INFO - Running setup.py (path:/tmp/pip-3ckINK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:40:09 INFO - Running setup.py (path:/tmp/pip-i16bLp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:40:09 INFO - Running setup.py (path:/tmp/pip-BssHy3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:40:09 INFO - Running setup.py (path:/tmp/pip-WIgWmL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:40:10 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)) 09:40:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:40:10 INFO - Running setup.py (path:/tmp/pip-HidAFS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:40:10 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)) 09:40:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:40:10 INFO - Running setup.py (path:/tmp/pip-nQkhid-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:40:10 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)) 09:40:10 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)) 09:40:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:40:10 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)) 09:40:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:40:10 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)) 09:40:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:40:10 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 09:40:10 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 09:40:10 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 09:40:10 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 09:40:10 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 09:40:10 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 09:40:11 INFO - Downloading blessings-1.5.1.tar.gz 09:40: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 09:40:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:40:11 INFO - Installing collected packages: blessings 09:40:11 INFO - Running setup.py install for blessings 09:40:12 INFO - Successfully installed blessings 09:40:12 INFO - Cleaning up... 09:40:12 INFO - Return code: 0 09:40:12 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:40:12 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:40:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:40:12 INFO - Reading from file tmpfile_stdout 09:40:12 INFO - Current package versions: 09:40:12 INFO - argparse == 1.2.1 09:40:12 INFO - blessings == 1.5.1 09:40:12 INFO - blobuploader == 1.2.4 09:40:12 INFO - docopt == 0.6.1 09:40:12 INFO - manifestparser == 1.1 09:40:12 INFO - mozInstall == 1.12 09:40:12 INFO - mozcrash == 0.16 09:40:12 INFO - mozdebug == 0.1 09:40:12 INFO - mozdevice == 0.47 09:40:12 INFO - mozfile == 1.2 09:40:12 INFO - mozhttpd == 0.7 09:40:12 INFO - mozinfo == 0.9 09:40:12 INFO - mozleak == 0.1 09:40:12 INFO - mozlog == 3.1 09:40:12 INFO - moznetwork == 0.27 09:40:12 INFO - mozprocess == 0.22 09:40:12 INFO - mozprofile == 0.27 09:40:12 INFO - mozrunner == 6.11 09:40:12 INFO - mozscreenshot == 0.1 09:40:12 INFO - mozsystemmonitor == 0.0 09:40:12 INFO - moztest == 0.7 09:40:12 INFO - mozversion == 1.4 09:40:12 INFO - psutil == 3.1.1 09:40:12 INFO - requests == 1.2.3 09:40:12 INFO - wsgiref == 0.1.2 09:40:12 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:12 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 0x11f5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14227d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b1640>, '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 0x14b2540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b29d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279', '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 09:40:12 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 09:40:12 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 09:40:12 INFO - Using env: {'DISPLAY': ':0', 09:40:12 INFO - 'HOME': '/home/cltbld', 09:40:12 INFO - 'LANG': 'en_US.UTF-8', 09:40:12 INFO - 'LANGUAGE': 'en_US:en', 09:40:12 INFO - 'LOGNAME': 'cltbld', 09:40:12 INFO - 'MAIL': '/var/mail/cltbld', 09:40:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:12 INFO - 'MOZ_NO_REMOTE': '1', 09:40:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:40:12 INFO - 'NO_EM_RESTART': '1', 09:40:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:40:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:40:12 INFO - 'PWD': '/builds/slave/test-pgo', 09:40:12 INFO - 'SHELL': '/bin/bash', 09:40:12 INFO - 'SHLVL': '1', 09:40:12 INFO - 'TERM': 'linux', 09:40:12 INFO - 'TMOUT': '86400', 09:40:12 INFO - 'USER': 'cltbld', 09:40:12 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279', 09:40:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:12 INFO - '_': '/tools/buildbot/bin/python'} 09:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:12 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)) 09:40:12 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)) 09:40:12 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)) 09:40:12 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)) 09:40:12 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)) 09:40:12 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)) 09:40:12 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)) 09:40:12 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)) 09:40:12 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)) 09:40:12 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)) 09:40:12 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)) 09:40:12 INFO - Cleaning up... 09:40:12 INFO - Return code: 0 09:40:12 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:12 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 0x11f5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14227d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b1640>, '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 0x14b2540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b29d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279', '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 09:40:12 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 09:40:12 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 09:40:12 INFO - Using env: {'DISPLAY': ':0', 09:40:12 INFO - 'HOME': '/home/cltbld', 09:40:12 INFO - 'LANG': 'en_US.UTF-8', 09:40:12 INFO - 'LANGUAGE': 'en_US:en', 09:40:12 INFO - 'LOGNAME': 'cltbld', 09:40:12 INFO - 'MAIL': '/var/mail/cltbld', 09:40:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:12 INFO - 'MOZ_NO_REMOTE': '1', 09:40:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:40:12 INFO - 'NO_EM_RESTART': '1', 09:40:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:40:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:40:12 INFO - 'PWD': '/builds/slave/test-pgo', 09:40:12 INFO - 'SHELL': '/bin/bash', 09:40:12 INFO - 'SHLVL': '1', 09:40:12 INFO - 'TERM': 'linux', 09:40:12 INFO - 'TMOUT': '86400', 09:40:12 INFO - 'USER': 'cltbld', 09:40:12 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279', 09:40:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:12 INFO - '_': '/tools/buildbot/bin/python'} 09:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:12 INFO - Downloading/unpacking jsonschema 09:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:40:14 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 09:40:14 INFO - Downloading/unpacking functools32 (from jsonschema) 09:40:14 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 09:40:14 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 09:40:14 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 09:40:14 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 09:40:14 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 09:40:14 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 09:40:14 INFO - Downloading functools32-3.2.3-2.tar.gz 09:40:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 09:40:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:40:14 INFO - warning: no files found matching '*.txt' 09:40:14 INFO - no previously-included directories found matching 'build' 09:40:14 INFO - no previously-included directories found matching 'dist' 09:40:14 INFO - no previously-included directories found matching '.git*' 09:40:14 INFO - Installing collected packages: jsonschema, functools32 09:40:14 INFO - Running setup.py install for functools32 09:40:14 INFO - warning: no files found matching '*.txt' 09:40:14 INFO - no previously-included directories found matching 'build' 09:40:14 INFO - no previously-included directories found matching 'dist' 09:40:14 INFO - no previously-included directories found matching '.git*' 09:40:14 INFO - Successfully installed jsonschema functools32 09:40:14 INFO - Cleaning up... 09:40:15 INFO - Return code: 0 09:40:15 INFO - Running post-action listener: _resource_record_post_action 09:40:15 INFO - Running post-action listener: _start_resource_monitoring 09:40:15 INFO - Starting resource monitoring. 09:40:15 INFO - ##### 09:40:15 INFO - ##### Running install step. 09:40:15 INFO - ##### 09:40:15 INFO - Running pre-action listener: _resource_record_pre_action 09:40:15 INFO - Running main action method: install 09:40:15 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:40:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:40:15 INFO - Reading from file tmpfile_stdout 09:40:15 INFO - Detecting whether we're running mozinstall >=1.0... 09:40:15 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:40:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:40:15 INFO - Reading from file tmpfile_stdout 09:40:15 INFO - Output received: 09:40:15 INFO - Usage: mozinstall [options] installer 09:40:15 INFO - Options: 09:40:15 INFO - -h, --help show this help message and exit 09:40:15 INFO - -d DEST, --destination=DEST 09:40:15 INFO - Directory to install application into. [default: 09:40:15 INFO - "/builds/slave/test-pgo"] 09:40:15 INFO - --app=APP Application being installed. [default: firefox] 09:40:15 INFO - mkdir: /builds/slave/test-pgo/build/application 09:40:15 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'] 09:40:15 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 09:40:29 INFO - Reading from file tmpfile_stdout 09:40:29 INFO - Output received: 09:40:29 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:40:29 INFO - Running post-action listener: _resource_record_post_action 09:40:29 INFO - ##### 09:40:29 INFO - ##### Running run-tests step. 09:40:29 INFO - ##### 09:40:29 INFO - Running pre-action listener: _resource_record_pre_action 09:40:29 INFO - Running main action method: run_tests 09:40:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:40:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:40:29 INFO - Python 2.7.3 09:40:29 INFO - Return code: 0 09:40:29 INFO - grabbing minidump binary from tooltool 09:40: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')]} 09:40: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 0x14b1640>, '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 0x14b2540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b29d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:40: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 09:40: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 09:40:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:40:30 INFO - Return code: 0 09:40:30 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:40:30 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:40:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:40:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:40:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:40:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:40:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-058 --webServer localhost 09:40:30 INFO - Using env: {'DISPLAY': ':0', 09:40:30 INFO - 'HOME': '/home/cltbld', 09:40:30 INFO - 'LANG': 'en_US.UTF-8', 09:40:30 INFO - 'LANGUAGE': 'en_US:en', 09:40:30 INFO - 'LOGNAME': 'cltbld', 09:40:30 INFO - 'MAIL': '/var/mail/cltbld', 09:40:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:40:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:40:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:30 INFO - 'MOZ_NO_REMOTE': '1', 09:40:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:40:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:40:30 INFO - 'NO_EM_RESTART': '1', 09:40:30 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:40:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:40:30 INFO - 'PWD': '/builds/slave/test-pgo', 09:40:30 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:40:30 INFO - 'SHELL': '/bin/bash', 09:40:30 INFO - 'SHLVL': '1', 09:40:30 INFO - 'TERM': 'linux', 09:40:30 INFO - 'TMOUT': '86400', 09:40:30 INFO - 'USER': 'cltbld', 09:40:30 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279', 09:40:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:30 INFO - '_': '/tools/buildbot/bin/python'} 09:40:30 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost'] with output_timeout 3600 09:40:30 INFO - mozversion INFO | application_buildid: 20151125073054 09:40:30 INFO - mozversion INFO | application_changeset: 35916735b8afc5b0732e00f9aeb56bf846bba7f4 09:40:30 INFO - mozversion INFO | application_display_name: Nightly 09:40:30 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:40:30 INFO - mozversion INFO | application_name: Firefox 09:40:30 INFO - mozversion INFO | application_remotingname: firefox 09:40:30 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 09:40:30 INFO - mozversion INFO | application_vendor: Mozilla 09:40:30 INFO - mozversion INFO | application_version: 45.0a1 09:40:30 INFO - mozversion INFO | platform_buildid: 20151125073054 09:40:30 INFO - mozversion INFO | platform_changeset: 35916735b8afc5b0732e00f9aeb56bf846bba7f4 09:40:30 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 09:40:30 INFO - mozversion INFO | platform_version: 45.0a1 09:40:30 INFO - 2015-11-25 09:40:30,216 DEBUG : using testdate: 1448473230 09:40:30 INFO - 2015-11-25 09:40:30,216 DEBUG : actual date: 1448473230 09:40:30 INFO - 2015-11-25 09:40:30,225 INFO : starting webserver on 'localhost:43530' 09:40:30 INFO - 2015-11-25 09:40:30,226 INFO : Starting test suite talos-linux64-ix-058.e 09:40:30 INFO - 2015-11-25 09:40:30,226 INFO : Starting test dromaeo_css 09:40:30 INFO - 2015-11-25 09:40:30,226 DEBUG : operating with platform_type : linux_ 09:40:30 INFO - 2015-11-25 09:40:30,227 INFO : Initialising browser for dromaeo_css test... 09:40:30 INFO - 2015-11-25 09:40:30,242 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpYsC9OM/profile http://localhost:43530/getInfo.html 09:40:34 INFO - 2015-11-25 09:40:34,955 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:40:34 INFO - 2015-11-25 09:40:34,956 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:40:34 INFO - 2015-11-25 09:40:34,956 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:40:34 INFO - 2015-11-25 09:40:34,956 DEBUG : BROWSER_OUTPUT: __metrics 09:40:36 INFO - 2015-11-25 09:40:36,045 INFO : Browser initialized. 09:40:36 INFO - 2015-11-25 09:40:36,046 INFO : Running cycle 1/1 for dromaeo_css test... 09:40:36 INFO - 2015-11-25 09:40:36,046 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpYsC9OM/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:40:47 INFO - 2015-11-25 09:40:47,719 DEBUG : BROWSER_OUTPUT: RSS: Main: 138018816 09:40:47 INFO - 2015-11-25 09:40:47,720 DEBUG : BROWSER_OUTPUT: 09:41:37 INFO - 2015-11-25 09:41:37,308 DEBUG : BROWSER_OUTPUT: 1448473297307 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] 09:43:46 INFO - 2015-11-25 09:43:46,280 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43530/tests/dromaeo/cssquery-ext.html) 09:43:47 INFO - 2015-11-25 09:43:47,451 DEBUG : BROWSER_OUTPUT: RSS: Main: 149061632 09:43:47 INFO - 2015-11-25 09:43:47,452 DEBUG : BROWSER_OUTPUT: 09:46:49 INFO - 2015-11-25 09:46:49,358 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/dromaeo/cssquery-ext.html (next: http://localhost:43530/tests/dromaeo/cssquery-jquery.html) 09:46:49 INFO - 2015-11-25 09:46:49,940 DEBUG : BROWSER_OUTPUT: RSS: Main: 142745600 09:46:49 INFO - 2015-11-25 09:46:49,940 DEBUG : BROWSER_OUTPUT: 09:49:51 INFO - 2015-11-25 09:49:51,607 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43530/tests/dromaeo/cssquery-mootools.html) 09:49:52 INFO - 2015-11-25 09:49:52,197 DEBUG : BROWSER_OUTPUT: RSS: Main: 144478208 09:49:52 INFO - 2015-11-25 09:49:52,197 DEBUG : BROWSER_OUTPUT: 09:52:53 INFO - 2015-11-25 09:52:53,863 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43530/tests/dromaeo/cssquery-prototype.html) 09:52:54 INFO - 2015-11-25 09:52:54,457 DEBUG : BROWSER_OUTPUT: RSS: Main: 142090240 09:52:54 INFO - 2015-11-25 09:52:54,457 DEBUG : BROWSER_OUTPUT: 09:55:56 INFO - 2015-11-25 09:55:56,722 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43530/tests/dromaeo/cssquery-yui.html) 09:55:57 INFO - 2015-11-25 09:55:57,328 DEBUG : BROWSER_OUTPUT: RSS: Main: 141942784 09:55:57 INFO - 2015-11-25 09:55:57,328 DEBUG : BROWSER_OUTPUT: 09:58:59 INFO - 2015-11-25 09:58:59,755 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/dromaeo/cssquery-yui.html (next: http://localhost:43530/tests/dromaeo/cssquery-dojo.html) 09:59:00 INFO - 2015-11-25 09:59:00,013 DEBUG : BROWSER_OUTPUT: RSS: Main: 145469440 09:59:00 INFO - 2015-11-25 09:59:00,013 DEBUG : BROWSER_OUTPUT: 09:59:00 INFO - 2015-11-25 09:59:00,013 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:59:00 INFO - 2015-11-25 09:59:00,013 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:59:00 INFO - 2015-11-25 09:59:00,013 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:59:00 INFO - 2015-11-25 09:59:00,013 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:59:00 INFO - 2015-11-25 09:59:00,013 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3259.722923551498;3290.9012729618103;3330.2340461693793;3346.7155291800195;3351.882684106056;5302.973485132578;5588.916166257504;5594.804181853636;5601.467860553246;5601.747921343539;5429.619926605139;5496.258005169301;5528.44715528447;5553;5632.605717599769;4911.066897289522;5018.623603229754;5085.211792172219;5099.33708617881;5107.795688172488;5103.923441148386;5116;5154.793808247666;5225.86935326618;5240.6593571417925;4958.603311735053;5070.442251352349;5117.974410127926;5150.227465880129;5233.685978841263;4972.303877457159;5092.032513822362;5094.286799848024;5109.744512774384;5119.718415487147;5082.415522214918;5119.001794650045;5136.152534831748;5136.512031357052;5151.665141765773;5159.019786240602;5225.581953443715;5261.4475480074425;5422.376426710939;5467.289252397202;6028;6043.244594425697;6062.999605065159;6087.543434242405;6097.2683278006925;6091.604045737013;6111.938880611181;6113.510919126459;6129.049997250433;6136.693165341715;5414.512693857571;5476.91784623235;5511.145772405243;5648.6893220872835;5664.037113690682;6097.939020609826;6154.446099851035;6155.169052177898;6163.291221509494;6177.444030037318;5860.736266868002;5888.087346461305;5901.911471327933;5912.379200184005;5922.644641321534;5773.220615216976;5847.795327163529;5933.881322373529;5997.250343707036;6077.665728384893;5885.735141918624;5908.675022873783;5913.408659134138;5943.613665111754;5982.760689572368;5783.248177736951;5882.58801902251;5918.2898052234;5925;5938.61399009063;4494.505604383515;4568.360429539867;4598.793054312497;4603.746793926366;4659.813607455664;4106.281400754916;4109.219248342805;4129.421880936671;4139.399787030924;4165.958340416618;5529.612927095145;5657.830265092054;5759.625624334405;5772.509336706427;5798.739056742456;5971.552133589998;6022.7289771959495;6062.878742425215;6064;6112.083187521907;5708.914366284509;5760.0783874579865;5785.913211301834;5802.448767367094;5819.418058194231;5054.418741844662;5140.845774626767;5162.328897243335;5251.36983561975;5266.92099618506;6207.34822843604;6257.217847769029;6280.0893870389455;6334.9683251584365;6347.301796802348;6062.757489700362;6087.634741915499;6090.543209259235;6101.054336577838;6116.174316467398;5823.126531020381;6011.428914253139;6062.5453091019235;6114.388610881663;6301.4643755279485;5863.970680146572;5894.970525147347;5897.5281977440845;5909.084091965752;5976.372480889618;5996.850078748066;6000.979833428415;6006.729697163725;6007.128966299774;6009.639421634716;5548.916766248429;5715.771369145187;5734.3978882218435;5783.8554036149435;5866.061430171152;5919.6152250103605;5929;5936.762529498945;5940.762369505344;5975.193348897843;5764.769409223584;5771.5671324649975;5800.390958949419;5825.533957283323;5838.211841401357;5583.385827559046;5769.105788602773;5808.651480911159;5931.3178984417345;5943.940560594338;5739.426057394394;5799.536037117105;5810.680412577349;5813.854653633693;5814.680192589448 09:59:00 INFO - 2015-11-25 09:59:00,013 DEBUG : BROWSER_OUTPUT: |1;ext.html;566.6033776356549;578.228065532514;579.6695883346495;580.2543731305274;585.1456872965472;40223.59776402235;40962.7951860241;41077.3838392424;41092.38361424581;41729.79135104324;42623.360649590206;43021.56978430215;43138.7843060785;43218.56781432185;43237;8772;8859.557022148867;8932.10678932108;8949.239314658229;8955.865662015074;8667.523286219255;8795.208431241186;8807.07525709801;8807.2513836324;8815.911840881605;8679.522626255553;8740.694075707352;8752.343574231927;8759;8768.34237432192;8717.825643487127;8827.117288271185;8833.11668833118;8848.424852384638;8865.06916773736;8628.35287353451;8635.568221588896;8748.168923952215;8751.34364922627;8765.956170219171;39983.80008099941;40258.597414025775;40611.390829137585;40848;40961.38557921634;3260.1686569924755;3304.7190988765983;3323.6842499962463;3422.3155368926064;4064.6138616831354;30380.392392152033;30956.38087238265;31053.84473077643;31296.37407251865;31858.681413185805;3106.6689164375475;3143.701348371901;3166.5881302547014;3169.413658473167;3193.5050067239613;259210;262852;263771;264824;265444;58346.708266458394;58857.70571147117;59437;59476.70261648664;59553.40446595479;72239;73032.63483682653;73190.26809731941;73238;73278.6336068327;16310.102944338176;16374.426895058616;16428.1785910704;16450.588735281475;16480.17599120039;12538.373081345897;12582.685432864251;12664;12682;12704.110712250238;20669.276575319793;20705.378838634864;20903.79096209049;20979.790202097785;20991.16035358599;7725.613719314012;7859.174786647434;7950.602469876483;8013.2387423194705;8069.677212911418;941.712777602824;942.4439580647314;1019.2661283875598;1044.8641676582147;1075.381655548063;28939.855300723782;29067.70932290692;29430.705692942796;29559.408811823643;33066.504002439986;1017.7353887989176;1027.8612387327764;1029.953652085658;1046.6598355534481;1146.1518476327458;16480.83519164817;17437.21532531039;17452.214650340764;17471.213795379237;17645.911770441067;387607;391681;396909;397894;399770;55284.17073743897;55815.72092139594;56040;56063.719681402144;56256;1344;1359.6736783172166;1361.3057340756286;1364.2155760437788;1366.2212538852957;30234.069570955635;30613.387732245676;31162.065138045895;31319.21701957469;31387.529187062668;46780.53219467762;47299.290510643055;47363.52636473591;47363.76318118387;47377.76311118422;308383;310232;312287;313842;314065;32483.512747308338;46626.76686616545;46687.76656116698;47511.76244118757;48085.5191448081;44955;44976.77511612421;45373;45447.09105817799;45683.77158114188;37580.2483950314;37954.2409151821;38043.42934856035;38528;39377.40933885937;37771.43342849914;38585.80707096447;38835;38906;39151.80424097975;38660.6133938657;40775;41709.79145104255;41884.79057604692;42346.78826605847;29314.413711726073;31484.842575786974;34817;37078.44382334321;39087.6091239084;25657.10200142986;25945.870270648527;26062.869685652207;26102.34744131412;26167.085978994844 09:59:00 INFO - 2015-11-25 09:59:00,014 DEBUG : BROWSER_OUTPUT: |2;jquery.html;350.7386996687467;354.64004035903565;357.54234579737926;357.872955100939;358.06009225782316;14377;14466.421343146289;16284.91857540712;17298.65402691947;18306.176222070022;15732.134732589702;15784.21078946051;15788.052716836997;16075.196240187974;16248.75626865595;5125.154349532323;5337.226102215176;5373.865653358658;5386.245925570423;5386.73066346683;5157.587393008569;5301.575873930076;5317.574594032488;5318.069337865877;5323.201519772027;5206.505381988705;5275.60432967529;5285.920711189336;5303.390110137345;5490.423505531921;5487.75305111267;5523.28197334346;5554.944450555483;5565.332160140769;5573.944260557383;5281.52466278033;5339.492748188916;5354.223637572569;5357.1696387060065;5476.342838859323;12743;12946;12998.740025199537;14416.351264193136;20429.387118386472;2208.900599473028;2270.2281224383623;2305.4582173189287;2332.288651961151;2345.8592484450987;13508;13843.723125537534;13900.791488127687;14102.224377659224;14535.78196327056;1980.12874335292;1985.811347921945;1997.0414201183369;1997.820196182336;2018.2028098900998;65096.67451662759;71714;75489.62255188744;77195.61402193009;78373;15840.841591584167;15887.205639717968;15975.840241597436;16082.276297566637;16118.919405402898;24540.141095061586;24785.62821557678;24914.501709965698;25064.12275570346;25091.62362564563;8658.350623703247;8749.825003500022;8766.736997890073;8772.078931712205;8793.516356600321;7290.4167666586545;7321.963390183121;7347.08161479815;7361.8159546011775;7372.741954031583;4281.186574550888;7840.13758486566;8004.719834805754;8043.316318112909;8071.354291656653;1821.4990877508792;1873.3443294846695;1876.7560217171954;1876.8926333463323;1890.4612185527333;899.1952202778476;964.1467301438278;965.8020105878278;1007.853861190138;1032.8037672842136;16655;16969.321227150776;17048.2328295227;17497;17608.119594020503;896.1934259166802;922.9446233225893;937.8921424036187;947.190152419685;958.3339578992533;9694.51527423626;9825.852612210823;10172.949135254275;10198.388096714221;10372.740681483023;592312;614686;617516;622250;627462;79250.20749792429;80127.19872801198;81736.59131704303;82237.58881205675;85410.14589854146;1140.0138879868853;1148.8161894096904;1150.097173718627;1157.2477889371808;1163.086976723285;16113.194340283173;16246.756298655293;16271.59321016984;16342.754858676892;16569.171541423122;37300.81349593234;37804.81097594494;38382.424263636625;39169.6083039166;39516;48949.2657610129;49875.75062124666;50269.74865125651;50380;54419.72790136024;9728.659496917573;9734.464604446823;9877.753056173366;9942.850857237003;9966.950165249127;11977.64067077989;17435.82564174342;17884.195211215265;17962.371317003843;18165.727514087564;15031.173285469404;15106.848931510543;15166;15198.848011520186;15359.385624574892;14410.567682969528;14675.48635797742;14681.779773303195;14842;15196.772048419061;14663;14835.406583736529;14940.85059149438;15118.244087795787;15499.30253138634;9651.324407291422;9743.26925480606;9791.265655075755;9848.40909545401;9854.310198286052;10482.947585262329;10546.525406356886;14847.777283340543;15118.319675614845;15207.619809504851 09:59:00 INFO - 2015-11-25 09:59:00,014 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1509.0417584833626;1660.5931546771044;1717.6049508613019;1737.4266492057623;1740.4430582213688;7044.823879403018;7222.855542889139;7772;8013.479123856952;8067.435279530435;7364.263573642633;7541.245875412456;7589.316961473466;7603.505772124822;7620.466567340274;2313.1325752842686;2339.0526836631193;2349.8942547585316;2356.1046802215133;2359.127122964497;2323.279783267184;2336.5093330400637;2373.0151986925493;2373.6558199061124;2379.4884099918513;2282.7488976212603;2285.5885940530698;2316.733575638798;2323.163661082009;2324.8256380771427;2306.746257911628;2309.8036666883336;2326.348622385735;2347.7065366829147;2348.96476552851;2334.171369163956;2369.6919400478;2372.1697405907967;2373.4896997145524;2377.072941552796;7830.177831327743;7847.215278472164;7905.723299684483;7916.881246781303;7917.802054948615;4762.38089048425;4860.927086093711;4867.148249056401;4886.780094895738;4905.2887331337115;2993.6108305920307;3002.7447666948337;3036.6659667436566;3046.84765761711;3054.465468542996;947.5215016416735;960.8222478841367;968.9079537443931;972.2319367699508;978.4080631218081;10831.945840270748;10876.6193183239;10929.180311476672;10950.890491095146;10961.725956851064;4394.252976993918;4427.313766366218;4427.889302767392;4439.067795762926;4509.977450112729;9198.816023679623;9270.582823772946;9294;9301.302402319852;9349.15857572808;4885.487023862514;4924.803007879645;4946.554810067039;4959.107360675041;4971.527704868032;3902.5316961964177;4028.5568587455355;4384.649228061748;4448.844290449883;4502.887427814266;3737.1965027518672;3741.6904083570535;3749.2876353493293;3780.3573392523335;3796.335641262823;1220.9084318676134;1225.6445630766978;1227.147132742753;1237.8514578250667;1240.5782034108265;1307.0066749270622;1320.5246111399888;1334.9065565410522;1339.912905661129;1340.9329533523285;7298.781036568911;7368;7429.294217049372;7431.665575049135;7458.440616953751;485.58239913674214;490.71048081632006;492.70683943053825;493.6914428482199;502.17893743729263;5789.392113828072;5824.621399609012;5846.181534585162;5886.23478947734;5886.823395298148;9014.233790127913;9190.08099190089;9273.675421360354;9301.441913485212;9376.812463750686;9138.954305228432;9173.724788256362;9264.11990860867;9277.350585459088;9300.11648893354;6871.828204294832;7045.823854403783;7048.097013806244;7119.07452031243;7241.565506069653;7233.132024157135;7336.7065317386705;7422.406207503279;7433.8141546461775;7466.589337586485;8224.465409748347;8350.707725229588;8371.121032291521;8455.196756308142;8532.445391049561;8346.499210047417;8522.829543409222;8535.95732021336;8547.95726021366;8662.82674346497;6070.605410648293;6289.654069026247;6614.338566143347;7042.647867606702;7062.752803651848;7466.178720340649;7501.549907005598;7504.587247701429;7516.586587737727;7523.059617547807;6729.293424190585;6971.965140174267;7104.3961263293195;7190.029346038218;7268.164161121539;6961.547499412522;7014.123234595676;7083.2208457068655;7091;7109.964450177716;6983.615901125487;6994.405475534637;7206.099237595301;7215.639218039182;7223.1693355264815;6452;6453.386928241809;6458.773942911976;6461.450776684034;6473.255575608865;6575.408213260829;6603.108580341785;6608.173978252718;6623.668816559057;6646.667666616553 09:59:00 INFO - 2015-11-25 09:59:00,014 DEBUG : BROWSER_OUTPUT: |4;prototype.html;330.8874982505948;335.5084800766876;342.2436415521696;346.72088968380444;351.2623490669594;6184.226971628546;6252.906206406908;6283.27742309634;6339.461145802612;6369.172007639012;6043.6675982820925;6232.252129744437;6246.344133865936;6249.65626890522;6256.937430625692;4766.2374020156785;4784.83253086142;4799.760011999403;4807.447143578498;4811.975940120295;4798.83204087857;4805.447373552051;4817.470078291385;4820.204666230085;4822.782974766126;4668.5098064703225;4720.221163508017;4731.408573928259;4750.21621432465;4753.073150735608;4737.0525894821;4744.928826067595;4749.952500474986;4750.90498190036;4767.356406885096;4018.819153138116;4110.897227569305;4171.853985110537;4176.248275310443;4185.016521117535;5890.793822216202;5902.937471255173;5928.258967629046;5938.168656388109;5968.4031596840405;5449.509544141007;5466.180072989044;5469.562435005229;5470.070088084995;5483.369412517572;6497.090407342976;6573.737050517973;6601.537892347537;6608.504362172856;6622.569532980341;4427;4510.030343476168;4512.751798651073;4514.954850451486;4529.954700452986;6857.794266172023;6891.448684105259;6927.445804335692;6927.930720692779;6941.68762405693;4548.044910568751;4555.2711566149255;4569.29175977724;4574;4586.289125185602;4547.15877562652;4610.97694511532;4629.421322334708;4635.837745678885;4636.165490211794;5449.264349312872;5451.563874889999;5456.208849716769;5459.754311056012;5463.426340234298;6702.430293425114;6708.429783468362;6711.261761206263;6711.530192886451;6711.63086030273;1650.298623085184;1658.3615308106466;1664.60049588098;1666.1086318819373;1670.2901266961735;1092.852464917226;1102.0026875677524;1107.95568177272;1123.5730422439585;1124.5951457475303;6412.9679351602945;6549.2468366137555;6561.14705088345;6564.212294524592;6566.244881838554;6919.065926099912;6924;6934.7226110955;6943.51395402317;6944.305569443116;6420.486361091102;6540.574862633914;6551;6554.868902621921;6559.016147577901;3740.4576336431064;3758.1732018955786;3759.154190307159;3759.3045286622064;3759.755615884959;7053.788386348418;7253.673584688701;7253.891191632129;7256.492045556866;7276.927230727731;7080.681369338392;7098.254683258287;7104.573725576482;7115.6086415247655;7160.462965277625;7031.367176953996;7182.2458641842895;7203.89194162088;7216.458765592497;7222.927770722225;6955.339242771928;6964.199117101598;6966.477514186355;6967.512274140761;6971.3028697129885;6831.795046148623;6859.416949559144;6873.656317184221;6878.415334696407;6887.5204926225515;7070.71717131309;7187.5687458752645;7193.784186474414;7201;7233.095863017123;4612.86161415158;4638.8608341749805;4652.32541281519;4655.348251244761;4668.743219122845;6913.3777959981835;6943.652817359213;6955.826104347432;6973.198082220407;6979.987901754319;6585.407313341822;6727.058211850247;6756.121704178426;6764.763233286812;6766.289539598271;6696.928491441346;6712.53012289135;6720.663966801542;6733.66331683404;6756.763513277208;6574.934250657623;6577.539572229898;6580.967095164494;6581.276059633532;6588.703508342041;6646.501512386493;6792.592444453348;6796.76211332601;6801.693923773343;6820.590764554143;6577.736890524326;6584.604923704593;6588.143541339595;6595.472362211108;6597.703103360456 09:59:00 INFO - 2015-11-25 09:59:00,014 DEBUG : BROWSER_OUTPUT: |5;yui.html;722.0719980479156;730.9744158954436;772.3666593393416;861.9008813986394;866.0257210638032;13637.045406821526;13647.52233671823;13658.453661853539;13701.725965480684;13727;12678.492860285578;12807.295598742065;12821.038422118356;12823.807642885364;12850.48598056079;821.2034326703089;824.9670013199465;836.188896770135;838.4508147163588;845.623697454633;841.4110122913985;845.3575282785097;846.0481957797477;846.2003406780594;849.5115308697524;872.2803686958284;877.8858748362704;878.4597472554365;880.5817236812527;882.7748924024365;859.1923591823728;861.2464093917822;863.9697610583631;865.8181781825824;866.5147517390219;644.5230529408204;646.9514786391039;650.4731167754087;651.7295322441181;652.1359199061263;12833.422495987703;12859.935700321439;12911.160774549624;12926.836584707422;12951.287679177642;11335.716607084807;11828.763424731456;11941.343226122559;12100.878991210151;12122.454489547992;9904.207663386984;9934.850977235346;9994.10053095225;10092.495375231209;10334.793304133951;3544.8404821783083;3588.910277243064;3605.1167463971497;3622.2393297407575;3686.5760437549757;46605;46814.2977855329;46875.29687054697;47079.529204707855;47184.05631887343;9781.217502599917;9803.264755143393;9879.16027137687;9998.600055997824;10184.541695623715;17444.302227910743;17616.119194040246;17623.471295861145;17636.20637071335;17662;7328.560286382727;7408.370288525536;7458.47790654649;7502.699892004259;7549.962250188714;4100.91798164035;4224.725392849455;4279.315309550457;4279.764612946318;4288.48538175421;3137.8117312961294;3169.920751981173;3175.158582975513;3176.5076413156;3180.904572862818;555.8693706978856;575.2808988764045;576.2019602850082;585.9794907178228;589.8436914217735;518.3572370260849;615.4368752591419;637.9330170332141;642.2517766801717;696.4463251714899;8281.710140145065;8379.874301885477;8497;8539.487630742175;8558.443701159405;552.505507570722;575.9020966435716;697.3584302441766;700.1318365227082;735.8197241675716;3834.9041273968373;3975.403689446606;4120.320147175682;4140.15126898985;4243.68172387066;57747;57996;58023.4197658018;58041;58107;48222.27666585004;49506;50084.4940854144;50616.74691626518;50904.74547627238;47306.290405643944;47671;48127.75936120297;48283.517164828605;48498;8047.919520804717;8067.919320806717;8274.627641756257;8311.584420778816;8459.365547583833;34209.31581368409;34316.31367372689;39829.60170398259;41655;42074.57925420706;49744.75127624339;54357;54456.727716361165;54966;56610.433895662165;11543.76912461763;11726.648200553997;12048.939755301168;12303.507859685513;12828.55100071493;11616.244944078608;11717.648470545897;11844.466998984894;12058.698532536757;12567.622971310875;10552.947235263775;10836.891631083588;10885.292455990335;10951.288166269167;11270.273233848882;10728.088112510211;11017.338959662125;11037.39294338787;11108.777824443305;11549;9827.459489727848;9971.202303815533;10825.188110891871;10881.673549793519;11117.666470005914;9332.626694932127;9537.904620953701;9767.413955162712;9812.116909478182;9832.508374581099;8415.368847336353;8556.957215214134;8560.272376848037;8932.151445612657;8937.821243575223 09:59:00 INFO - 2015-11-25 09:59:00,014 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:59:00 INFO - 2015-11-25 09:59:00,014 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:59:00 INFO - 2015-11-25 09:59:00,014 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,305 09:59:00 INFO - 2015-11-25 09:59:00,014 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:59:00 INFO - 2015-11-25 09:59:00,014 DEBUG : BROWSER_OUTPUT: __startTimestamp1448474340007__endTimestamp 09:59:00 INFO - 2015-11-25 09:59:00,014 DEBUG : BROWSER_OUTPUT: 09:59:00 INFO - 2015-11-25 09:59:00,014 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:59:00 INFO - 2015-11-25 09:59:00,014 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:59:00 INFO - 2015-11-25 09:59:00,015 DEBUG : BROWSER_OUTPUT: 09:59:00 INFO - 2015-11-25 09:59:00,016 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5562.56 Median:5785.91 stddev:626.84 (10.8%) stddev-sans-first:602.17 09:59:00 INFO - 2015-11-25 09:59:00,016 DEBUG : BROWSER_OUTPUT: Values: 3259.7 3290.9 3330.2 3346.7 3351.9 5303.0 5588.9 5594.8 5601.5 5601.7 5429.6 5496.3 5528.4 5553.0 5632.6 4911.1 5018.6 5085.2 5099.3 5107.8 5103.9 5116.0 5154.8 5225.9 5240.7 4958.6 5070.4 5118.0 5150.2 5233.7 4972.3 5092.0 5094.3 5109.7 5119.7 5082.4 5119.0 5136.2 5136.5 5151.7 5159.0 5225.6 5261.4 5422.4 5467.3 6028.0 6043.2 6063.0 6087.5 6097.3 6091.6 6111.9 6113.5 6129.0 6136.7 5414.5 5476.9 5511.1 5648.7 5664.0 6097.9 6154.4 6155.2 6163.3 6177.4 5860.7 5888.1 5901.9 5912.4 5922.6 5773.2 5847.8 5933.9 5997.3 6077.7 5885.7 5908.7 5913.4 5943.6 5982.8 5783.2 5882.6 5918.3 5925.0 5938.6 4494.5 4568.4 4598.8 4603.7 4659.8 4106.3 4109.2 4129.4 4139.4 4166.0 5529.6 5657.8 5759.6 5772.5 5798.7 5971.6 6022.7 6062.9 6064.0 6112.1 5708.9 5760.1 5785.9 5802.4 5819.4 5054.4 5140.8 5162.3 5251.4 5266.9 6207.3 6257.2 6280.1 6335.0 6347.3 6062.8 6087.6 6090.5 6101.1 6116.2 5823.1 6011.4 6062.5 6114.4 6301.5 5864.0 5895.0 5897.5 5909.1 5976.4 5996.9 6001.0 6006.7 6007.1 6009.6 5548.9 5715.8 5734.4 5783.9 5866.1 5919.6 5929.0 5936.8 5940.8 5975.2 5764.8 5771.6 5800.4 5825.5 5838.2 5583.4 5769.1 5808.7 5931.3 5943.9 5739.4 5799.5 5810.7 5813.9 5814.7 09:59:00 INFO - 2015-11-25 09:59:00,016 DEBUG : BROWSER_OUTPUT: 09:59:00 INFO - 2015-11-25 09:59:00,016 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:50535.69 Median:218143.20 stddev:86005.12 (39.4%) stddev-sans-first:86164.55 09:59:00 INFO - 2015-11-25 09:59:00,016 DEBUG : BROWSER_OUTPUT: Values: 566.6 578.2 579.7 580.3 585.1 40223.6 40962.8 41077.4 41092.4 41729.8 42623.4 43021.6 43138.8 43218.6 43237.0 8772.0 8859.6 8932.1 8949.2 8955.9 8667.5 8795.2 8807.1 8807.3 8815.9 8679.5 8740.7 8752.3 8759.0 8768.3 8717.8 8827.1 8833.1 8848.4 8865.1 8628.4 8635.6 8748.2 8751.3 8766.0 39983.8 40258.6 40611.4 40848.0 40961.4 3260.2 3304.7 3323.7 3422.3 4064.6 30380.4 30956.4 31053.8 31296.4 31858.7 3106.7 3143.7 3166.6 3169.4 3193.5 259210.0 262852.0 263771.0 264824.0 265444.0 58346.7 58857.7 59437.0 59476.7 59553.4 72239.0 73032.6 73190.3 73238.0 73278.6 16310.1 16374.4 16428.2 16450.6 16480.2 12538.4 12582.7 12664.0 12682.0 12704.1 20669.3 20705.4 20903.8 20979.8 20991.2 7725.6 7859.2 7950.6 8013.2 8069.7 941.7 942.4 1019.3 1044.9 1075.4 28939.9 29067.7 29430.7 29559.4 33066.5 1017.7 1027.9 1030.0 1046.7 1146.2 16480.8 17437.2 17452.2 17471.2 17645.9 387607.0 391681.0 396909.0 397894.0 399770.0 55284.2 55815.7 56040.0 56063.7 56256.0 1344.0 1359.7 1361.3 1364.2 1366.2 30234.1 30613.4 31162.1 31319.2 31387.5 46780.5 47299.3 47363.5 47363.8 47377.8 308383.0 310232.0 312287.0 313842.0 314065.0 32483.5 46626.8 46687.8 47511.8 48085.5 44955.0 44976.8 45373.0 45447.1 45683.8 37580.2 37954.2 38043.4 38528.0 39377.4 37771.4 38585.8 38835.0 38906.0 39151.8 38660.6 40775.0 41709.8 41884.8 42346.8 29314.4 31484.8 34817.0 37078.4 39087.6 25657.1 25945.9 26062.9 26102.3 26167.1 09:59:00 INFO - 2015-11-25 09:59:00,016 DEBUG : BROWSER_OUTPUT: 09:59:00 INFO - 2015-11-25 09:59:00,016 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:32187.34 Median:352.69 stddev:100448.19 (28480.6%) stddev-sans-first:100701.53 09:59:00 INFO - 2015-11-25 09:59:00,018 DEBUG : BROWSER_OUTPUT: Values: 350.7 354.6 357.5 357.9 358.1 14377.0 14466.4 16284.9 17298.7 18306.2 15732.1 15784.2 15788.1 16075.2 16248.8 5125.2 5337.2 5373.9 5386.2 5386.7 5157.6 5301.6 5317.6 5318.1 5323.2 5206.5 5275.6 5285.9 5303.4 5490.4 5487.8 5523.3 5554.9 5565.3 5573.9 5281.5 5339.5 5354.2 5357.2 5476.3 12743.0 12946.0 12998.7 14416.4 20429.4 2208.9 2270.2 2305.5 2332.3 2345.9 13508.0 13843.7 13900.8 14102.2 14535.8 1980.1 1985.8 1997.0 1997.8 2018.2 65096.7 71714.0 75489.6 77195.6 78373.0 15840.8 15887.2 15975.8 16082.3 16118.9 24540.1 24785.6 24914.5 25064.1 25091.6 8658.4 8749.8 8766.7 8772.1 8793.5 7290.4 7322.0 7347.1 7361.8 7372.7 4281.2 7840.1 8004.7 8043.3 8071.4 1821.5 1873.3 1876.8 1876.9 1890.5 899.2 964.1 965.8 1007.9 1032.8 16655.0 16969.3 17048.2 17497.0 17608.1 896.2 922.9 937.9 947.2 958.3 9694.5 9825.9 10172.9 10198.4 10372.7 592312.0 614686.0 617516.0 622250.0 627462.0 79250.2 80127.2 81736.6 82237.6 85410.1 1140.0 1148.8 1150.1 1157.2 1163.1 16113.2 16246.8 16271.6 16342.8 16569.2 37300.8 37804.8 38382.4 39169.6 39516.0 48949.3 49875.8 50269.7 50380.0 54419.7 9728.7 9734.5 9877.8 9942.9 9967.0 11977.6 17435.8 17884.2 17962.4 18165.7 15031.2 15106.8 15166.0 15198.8 15359.4 14410.6 14675.5 14681.8 14842.0 15196.8 14663.0 14835.4 14940.9 15118.2 15499.3 9651.3 9743.3 9791.3 9848.4 9854.3 10482.9 10546.5 14847.8 15118.3 15207.6 09:59:00 INFO - 2015-11-25 09:59:00,018 DEBUG : BROWSER_OUTPUT: 09:59:00 INFO - 2015-11-25 09:59:00,018 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5483.03 Median:6467.35 stddev:2830.12 (43.8%) stddev-sans-first:2822.29 09:59:00 INFO - 2015-11-25 09:59:00,019 DEBUG : BROWSER_OUTPUT: Values: 1509.0 1660.6 1717.6 1737.4 1740.4 7044.8 7222.9 7772.0 8013.5 8067.4 7364.3 7541.2 7589.3 7603.5 7620.5 2313.1 2339.1 2349.9 2356.1 2359.1 2323.3 2336.5 2373.0 2373.7 2379.5 2282.7 2285.6 2316.7 2323.2 2324.8 2306.7 2309.8 2326.3 2347.7 2349.0 2334.2 2369.7 2372.2 2373.5 2377.1 7830.2 7847.2 7905.7 7916.9 7917.8 4762.4 4860.9 4867.1 4886.8 4905.3 2993.6 3002.7 3036.7 3046.8 3054.5 947.5 960.8 968.9 972.2 978.4 10831.9 10876.6 10929.2 10950.9 10961.7 4394.3 4427.3 4427.9 4439.1 4510.0 9198.8 9270.6 9294.0 9301.3 9349.2 4885.5 4924.8 4946.6 4959.1 4971.5 3902.5 4028.6 4384.6 4448.8 4502.9 3737.2 3741.7 3749.3 3780.4 3796.3 1220.9 1225.6 1227.1 1237.9 1240.6 1307.0 1320.5 1334.9 1339.9 1340.9 7298.8 7368.0 7429.3 7431.7 7458.4 485.6 490.7 492.7 493.7 502.2 5789.4 5824.6 5846.2 5886.2 5886.8 9014.2 9190.1 9273.7 9301.4 9376.8 9139.0 9173.7 9264.1 9277.4 9300.1 6871.8 7045.8 7048.1 7119.1 7241.6 7233.1 7336.7 7422.4 7433.8 7466.6 8224.5 8350.7 8371.1 8455.2 8532.4 8346.5 8522.8 8536.0 8548.0 8662.8 6070.6 6289.7 6614.3 7042.6 7062.8 7466.2 7501.5 7504.6 7516.6 7523.1 6729.3 6972.0 7104.4 7190.0 7268.2 6961.5 7014.1 7083.2 7091.0 7110.0 6983.6 6994.4 7206.1 7215.6 7223.2 6452.0 6453.4 6458.8 6461.5 6473.3 6575.4 6603.1 6608.2 6623.7 6646.7 09:59:00 INFO - 2015-11-25 09:59:00,019 DEBUG : BROWSER_OUTPUT: 09:59:00 INFO - 2015-11-25 09:59:00,019 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5577.34 Median:6416.73 stddev:1714.36 (26.7%) stddev-sans-first:1673.33 09:59:00 INFO - 2015-11-25 09:59:00,021 DEBUG : BROWSER_OUTPUT: Values: 330.9 335.5 342.2 346.7 351.3 6184.2 6252.9 6283.3 6339.5 6369.2 6043.7 6232.3 6246.3 6249.7 6256.9 4766.2 4784.8 4799.8 4807.4 4812.0 4798.8 4805.4 4817.5 4820.2 4822.8 4668.5 4720.2 4731.4 4750.2 4753.1 4737.1 4744.9 4750.0 4750.9 4767.4 4018.8 4110.9 4171.9 4176.2 4185.0 5890.8 5902.9 5928.3 5938.2 5968.4 5449.5 5466.2 5469.6 5470.1 5483.4 6497.1 6573.7 6601.5 6608.5 6622.6 4427.0 4510.0 4512.8 4515.0 4530.0 6857.8 6891.4 6927.4 6927.9 6941.7 4548.0 4555.3 4569.3 4574.0 4586.3 4547.2 4611.0 4629.4 4635.8 4636.2 5449.3 5451.6 5456.2 5459.8 5463.4 6702.4 6708.4 6711.3 6711.5 6711.6 1650.3 1658.4 1664.6 1666.1 1670.3 1092.9 1102.0 1108.0 1123.6 1124.6 6413.0 6549.2 6561.1 6564.2 6566.2 6919.1 6924.0 6934.7 6943.5 6944.3 6420.5 6540.6 6551.0 6554.9 6559.0 3740.5 3758.2 3759.2 3759.3 3759.8 7053.8 7253.7 7253.9 7256.5 7276.9 7080.7 7098.3 7104.6 7115.6 7160.5 7031.4 7182.2 7203.9 7216.5 7222.9 6955.3 6964.2 6966.5 6967.5 6971.3 6831.8 6859.4 6873.7 6878.4 6887.5 7070.7 7187.6 7193.8 7201.0 7233.1 4612.9 4638.9 4652.3 4655.3 4668.7 6913.4 6943.7 6955.8 6973.2 6980.0 6585.4 6727.1 6756.1 6764.8 6766.3 6696.9 6712.5 6720.7 6733.7 6756.8 6574.9 6577.5 6581.0 6581.3 6588.7 6646.5 6792.6 6796.8 6801.7 6820.6 6577.7 6584.6 6588.1 6595.5 6597.7 09:59:00 INFO - 2015-11-25 09:59:00,021 DEBUG : BROWSER_OUTPUT: 09:59:00 INFO - 2015-11-25 09:59:00,021 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14022.60 Median:50760.75 stddev:16647.41 (32.8%) stddev-sans-first:16664.15 09:59:00 INFO - 2015-11-25 09:59:00,021 DEBUG : BROWSER_OUTPUT: Values: 722.1 731.0 772.4 861.9 866.0 13637.0 13647.5 13658.5 13701.7 13727.0 12678.5 12807.3 12821.0 12823.8 12850.5 821.2 825.0 836.2 838.5 845.6 841.4 845.4 846.0 846.2 849.5 872.3 877.9 878.5 880.6 882.8 859.2 861.2 864.0 865.8 866.5 644.5 647.0 650.5 651.7 652.1 12833.4 12859.9 12911.2 12926.8 12951.3 11335.7 11828.8 11941.3 12100.9 12122.5 9904.2 9934.9 9994.1 10092.5 10334.8 3544.8 3588.9 3605.1 3622.2 3686.6 46605.0 46814.3 46875.3 47079.5 47184.1 9781.2 9803.3 9879.2 9998.6 10184.5 17444.3 17616.1 17623.5 17636.2 17662.0 7328.6 7408.4 7458.5 7502.7 7550.0 4100.9 4224.7 4279.3 4279.8 4288.5 3137.8 3169.9 3175.2 3176.5 3180.9 555.9 575.3 576.2 586.0 589.8 518.4 615.4 637.9 642.3 696.4 8281.7 8379.9 8497.0 8539.5 8558.4 552.5 575.9 697.4 700.1 735.8 3834.9 3975.4 4120.3 4140.2 4243.7 57747.0 57996.0 58023.4 58041.0 58107.0 48222.3 49506.0 50084.5 50616.7 50904.7 47306.3 47671.0 48127.8 48283.5 48498.0 8047.9 8067.9 8274.6 8311.6 8459.4 34209.3 34316.3 39829.6 41655.0 42074.6 49744.8 54357.0 54456.7 54966.0 56610.4 11543.8 11726.6 12048.9 12303.5 12828.6 11616.2 11717.6 11844.5 12058.7 12567.6 10552.9 10836.9 10885.3 10951.3 11270.3 10728.1 11017.3 11037.4 11108.8 11549.0 9827.5 9971.2 10825.2 10881.7 11117.7 9332.6 9537.9 9767.4 9812.1 9832.5 8415.4 8557.0 8560.3 8932.2 8937.8 09:59:00 INFO - 2015-11-25 09:59:00,021 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:59:00 INFO - 2015-11-25 09:59:00,021 DEBUG : BROWSER_OUTPUT: 09:59:00 INFO - 2015-11-25 09:59:00,665 INFO : Browser exited with error code: 0 09:59:00 INFO - 2015-11-25 09:59:00,687 INFO : Completed test dromaeo_css (00:18:30) 09:59:00 INFO - 2015-11-25 09:59:00,687 INFO : Starting test kraken 09:59:00 INFO - 2015-11-25 09:59:00,687 DEBUG : operating with platform_type : linux_ 09:59:00 INFO - 2015-11-25 09:59:00,687 INFO : Initialising browser for kraken test... 09:59:00 INFO - 2015-11-25 09:59:00,694 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpqjX8zy/profile http://localhost:43530/getInfo.html 09:59:05 INFO - 2015-11-25 09:59:05,162 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:59:05 INFO - 2015-11-25 09:59:05,162 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:59:05 INFO - 2015-11-25 09:59:05,162 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:59:05 INFO - 2015-11-25 09:59:05,162 DEBUG : BROWSER_OUTPUT: __metrics 09:59:06 INFO - 2015-11-25 09:59:06,181 INFO : Browser initialized. 09:59:06 INFO - 2015-11-25 09:59:06,182 INFO : Running cycle 1/1 for kraken test... 09:59:06 INFO - 2015-11-25 09:59:06,182 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpqjX8zy/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:59:17 INFO - 2015-11-25 09:59:17,932 DEBUG : BROWSER_OUTPUT: RSS: Main: 140763136 09:59:17 INFO - 2015-11-25 09:59:17,932 DEBUG : BROWSER_OUTPUT: 09:59:20 INFO - 2015-11-25 09:59:20,428 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43530/tests/kraken/driver.html?testName=audio-beat-detection) 09:59:21 INFO - 2015-11-25 09:59:21,030 DEBUG : BROWSER_OUTPUT: RSS: Main: 140423168 09:59:21 INFO - 2015-11-25 09:59:21,030 DEBUG : BROWSER_OUTPUT: 09:59:23 INFO - 2015-11-25 09:59:23,673 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43530/tests/kraken/driver.html?testName=audio-dft) 09:59:24 INFO - 2015-11-25 09:59:24,260 DEBUG : BROWSER_OUTPUT: RSS: Main: 141127680 09:59:24 INFO - 2015-11-25 09:59:24,261 DEBUG : BROWSER_OUTPUT: 09:59:27 INFO - 2015-11-25 09:59:27,114 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43530/tests/kraken/driver.html?testName=audio-fft) 09:59:27 INFO - 2015-11-25 09:59:27,687 DEBUG : BROWSER_OUTPUT: RSS: Main: 135245824 09:59:27 INFO - 2015-11-25 09:59:27,687 DEBUG : BROWSER_OUTPUT: 09:59:29 INFO - 2015-11-25 09:59:29,699 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43530/tests/kraken/driver.html?testName=audio-oscillator) 09:59:30 INFO - 2015-11-25 09:59:30,302 DEBUG : BROWSER_OUTPUT: RSS: Main: 136019968 09:59:30 INFO - 2015-11-25 09:59:30,302 DEBUG : BROWSER_OUTPUT: 09:59:32 INFO - 2015-11-25 09:59:32,521 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43530/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:59:33 INFO - 2015-11-25 09:59:33,102 DEBUG : BROWSER_OUTPUT: RSS: Main: 137306112 09:59:33 INFO - 2015-11-25 09:59:33,102 DEBUG : BROWSER_OUTPUT: 09:59:36 INFO - 2015-11-25 09:59:36,127 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43530/tests/kraken/driver.html?testName=imaging-darkroom) 09:59:36 INFO - 2015-11-25 09:59:36,714 DEBUG : BROWSER_OUTPUT: RSS: Main: 137826304 09:59:36 INFO - 2015-11-25 09:59:36,714 DEBUG : BROWSER_OUTPUT: 09:59:40 INFO - 2015-11-25 09:59:40,138 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43530/tests/kraken/driver.html?testName=imaging-desaturate) 09:59:40 INFO - 2015-11-25 09:59:40,739 DEBUG : BROWSER_OUTPUT: RSS: Main: 137760768 09:59:40 INFO - 2015-11-25 09:59:40,739 DEBUG : BROWSER_OUTPUT: 09:59:43 INFO - 2015-11-25 09:59:43,643 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43530/tests/kraken/driver.html?testName=json-parse-financial) 09:59:44 INFO - 2015-11-25 09:59:44,228 DEBUG : BROWSER_OUTPUT: RSS: Main: 138620928 09:59:44 INFO - 2015-11-25 09:59:44,228 DEBUG : BROWSER_OUTPUT: 09:59:45 INFO - 2015-11-25 09:59:45,808 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43530/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:59:46 INFO - 2015-11-25 09:59:46,390 DEBUG : BROWSER_OUTPUT: RSS: Main: 138309632 09:59:46 INFO - 2015-11-25 09:59:46,391 DEBUG : BROWSER_OUTPUT: 09:59:48 INFO - 2015-11-25 09:59:48,265 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43530/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:59:48 INFO - 2015-11-25 09:59:48,875 DEBUG : BROWSER_OUTPUT: RSS: Main: 139120640 09:59:48 INFO - 2015-11-25 09:59:48,875 DEBUG : BROWSER_OUTPUT: 09:59:50 INFO - 2015-11-25 09:59:50,959 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43530/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:59:51 INFO - 2015-11-25 09:59:51,546 DEBUG : BROWSER_OUTPUT: RSS: Main: 139534336 09:59:51 INFO - 2015-11-25 09:59:51,546 DEBUG : BROWSER_OUTPUT: 09:59:54 INFO - 2015-11-25 09:59:54,021 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43530/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:59:54 INFO - 2015-11-25 09:59:54,611 DEBUG : BROWSER_OUTPUT: RSS: Main: 139341824 09:59:54 INFO - 2015-11-25 09:59:54,611 DEBUG : BROWSER_OUTPUT: 09:59:57 INFO - 2015-11-25 09:59:57,186 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43530/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:59:57 INFO - 2015-11-25 09:59:57,772 DEBUG : BROWSER_OUTPUT: RSS: Main: 139952128 09:59:57 INFO - 2015-11-25 09:59:57,772 DEBUG : BROWSER_OUTPUT: 09:59:59 INFO - 2015-11-25 09:59:59,449 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43530/tests/kraken/driver.html?testName=ai-astar) 09:59:59 INFO - 2015-11-25 09:59:59,700 DEBUG : BROWSER_OUTPUT: RSS: Main: 138145792 09:59:59 INFO - 2015-11-25 09:59:59,700 DEBUG : BROWSER_OUTPUT: 09:59:59 INFO - 2015-11-25 09:59:59,701 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:59:59 INFO - 2015-11-25 09:59:59,702 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:59:59 INFO - 2015-11-25 09:59:59,702 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:59:59 INFO - 2015-11-25 09:59:59,702 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:59:59 INFO - 2015-11-25 09:59:59,702 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;106;111;112;112;126;112;111;111;111 09:59:59 INFO - 2015-11-25 09:59:59,702 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;123;162;159;135;166;156;139;159;134 09:59:59 INFO - 2015-11-25 09:59:59,702 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;164;162;164;163;162;161;163;169;163 09:59:59 INFO - 2015-11-25 09:59:59,702 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;70;73;73;76;75;146;72;72;72 09:59:59 INFO - 2015-11-25 09:59:59,703 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;93;147;94;92;92;141;93;92;135 09:59:59 INFO - 2015-11-25 09:59:59,703 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;110;110;110;110;110;110;110 09:59:59 INFO - 2015-11-25 09:59:59,703 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;147;147;148;146;149;147;147;147 09:59:59 INFO - 2015-11-25 09:59:59,703 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;96;98;96;96;97;96;96 09:59:59 INFO - 2015-11-25 09:59:59,703 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;62;66;62;61;66;62;63;63;59;60 09:59:59 INFO - 2015-11-25 09:59:59,703 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;70;71;72;71;71;70;70;71;71;72 09:59:59 INFO - 2015-11-25 09:59:59,703 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;84;85;86;84;86;85;85;96;84 09:59:59 INFO - 2015-11-25 09:59:59,703 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;133;126;120;133;120;132;122;126;119;118 09:59:59 INFO - 2015-11-25 09:59:59,704 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;151;155;149;141;148;148;148;160;143;140 09:59:59 INFO - 2015-11-25 09:59:59,704 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;68;67;69;67;68;73;72;67;67;66 09:59:59 INFO - 2015-11-25 09:59:59,704 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:59:59 INFO - 2015-11-25 09:59:59,704 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:59:59 INFO - 2015-11-25 09:59:59,705 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,712 09:59:59 INFO - 2015-11-25 09:59:59,706 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:59:59 INFO - 2015-11-25 09:59:59,706 DEBUG : BROWSER_OUTPUT: __startTimestamp1448474399701__endTimestamp 09:59:59 INFO - 2015-11-25 09:59:59,706 DEBUG : BROWSER_OUTPUT: 09:59:59 INFO - 2015-11-25 09:59:59,709 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:59:59 INFO - 2015-11-25 09:59:59,709 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:59:59 INFO - 2015-11-25 09:59:59,709 DEBUG : BROWSER_OUTPUT: 09:59:59 INFO - 2015-11-25 09:59:59,709 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:112.20 Median:111.50 stddev:5.16 (4.6%) stddev-sans-first:5.41 09:59:59 INFO - 2015-11-25 09:59:59,710 DEBUG : BROWSER_OUTPUT: Values: 110.0 106.0 111.0 112.0 112.0 126.0 112.0 111.0 111.0 111.0 09:59:59 INFO - 2015-11-25 09:59:59,710 DEBUG : BROWSER_OUTPUT: 09:59:59 INFO - 2015-11-25 09:59:59,710 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:148.20 Median:157.50 stddev:14.52 (9.2%) stddev-sans-first:15.40 09:59:59 INFO - 2015-11-25 09:59:59,710 DEBUG : BROWSER_OUTPUT: Values: 149.0 123.0 162.0 159.0 135.0 166.0 156.0 139.0 159.0 134.0 09:59:59 INFO - 2015-11-25 09:59:59,710 DEBUG : BROWSER_OUTPUT: 09:59:59 INFO - 2015-11-25 09:59:59,710 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.30 Median:163.00 stddev:2.21 (1.4%) stddev-sans-first:2.30 09:59:59 INFO - 2015-11-25 09:59:59,710 DEBUG : BROWSER_OUTPUT: Values: 162.0 164.0 162.0 164.0 163.0 162.0 161.0 163.0 169.0 163.0 09:59:59 INFO - 2015-11-25 09:59:59,710 DEBUG : BROWSER_OUTPUT: 09:59:59 INFO - 2015-11-25 09:59:59,710 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.10 Median:72.50 stddev:23.22 (32.0%) stddev-sans-first:24.44 09:59:59 INFO - 2015-11-25 09:59:59,710 DEBUG : BROWSER_OUTPUT: Values: 72.0 70.0 73.0 73.0 76.0 75.0 146.0 72.0 72.0 72.0 09:59:59 INFO - 2015-11-25 09:59:59,710 DEBUG : BROWSER_OUTPUT: 09:59:59 INFO - 2015-11-25 09:59:59,711 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.30 Median:92.50 stddev:23.44 (25.3%) stddev-sans-first:24.36 09:59:59 INFO - 2015-11-25 09:59:59,711 DEBUG : BROWSER_OUTPUT: Values: 94.0 93.0 147.0 94.0 92.0 92.0 141.0 93.0 92.0 135.0 09:59:59 INFO - 2015-11-25 09:59:59,711 DEBUG : BROWSER_OUTPUT: 09:59:59 INFO - 2015-11-25 09:59:59,711 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.80 Median:110.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 09:59:59 INFO - 2015-11-25 09:59:59,711 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 110.0 110.0 110.0 110.0 110.0 110.0 110.0 09:59:59 INFO - 2015-11-25 09:59:59,711 DEBUG : BROWSER_OUTPUT: 09:59:59 INFO - 2015-11-25 09:59:59,711 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 09:59:59 INFO - 2015-11-25 09:59:59,711 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 147.0 147.0 148.0 146.0 149.0 147.0 147.0 147.0 09:59:59 INFO - 2015-11-25 09:59:59,711 DEBUG : BROWSER_OUTPUT: 09:59:59 INFO - 2015-11-25 09:59:59,711 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.30 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 09:59:59 INFO - 2015-11-25 09:59:59,711 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 96.0 98.0 96.0 96.0 97.0 96.0 96.0 09:59:59 INFO - 2015-11-25 09:59:59,712 DEBUG : BROWSER_OUTPUT: 09:59:59 INFO - 2015-11-25 09:59:59,712 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:62.40 Median:62.50 stddev:2.27 (3.6%) stddev-sans-first:2.40 09:59:59 INFO - 2015-11-25 09:59:59,712 DEBUG : BROWSER_OUTPUT: Values: 62.0 66.0 62.0 61.0 66.0 62.0 63.0 63.0 59.0 60.0 09:59:59 INFO - 2015-11-25 09:59:59,712 DEBUG : BROWSER_OUTPUT: 09:59:59 INFO - 2015-11-25 09:59:59,712 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.00 stddev:0.74 (1.0%) stddev-sans-first:0.71 09:59:59 INFO - 2015-11-25 09:59:59,712 DEBUG : BROWSER_OUTPUT: Values: 70.0 71.0 72.0 71.0 71.0 70.0 70.0 71.0 71.0 72.0 09:59:59 INFO - 2015-11-25 09:59:59,712 DEBUG : BROWSER_OUTPUT: 09:59:59 INFO - 2015-11-25 09:59:59,712 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:85.00 stddev:3.71 (4.4%) stddev-sans-first:3.79 09:59:59 INFO - 2015-11-25 09:59:59,712 DEBUG : BROWSER_OUTPUT: Values: 83.0 84.0 85.0 86.0 84.0 86.0 85.0 85.0 96.0 84.0 09:59:59 INFO - 2015-11-25 09:59:59,712 DEBUG : BROWSER_OUTPUT: 09:59:59 INFO - 2015-11-25 09:59:59,712 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:124.90 Median:126.00 stddev:5.99 (4.8%) stddev-sans-first:5.59 09:59:59 INFO - 2015-11-25 09:59:59,712 DEBUG : BROWSER_OUTPUT: Values: 133.0 126.0 120.0 133.0 120.0 132.0 122.0 126.0 119.0 118.0 09:59:59 INFO - 2015-11-25 09:59:59,712 DEBUG : BROWSER_OUTPUT: 09:59:59 INFO - 2015-11-25 09:59:59,712 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.30 Median:148.50 stddev:6.15 (4.1%) stddev-sans-first:6.44 09:59:59 INFO - 2015-11-25 09:59:59,712 DEBUG : BROWSER_OUTPUT: Values: 151.0 155.0 149.0 141.0 148.0 148.0 148.0 160.0 143.0 140.0 09:59:59 INFO - 2015-11-25 09:59:59,713 DEBUG : BROWSER_OUTPUT: 09:59:59 INFO - 2015-11-25 09:59:59,713 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.00 stddev:2.32 (3.4%) stddev-sans-first:2.46 09:59:59 INFO - 2015-11-25 09:59:59,713 DEBUG : BROWSER_OUTPUT: Values: 68.0 67.0 69.0 67.0 68.0 73.0 72.0 67.0 67.0 66.0 09:59:59 INFO - 2015-11-25 09:59:59,713 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:59:59 INFO - 2015-11-25 09:59:59,713 DEBUG : BROWSER_OUTPUT: 10:00:00 INFO - 2015-11-25 10:00:00,713 INFO : Browser exited with error code: 0 10:00:00 INFO - 2015-11-25 10:00:00,767 INFO : Completed test kraken (00:01:00) 10:00:00 INFO - 2015-11-25 10:00:00,767 INFO : Starting test v8_7 10:00:00 INFO - 2015-11-25 10:00:00,767 DEBUG : operating with platform_type : linux_ 10:00:00 INFO - 2015-11-25 10:00:00,767 INFO : Initialising browser for v8_7 test... 10:00:00 INFO - 2015-11-25 10:00:00,773 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFfxTZ4/profile http://localhost:43530/getInfo.html 10:00:05 INFO - 2015-11-25 10:00:05,336 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:00:05 INFO - 2015-11-25 10:00:05,336 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:00:05 INFO - 2015-11-25 10:00:05,336 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:00:05 INFO - 2015-11-25 10:00:05,336 DEBUG : BROWSER_OUTPUT: __metrics 10:00:06 INFO - 2015-11-25 10:00:06,381 INFO : Browser initialized. 10:00:06 INFO - 2015-11-25 10:00:06,382 INFO : Running cycle 1/1 for v8_7 test... 10:00:06 INFO - 2015-11-25 10:00:06,382 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFfxTZ4/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:00:18 INFO - 2015-11-25 10:00:18,173 DEBUG : BROWSER_OUTPUT: RSS: Main: 138969088 10:00:18 INFO - 2015-11-25 10:00:18,174 DEBUG : BROWSER_OUTPUT: 10:00:39 INFO - 2015-11-25 10:00:39,535 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43530/tests/v8_7/run.html (next: http://localhost:43530/tests/v8_7/run.html) 10:00:39 INFO - 2015-11-25 10:00:39,793 DEBUG : BROWSER_OUTPUT: RSS: Main: 137379840 10:00:39 INFO - 2015-11-25 10:00:39,793 DEBUG : BROWSER_OUTPUT: 10:00:39 INFO - 2015-11-25 10:00:39,793 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:00:39 INFO - 2015-11-25 10:00:39,793 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:00:39 INFO - 2015-11-25 10:00:39,793 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:00:39 INFO - 2015-11-25 10:00:39,793 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:00:39 INFO - 2015-11-25 10:00:39,794 DEBUG : BROWSER_OUTPUT: |0;Richards;166.05778811026238 10:00:39 INFO - 2015-11-25 10:00:39,794 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;193.38619222587508 10:00:39 INFO - 2015-11-25 10:00:39,794 DEBUG : BROWSER_OUTPUT: |2;Encrypt;281.0567734682406 10:00:39 INFO - 2015-11-25 10:00:39,794 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5170.103092783505 10:00:39 INFO - 2015-11-25 10:00:39,794 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1052.6315789473683 10:00:39 INFO - 2015-11-25 10:00:39,794 DEBUG : BROWSER_OUTPUT: |5;Earley;707.7140835102618 10:00:39 INFO - 2015-11-25 10:00:39,794 DEBUG : BROWSER_OUTPUT: |6;Boyer;12192.77108433735 10:00:39 INFO - 2015-11-25 10:00:39,794 DEBUG : BROWSER_OUTPUT: |7;RegExp;24829.268292682926 10:00:39 INFO - 2015-11-25 10:00:39,795 DEBUG : BROWSER_OUTPUT: |8;Splay;587.8894767783656 10:00:39 INFO - 2015-11-25 10:00:39,795 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 10:00:39 INFO - 2015-11-25 10:00:39,795 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:00:39 INFO - 2015-11-25 10:00:39,795 DEBUG : BROWSER_OUTPUT: __startTimestamp1448474439786__endTimestamp 10:00:39 INFO - 2015-11-25 10:00:39,795 DEBUG : BROWSER_OUTPUT: 10:00:39 INFO - 2015-11-25 10:00:39,795 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:00:39 INFO - 2015-11-25 10:00:39,795 DEBUG : BROWSER_OUTPUT: Number of tests: 10 10:00:39 INFO - 2015-11-25 10:00:39,795 DEBUG : BROWSER_OUTPUT: 10:00:39 INFO - 2015-11-25 10:00:39,795 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.06 Median:166.06 stddev:0.00 (0.0%) 10:00:39 INFO - 2015-11-25 10:00:39,795 DEBUG : BROWSER_OUTPUT: Values: 166.1 10:00:39 INFO - 2015-11-25 10:00:39,795 DEBUG : BROWSER_OUTPUT: 10:00:39 INFO - 2015-11-25 10:00:39,795 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:193.39 Median:193.39 stddev:0.00 (0.0%) 10:00:39 INFO - 2015-11-25 10:00:39,795 DEBUG : BROWSER_OUTPUT: Values: 193.4 10:00:39 INFO - 2015-11-25 10:00:39,795 DEBUG : BROWSER_OUTPUT: 10:00:39 INFO - 2015-11-25 10:00:39,795 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:281.06 Median:281.06 stddev:0.00 (0.0%) 10:00:39 INFO - 2015-11-25 10:00:39,796 DEBUG : BROWSER_OUTPUT: Values: 281.1 10:00:39 INFO - 2015-11-25 10:00:39,796 DEBUG : BROWSER_OUTPUT: 10:00:39 INFO - 2015-11-25 10:00:39,796 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5170.10 Median:5170.10 stddev:0.00 (0.0%) 10:00:39 INFO - 2015-11-25 10:00:39,796 DEBUG : BROWSER_OUTPUT: Values: 5170.1 10:00:39 INFO - 2015-11-25 10:00:39,796 DEBUG : BROWSER_OUTPUT: 10:00:39 INFO - 2015-11-25 10:00:39,796 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1052.63 Median:1052.63 stddev:0.00 (0.0%) 10:00:39 INFO - 2015-11-25 10:00:39,796 DEBUG : BROWSER_OUTPUT: Values: 1052.6 10:00:39 INFO - 2015-11-25 10:00:39,797 DEBUG : BROWSER_OUTPUT: 10:00:39 INFO - 2015-11-25 10:00:39,797 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:707.71 Median:707.71 stddev:0.00 (0.0%) 10:00:39 INFO - 2015-11-25 10:00:39,797 DEBUG : BROWSER_OUTPUT: Values: 707.7 10:00:39 INFO - 2015-11-25 10:00:39,797 DEBUG : BROWSER_OUTPUT: 10:00:39 INFO - 2015-11-25 10:00:39,797 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12192.77 Median:12192.77 stddev:0.00 (0.0%) 10:00:39 INFO - 2015-11-25 10:00:39,797 DEBUG : BROWSER_OUTPUT: Values: 12192.8 10:00:39 INFO - 2015-11-25 10:00:39,797 DEBUG : BROWSER_OUTPUT: 10:00:39 INFO - 2015-11-25 10:00:39,798 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24829.27 Median:24829.27 stddev:0.00 (0.0%) 10:00:39 INFO - 2015-11-25 10:00:39,798 DEBUG : BROWSER_OUTPUT: Values: 24829.3 10:00:39 INFO - 2015-11-25 10:00:39,798 DEBUG : BROWSER_OUTPUT: 10:00:39 INFO - 2015-11-25 10:00:39,798 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:587.89 Median:587.89 stddev:0.00 (0.0%) 10:00:39 INFO - 2015-11-25 10:00:39,798 DEBUG : BROWSER_OUTPUT: Values: 587.9 10:00:39 INFO - 2015-11-25 10:00:39,798 DEBUG : BROWSER_OUTPUT: 10:00:39 INFO - 2015-11-25 10:00:39,798 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 10:00:39 INFO - 2015-11-25 10:00:39,798 DEBUG : BROWSER_OUTPUT: Values: 4840.6 10:00:39 INFO - 2015-11-25 10:00:39,799 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:00:39 INFO - 2015-11-25 10:00:39,799 DEBUG : BROWSER_OUTPUT: 10:00:40 INFO - 2015-11-25 10:00:40,622 INFO : Browser exited with error code: 0 10:00:40 INFO - 2015-11-25 10:00:40,640 INFO : Completed test v8_7 (00:00:39) 10:00:40 INFO - 2015-11-25 10:00:40,821 INFO : Completed test suite (00:20:10) 10:00:40 INFO - 2015-11-25 10:00:40,822 DEBUG : Working with test: dromaeo_css 10:00:40 INFO - 2015-11-25 10:00:40,822 DEBUG : Generating results file: dromaeo_css 10:00:40 INFO - 2015-11-25 10:00:40,823 DEBUG : Working with test: kraken 10:00:40 INFO - 2015-11-25 10:00:40,823 DEBUG : Generating results file: kraken 10:00:40 INFO - 2015-11-25 10:00:40,823 INFO : javascript benchmark 10:00:40 INFO - 2015-11-25 10:00:40,823 DEBUG : Working with test: v8_7 10:00:40 INFO - 2015-11-25 10:00:40,823 DEBUG : Generating results file: v8_7 10:00:40 INFO - 2015-11-25 10:00:40,824 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:00:40 INFO - 2015-11-25 10:00:40,988 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,43]] 10:00:40 INFO - 2015-11-25 10:00:40,988 DEBUG : process_Request line: dromaeo_css 7244.63 graph.html#tests=[[72,1,43]] 10:00:40 INFO - 2015-11-25 10:00:40,989 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:00:41 INFO - 2015-11-25 10:00:41,027 DEBUG : process_Request line: kraken 1525.00 graph.html#tests=[[232,1,43]] 10:00:41 INFO - 2015-11-25 10:00:41,027 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:00:41 INFO - 2015-11-25 10:00:41,060 DEBUG : process_Request line: v8_7 21468.60 graph.html#tests=[[230,1,43]] 10:00:41 INFO - 2015-11-25 10:00:41,061 INFO : javascript benchmark 10:00:41 INFO - 2015-11-25 10:00:41,065 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3259.722923551498, 3290.9012729618103, 3330.2340461693793, 3346.7155291800195, 3351.882684106056, 5302.973485132578, 5588.916166257504, 5594.804181853636, 5601.467860553246, 5601.747921343539, 5429.619926605139, 5496.258005169301, 5528.44715528447, 5553.0, 5632.605717599769, 4911.066897289522, 5018.623603229754, 5085.211792172219, 5099.33708617881, 5107.795688172488, 5103.923441148386, 5116.0, 5154.793808247666, 5225.86935326618, 5240.6593571417925, 4958.603311735053, 5070.442251352349, 5117.974410127926, 5150.227465880129, 5233.685978841263, 4972.303877457159, 5092.032513822362, 5094.286799848024, 5109.744512774384, 5119.718415487147, 5082.415522214918, 5119.001794650045, 5136.152534831748, 5136.512031357052, 5151.665141765773, 5159.019786240602, 5225.581953443715, 5261.4475480074425, 5422.376426710939, 5467.289252397202, 6028.0, 6043.244594425697, 6062.999605065159, 6087.543434242405, 6097.2683278006925, 6091.604045737013, 6111.938880611181, 6113.510919126459, 6129.049997250433, 6136.693165341715, 5414.512693857571, 5476.91784623235, 5511.145772405243, 5648.6893220872835, 5664.037113690682, 6097.939020609826, 6154.446099851035, 6155.169052177898, 6163.291221509494, 6177.444030037318, 5860.736266868002, 5888.087346461305, 5901.911471327933, 5912.379200184005, 5922.644641321534, 5773.220615216976, 5847.795327163529, 5933.881322373529, 5997.250343707036, 6077.665728384893, 5885.735141918624, 5908.675022873783, 5913.408659134138, 5943.613665111754, 5982.760689572368, 5783.248177736951, 5882.58801902251, 5918.2898052234, 5925.0, 5938.61399009063, 4494.505604383515, 4568.360429539867, 4598.793054312497, 4603.746793926366, 4659.813607455664, 4106.281400754916, 4109.219248342805, 4129.421880936671, 4139.399787030924, 4165.958340416618, 5529.612927095145, 5657.830265092054, 5759.625624334405, 5772.509336706427, 5798.739056742456, 5971.552133589998, 6022.7289771959495, 6062.878742425215, 6064.0, 6112.083187521907, 5708.914366284509, 5760.0783874579865, 5785.913211301834, 5802.448767367094, 5819.418058194231, 5054.418741844662, 5140.845774626767, 5162.328897243335, 5251.36983561975, 5266.92099618506, 6207.34822843604, 6257.217847769029, 6280.0893870389455, 6334.9683251584365, 6347.301796802348, 6062.757489700362, 6087.634741915499, 6090.543209259235, 6101.054336577838, 6116.174316467398, 5823.126531020381, 6011.428914253139, 6062.5453091019235, 6114.388610881663, 6301.4643755279485, 5863.970680146572, 5894.970525147347, 5897.5281977440845, 5909.084091965752, 5976.372480889618, 5996.850078748066, 6000.979833428415, 6006.729697163725, 6007.128966299774, 6009.639421634716, 5548.916766248429, 5715.771369145187, 5734.3978882218435, 5783.8554036149435, 5866.061430171152, 5919.6152250103605, 5929.0, 5936.762529498945, 5940.762369505344, 5975.193348897843, 5764.769409223584, 5771.5671324649975, 5800.390958949419, 5825.533957283323, 5838.211841401357, 5583.385827559046, 5769.105788602773, 5808.651480911159, 5931.3178984417345, 5943.940560594338, 5739.426057394394, 5799.536037117105, 5810.680412577349, 5813.854653633693, 5814.680192589448], "unit": "ms", "name": "dojo.html", "value": 5521.451771069997}, {"lowerIsBetter": false, "replicates": [566.6033776356549, 578.228065532514, 579.6695883346495, 580.2543731305274, 585.1456872965472, 40223.59776402235, 40962.7951860241, 41077.3838392424, 41092.38361424581, 41729.79135104324, 42623.360649590206, 43021.56978430215, 43138.7843060785, 43218.56781432185, 43237.0, 8772.0, 8859.557022148867, 8932.10678932108, 8949.239314658229, 8955.865662015074, 8667.523286219255, 8795.208431241186, 8807.07525709801, 8807.2513836324, 8815.911840881605, 8679.522626255553, 8740.694075707352, 8752.343574231927, 8759.0, 8768.34237432192, 8717.825643487127, 8827.117288271185, 8833.11668833118, 8848.424852384638, 8865.06916773736, 8628.35287353451, 8635.568221588896, 8748.168923952215, 8751.34364922627, 8765.956170219171, 39983.80008099941, 40258.597414025775, 40611.390829137585, 40848.0, 40961.38557921634, 3260.1686569924755, 3304.7190988765983, 3323.6842499962463, 3422.3155368926064, 4064.6138616831354, 30380.392392152033, 30956.38087238265, 31053.84473077643, 31296.37407251865, 31858.681413185805, 3106.6689164375475, 3143.701348371901, 3166.5881302547014, 3169.413658473167, 3193.5050067239613, 259210.0, 262852.0, 263771.0, 264824.0, 265444.0, 58346.708266458394, 58857.70571147117, 59437.0, 59476.70261648664, 59553.40446595479, 72239.0, 73032.63483682653, 73190.26809731941, 73238.0, 73278.6336068327, 16310.102944338176, 16374.426895058616, 16428.1785910704, 16450.588735281475, 16480.17599120039, 12538.373081345897, 12582.685432864251, 12664.0, 12682.0, 12704.110712250238, 20669.276575319793, 20705.378838634864, 20903.79096209049, 20979.790202097785, 20991.16035358599, 7725.613719314012, 7859.174786647434, 7950.602469876483, 8013.2387423194705, 8069.677212911418, 941.712777602824, 942.4439580647314, 1019.2661283875598, 1044.8641676582147, 1075.381655548063, 28939.855300723782, 29067.70932290692, 29430.705692942796, 29559.408811823643, 33066.504002439986, 1017.7353887989176, 1027.8612387327764, 1029.953652085658, 1046.6598355534481, 1146.1518476327458, 16480.83519164817, 17437.21532531039, 17452.214650340764, 17471.213795379237, 17645.911770441067, 387607.0, 391681.0, 396909.0, 397894.0, 399770.0, 55284.17073743897, 55815.72092139594, 56040.0, 56063.719681402144, 56256.0, 1344.0, 1359.6736783172166, 1361.3057340756286, 1364.2155760437788, 1366.2212538852957, 30234.069570955635, 30613.387732245676, 31162.065138045895, 31319.21701957469, 31387.529187062668, 46780.53219467762, 47299.290510643055, 47363.52636473591, 47363.76318118387, 47377.76311118422, 308383.0, 310232.0, 312287.0, 313842.0, 314065.0, 32483.512747308338, 46626.76686616545, 46687.76656116698, 47511.76244118757, 48085.5191448081, 44955.0, 44976.77511612421, 45373.0, 45447.09105817799, 45683.77158114188, 37580.2483950314, 37954.2409151821, 38043.42934856035, 38528.0, 39377.40933885937, 37771.43342849914, 38585.80707096447, 38835.0, 38906.0, 39151.80424097975, 38660.6133938657, 40775.0, 41709.79145104255, 41884.79057604692, 42346.78826605847, 29314.413711726073, 31484.842575786974, 34817.0, 37078.44382334321, 39087.6091239084, 25657.10200142986, 25945.870270648527, 26062.869685652207, 26102.34744131412, 26167.085978994844], "unit": "ms", "name": "ext.html", "value": 19415.57781017524}, {"lowerIsBetter": false, "replicates": [350.7386996687467, 354.64004035903565, 357.54234579737926, 357.872955100939, 358.06009225782316, 14377.0, 14466.421343146289, 16284.91857540712, 17298.65402691947, 18306.176222070022, 15732.134732589702, 15784.21078946051, 15788.052716836997, 16075.196240187974, 16248.75626865595, 5125.154349532323, 5337.226102215176, 5373.865653358658, 5386.245925570423, 5386.73066346683, 5157.587393008569, 5301.575873930076, 5317.574594032488, 5318.069337865877, 5323.201519772027, 5206.505381988705, 5275.60432967529, 5285.920711189336, 5303.390110137345, 5490.423505531921, 5487.75305111267, 5523.28197334346, 5554.944450555483, 5565.332160140769, 5573.944260557383, 5281.52466278033, 5339.492748188916, 5354.223637572569, 5357.1696387060065, 5476.342838859323, 12743.0, 12946.0, 12998.740025199537, 14416.351264193136, 20429.387118386472, 2208.900599473028, 2270.2281224383623, 2305.4582173189287, 2332.288651961151, 2345.8592484450987, 13508.0, 13843.723125537534, 13900.791488127687, 14102.224377659224, 14535.78196327056, 1980.12874335292, 1985.811347921945, 1997.0414201183369, 1997.820196182336, 2018.2028098900998, 65096.67451662759, 71714.0, 75489.62255188744, 77195.61402193009, 78373.0, 15840.841591584167, 15887.205639717968, 15975.840241597436, 16082.276297566637, 16118.919405402898, 24540.141095061586, 24785.62821557678, 24914.501709965698, 25064.12275570346, 25091.62362564563, 8658.350623703247, 8749.825003500022, 8766.736997890073, 8772.078931712205, 8793.516356600321, 7290.4167666586545, 7321.963390183121, 7347.08161479815, 7361.8159546011775, 7372.741954031583, 4281.186574550888, 7840.13758486566, 8004.719834805754, 8043.316318112909, 8071.354291656653, 1821.4990877508792, 1873.3443294846695, 1876.7560217171954, 1876.8926333463323, 1890.4612185527333, 899.1952202778476, 964.1467301438278, 965.8020105878278, 1007.853861190138, 1032.8037672842136, 16655.0, 16969.321227150776, 17048.2328295227, 17497.0, 17608.119594020503, 896.1934259166802, 922.9446233225893, 937.8921424036187, 947.190152419685, 958.3339578992533, 9694.51527423626, 9825.852612210823, 10172.949135254275, 10198.388096714221, 10372.740681483023, 592312.0, 614686.0, 617516.0, 622250.0, 627462.0, 79250.20749792429, 80127.19872801198, 81736.59131704303, 82237.58881205675, 85410.14589854146, 1140.0138879868853, 1148.8161894096904, 1150.097173718627, 1157.2477889371808, 1163.086976723285, 16113.194340283173, 16246.756298655293, 16271.59321016984, 16342.754858676892, 16569.171541423122, 37300.81349593234, 37804.81097594494, 38382.424263636625, 39169.6083039166, 39516.0, 48949.2657610129, 49875.75062124666, 50269.74865125651, 50380.0, 54419.72790136024, 9728.659496917573, 9734.464604446823, 9877.753056173366, 9942.850857237003, 9966.950165249127, 11977.64067077989, 17435.82564174342, 17884.195211215265, 17962.371317003843, 18165.727514087564, 15031.173285469404, 15106.848931510543, 15166.0, 15198.848011520186, 15359.385624574892, 14410.567682969528, 14675.48635797742, 14681.779773303195, 14842.0, 15196.772048419061, 14663.0, 14835.406583736529, 14940.85059149438, 15118.244087795787, 15499.30253138634, 9651.324407291422, 9743.26925480606, 9791.265655075755, 9848.40909545401, 9854.310198286052, 10482.947585262329, 10546.525406356886, 14847.777283340543, 15118.319675614845, 15207.619809504851], "unit": "ms", "name": "jquery.html", "value": 9565.019868760444}, {"lowerIsBetter": false, "replicates": [1509.0417584833626, 1660.5931546771044, 1717.6049508613019, 1737.4266492057623, 1740.4430582213688, 7044.823879403018, 7222.855542889139, 7772.0, 8013.479123856952, 8067.435279530435, 7364.263573642633, 7541.245875412456, 7589.316961473466, 7603.505772124822, 7620.466567340274, 2313.1325752842686, 2339.0526836631193, 2349.8942547585316, 2356.1046802215133, 2359.127122964497, 2323.279783267184, 2336.5093330400637, 2373.0151986925493, 2373.6558199061124, 2379.4884099918513, 2282.7488976212603, 2285.5885940530698, 2316.733575638798, 2323.163661082009, 2324.8256380771427, 2306.746257911628, 2309.8036666883336, 2326.348622385735, 2347.7065366829147, 2348.96476552851, 2334.171369163956, 2369.6919400478, 2372.1697405907967, 2373.4896997145524, 2377.072941552796, 7830.177831327743, 7847.215278472164, 7905.723299684483, 7916.881246781303, 7917.802054948615, 4762.38089048425, 4860.927086093711, 4867.148249056401, 4886.780094895738, 4905.2887331337115, 2993.6108305920307, 3002.7447666948337, 3036.6659667436566, 3046.84765761711, 3054.465468542996, 947.5215016416735, 960.8222478841367, 968.9079537443931, 972.2319367699508, 978.4080631218081, 10831.945840270748, 10876.6193183239, 10929.180311476672, 10950.890491095146, 10961.725956851064, 4394.252976993918, 4427.313766366218, 4427.889302767392, 4439.067795762926, 4509.977450112729, 9198.816023679623, 9270.582823772946, 9294.0, 9301.302402319852, 9349.15857572808, 4885.487023862514, 4924.803007879645, 4946.554810067039, 4959.107360675041, 4971.527704868032, 3902.5316961964177, 4028.5568587455355, 4384.649228061748, 4448.844290449883, 4502.887427814266, 3737.1965027518672, 3741.6904083570535, 3749.2876353493293, 3780.3573392523335, 3796.335641262823, 1220.9084318676134, 1225.6445630766978, 1227.147132742753, 1237.8514578250667, 1240.5782034108265, 1307.0066749270622, 1320.5246111399888, 1334.9065565410522, 1339.912905661129, 1340.9329533523285, 7298.781036568911, 7368.0, 7429.294217049372, 7431.665575049135, 7458.440616953751, 485.58239913674214, 490.71048081632006, 492.70683943053825, 493.6914428482199, 502.17893743729263, 5789.392113828072, 5824.621399609012, 5846.181534585162, 5886.23478947734, 5886.823395298148, 9014.233790127913, 9190.08099190089, 9273.675421360354, 9301.441913485212, 9376.812463750686, 9138.954305228432, 9173.724788256362, 9264.11990860867, 9277.350585459088, 9300.11648893354, 6871.828204294832, 7045.823854403783, 7048.097013806244, 7119.07452031243, 7241.565506069653, 7233.132024157135, 7336.7065317386705, 7422.406207503279, 7433.8141546461775, 7466.589337586485, 8224.465409748347, 8350.707725229588, 8371.121032291521, 8455.196756308142, 8532.445391049561, 8346.499210047417, 8522.829543409222, 8535.95732021336, 8547.95726021366, 8662.82674346497, 6070.605410648293, 6289.654069026247, 6614.338566143347, 7042.647867606702, 7062.752803651848, 7466.178720340649, 7501.549907005598, 7504.587247701429, 7516.586587737727, 7523.059617547807, 6729.293424190585, 6971.965140174267, 7104.3961263293195, 7190.029346038218, 7268.164161121539, 6961.547499412522, 7014.123234595676, 7083.2208457068655, 7091.0, 7109.964450177716, 6983.615901125487, 6994.405475534637, 7206.099237595301, 7215.639218039182, 7223.1693355264815, 6452.0, 6453.386928241809, 6458.773942911976, 6461.450776684034, 6473.255575608865, 6575.408213260829, 6603.108580341785, 6608.173978252718, 6623.668816559057, 6646.667666616553], "unit": "ms", "name": "mootools.html", "value": 4452.538276613932}, {"lowerIsBetter": false, "replicates": [330.8874982505948, 335.5084800766876, 342.2436415521696, 346.72088968380444, 351.2623490669594, 6184.226971628546, 6252.906206406908, 6283.27742309634, 6339.461145802612, 6369.172007639012, 6043.6675982820925, 6232.252129744437, 6246.344133865936, 6249.65626890522, 6256.937430625692, 4766.2374020156785, 4784.83253086142, 4799.760011999403, 4807.447143578498, 4811.975940120295, 4798.83204087857, 4805.447373552051, 4817.470078291385, 4820.204666230085, 4822.782974766126, 4668.5098064703225, 4720.221163508017, 4731.408573928259, 4750.21621432465, 4753.073150735608, 4737.0525894821, 4744.928826067595, 4749.952500474986, 4750.90498190036, 4767.356406885096, 4018.819153138116, 4110.897227569305, 4171.853985110537, 4176.248275310443, 4185.016521117535, 5890.793822216202, 5902.937471255173, 5928.258967629046, 5938.168656388109, 5968.4031596840405, 5449.509544141007, 5466.180072989044, 5469.562435005229, 5470.070088084995, 5483.369412517572, 6497.090407342976, 6573.737050517973, 6601.537892347537, 6608.504362172856, 6622.569532980341, 4427.0, 4510.030343476168, 4512.751798651073, 4514.954850451486, 4529.954700452986, 6857.794266172023, 6891.448684105259, 6927.445804335692, 6927.930720692779, 6941.68762405693, 4548.044910568751, 4555.2711566149255, 4569.29175977724, 4574.0, 4586.289125185602, 4547.15877562652, 4610.97694511532, 4629.421322334708, 4635.837745678885, 4636.165490211794, 5449.264349312872, 5451.563874889999, 5456.208849716769, 5459.754311056012, 5463.426340234298, 6702.430293425114, 6708.429783468362, 6711.261761206263, 6711.530192886451, 6711.63086030273, 1650.298623085184, 1658.3615308106466, 1664.60049588098, 1666.1086318819373, 1670.2901266961735, 1092.852464917226, 1102.0026875677524, 1107.95568177272, 1123.5730422439585, 1124.5951457475303, 6412.9679351602945, 6549.2468366137555, 6561.14705088345, 6564.212294524592, 6566.244881838554, 6919.065926099912, 6924.0, 6934.7226110955, 6943.51395402317, 6944.305569443116, 6420.486361091102, 6540.574862633914, 6551.0, 6554.868902621921, 6559.016147577901, 3740.4576336431064, 3758.1732018955786, 3759.154190307159, 3759.3045286622064, 3759.755615884959, 7053.788386348418, 7253.673584688701, 7253.891191632129, 7256.492045556866, 7276.927230727731, 7080.681369338392, 7098.254683258287, 7104.573725576482, 7115.6086415247655, 7160.462965277625, 7031.367176953996, 7182.2458641842895, 7203.89194162088, 7216.458765592497, 7222.927770722225, 6955.339242771928, 6964.199117101598, 6966.477514186355, 6967.512274140761, 6971.3028697129885, 6831.795046148623, 6859.416949559144, 6873.656317184221, 6878.415334696407, 6887.5204926225515, 7070.71717131309, 7187.5687458752645, 7193.784186474414, 7201.0, 7233.095863017123, 4612.86161415158, 4638.8608341749805, 4652.32541281519, 4655.348251244761, 4668.743219122845, 6913.3777959981835, 6943.652817359213, 6955.826104347432, 6973.198082220407, 6979.987901754319, 6585.407313341822, 6727.058211850247, 6756.121704178426, 6764.763233286812, 6766.289539598271, 6696.928491441346, 6712.53012289135, 6720.663966801542, 6733.66331683404, 6756.763513277208, 6574.934250657623, 6577.539572229898, 6580.967095164494, 6581.276059633532, 6588.703508342041, 6646.501512386493, 6792.592444453348, 6796.76211332601, 6801.693923773343, 6820.590764554143, 6577.736890524326, 6584.604923704593, 6588.143541339595, 6595.472362211108, 6597.703103360456], "unit": "ms", "name": "prototype.html", "value": 5014.818057476083}, {"lowerIsBetter": false, "replicates": [722.0719980479156, 730.9744158954436, 772.3666593393416, 861.9008813986394, 866.0257210638032, 13637.045406821526, 13647.52233671823, 13658.453661853539, 13701.725965480684, 13727.0, 12678.492860285578, 12807.295598742065, 12821.038422118356, 12823.807642885364, 12850.48598056079, 821.2034326703089, 824.9670013199465, 836.188896770135, 838.4508147163588, 845.623697454633, 841.4110122913985, 845.3575282785097, 846.0481957797477, 846.2003406780594, 849.5115308697524, 872.2803686958284, 877.8858748362704, 878.4597472554365, 880.5817236812527, 882.7748924024365, 859.1923591823728, 861.2464093917822, 863.9697610583631, 865.8181781825824, 866.5147517390219, 644.5230529408204, 646.9514786391039, 650.4731167754087, 651.7295322441181, 652.1359199061263, 12833.422495987703, 12859.935700321439, 12911.160774549624, 12926.836584707422, 12951.287679177642, 11335.716607084807, 11828.763424731456, 11941.343226122559, 12100.878991210151, 12122.454489547992, 9904.207663386984, 9934.850977235346, 9994.10053095225, 10092.495375231209, 10334.793304133951, 3544.8404821783083, 3588.910277243064, 3605.1167463971497, 3622.2393297407575, 3686.5760437549757, 46605.0, 46814.2977855329, 46875.29687054697, 47079.529204707855, 47184.05631887343, 9781.217502599917, 9803.264755143393, 9879.16027137687, 9998.600055997824, 10184.541695623715, 17444.302227910743, 17616.119194040246, 17623.471295861145, 17636.20637071335, 17662.0, 7328.560286382727, 7408.370288525536, 7458.47790654649, 7502.699892004259, 7549.962250188714, 4100.91798164035, 4224.725392849455, 4279.315309550457, 4279.764612946318, 4288.48538175421, 3137.8117312961294, 3169.920751981173, 3175.158582975513, 3176.5076413156, 3180.904572862818, 555.8693706978856, 575.2808988764045, 576.2019602850082, 585.9794907178228, 589.8436914217735, 518.3572370260849, 615.4368752591419, 637.9330170332141, 642.2517766801717, 696.4463251714899, 8281.710140145065, 8379.874301885477, 8497.0, 8539.487630742175, 8558.443701159405, 552.505507570722, 575.9020966435716, 697.3584302441766, 700.1318365227082, 735.8197241675716, 3834.9041273968373, 3975.403689446606, 4120.320147175682, 4140.15126898985, 4243.68172387066, 57747.0, 57996.0, 58023.4197658018, 58041.0, 58107.0, 48222.27666585004, 49506.0, 50084.4940854144, 50616.74691626518, 50904.74547627238, 47306.290405643944, 47671.0, 48127.75936120297, 48283.517164828605, 48498.0, 8047.919520804717, 8067.919320806717, 8274.627641756257, 8311.584420778816, 8459.365547583833, 34209.31581368409, 34316.31367372689, 39829.60170398259, 41655.0, 42074.57925420706, 49744.75127624339, 54357.0, 54456.727716361165, 54966.0, 56610.433895662165, 11543.76912461763, 11726.648200553997, 12048.939755301168, 12303.507859685513, 12828.55100071493, 11616.244944078608, 11717.648470545897, 11844.466998984894, 12058.698532536757, 12567.622971310875, 10552.947235263775, 10836.891631083588, 10885.292455990335, 10951.288166269167, 11270.273233848882, 10728.088112510211, 11017.338959662125, 11037.39294338787, 11108.777824443305, 11549.0, 9827.459489727848, 9971.202303815533, 10825.188110891871, 10881.673549793519, 11117.666470005914, 9332.626694932127, 9537.904620953701, 9767.413955162712, 9812.116909478182, 9832.508374581099, 8415.368847336353, 8556.957215214134, 8560.272376848037, 8932.151445612657, 8937.821243575223], "unit": "ms", "name": "yui.html", "value": 6314.01492687745}], "name": "dromaeo_css", "value": 7244.630905823543}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 106.0, 111.0, 112.0, 112.0, 126.0, 112.0, 111.0, 111.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 112.2}, {"lowerIsBetter": true, "replicates": [149.0, 123.0, 162.0, 159.0, 135.0, 166.0, 156.0, 139.0, 159.0, 134.0], "unit": "ms", "name": "audio-beat-detection", "value": 148.2}, {"lowerIsBetter": true, "replicates": [162.0, 164.0, 162.0, 164.0, 163.0, 162.0, 161.0, 163.0, 169.0, 163.0], "unit": "ms", "name": "audio-dft", "value": 163.3}, {"lowerIsBetter": true, "replicates": [72.0, 70.0, 73.0, 73.0, 76.0, 75.0, 146.0, 72.0, 72.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 80.1}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 147.0, 94.0, 92.0, 92.0, 141.0, 93.0, 92.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 107.3}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.8}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 147.0, 147.0, 148.0, 146.0, 149.0, 147.0, 147.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 147.1}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 98.0, 96.0, 96.0, 97.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.3}, {"lowerIsBetter": true, "replicates": [62.0, 66.0, 62.0, 61.0, 66.0, 62.0, 63.0, 63.0, 59.0, 60.0], "unit": "ms", "name": "json-parse-financial", "value": 62.4}, {"lowerIsBetter": true, "replicates": [70.0, 71.0, 72.0, 71.0, 71.0, 70.0, 70.0, 71.0, 71.0, 72.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 70.9}, {"lowerIsBetter": true, "replicates": [83.0, 84.0, 85.0, 86.0, 84.0, 86.0, 85.0, 85.0, 96.0, 84.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.8}, {"lowerIsBetter": true, "replicates": [133.0, 126.0, 120.0, 133.0, 120.0, 132.0, 122.0, 126.0, 119.0, 118.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 124.9}, {"lowerIsBetter": true, "replicates": [151.0, 155.0, 149.0, 141.0, 148.0, 148.0, 148.0, 160.0, 143.0, 140.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 148.3}, {"lowerIsBetter": true, "replicates": [68.0, 67.0, 69.0, 67.0, 68.0, 73.0, 72.0, 67.0, 67.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 68.4}], "name": "kraken", "value": 1525.0}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.05778811026238], "unit": "score", "name": "Richards", "value": 212.58864400000002}, {"lowerIsBetter": false, "replicates": [193.38619222587508], "unit": "score", "name": "DeltaBlue", "value": 341.896178}, {"lowerIsBetter": false, "replicates": [281.0567734682406], "unit": "score", "name": "Encrypt", "value": 947.0719979999998}, {"lowerIsBetter": false, "replicates": [5170.103092783505], "unit": "score", "name": "Decrypt", "value": 51.48466001994015}, {"lowerIsBetter": false, "replicates": [1052.6315789473683], "unit": "score", "name": "RayTrace", "value": 702.9895499999999}, {"lowerIsBetter": false, "replicates": [707.7140835102618], "unit": "score", "name": "Earley", "value": 941.7122190000003}, {"lowerIsBetter": false, "replicates": [12192.77108433735], "unit": "score", "name": "Boyer", "value": 54.66050296442688}, {"lowerIsBetter": false, "replicates": [24829.268292682926], "unit": "score", "name": "RegExp", "value": 36.68996561886052}, {"lowerIsBetter": false, "replicates": [587.8894767783656], "unit": "score", "name": "Splay", "value": 138.616191}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 21468.59886196227}]} 10:00:41 INFO - RETURN: dromaeo_css: 7244.63 10:00:41 INFO - RETURN: kraken: 1525.00 10:00:41 INFO - RETURN: v8_7: 21468.60 10:00:41 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,43]]", "result": "21468.60"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,43]]", "result": "1525.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,43]]", "result": "7244.63"}}} 10:00:41 INFO - Return code: 0 10:00:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 10:00:41 INFO - # TBPL SUCCESS # 10:00:41 INFO - Running post-action listener: _resource_record_post_action 10:00:41 INFO - Running post-run listener: _resource_record_post_run 10:00:42 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 24576; Write bytes: 525307904; Read time: 148; Write time: 602040 10:00:42 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 4096; Write bytes: 245506048; Read time: 96; Write time: 426824 10:00:42 INFO - run-tests - Wall time: 1211s; CPU: 13.0%; Read bytes: 20480; Write bytes: 279801856; Read time: 52; Write time: 175216 10:00:42 INFO - Running post-run listener: _upload_blobber_files 10:00:42 INFO - Blob upload gear active. 10:00:42 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:00:42 INFO - Copying logs to upload dir... 10:00:42 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1262.381540 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2015-11-25 10:00:42.333652) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-25 10:00:42.341490) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010006 build_url: 'https://queue.taskcluster.net/v1/task/N2KBKgtMR8mI7PNv_gtU7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.01 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-25 10:00:42.365448) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 10:00:42.365833) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e25b24362c3bb3dea6e300e4000001c1-1448471308.690263-2079840279 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004471 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 10:00:42.419166) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 10:00:42.419559) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 10:00:42.419904) ========= ========= Total master_lag: 0.26 =========