builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-016 starttime: 1448430098.19 results: success (0) buildid: 20151124193113 builduid: 590e711305b84e53954fabe160086e49 revision: 099f695d31326c39595264c34988a0f4b7cbc698 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:38.194211) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:38.194658) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:38.194931) ========= 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=d6a7e047ef025f707024e163000001c3-1448429650.365608-351810649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006609 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:38.232343) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:38.232903) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:38.333983) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:38.334303) ========= 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=d6a7e047ef025f707024e163000001c3-1448429650.365608-351810649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004640 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:38.395791) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:38.397978) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:38.398347) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:38.398654) ========= 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=d6a7e047ef025f707024e163000001c3-1448429650.365608-351810649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-24 21:41: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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.6M=0s 2015-11-24 21:41:38 (43.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.189083 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:38.605125) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:38.605866) ========= 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=d6a7e047ef025f707024e163000001c3-1448429650.365608-351810649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016146 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:38.662836) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:38.663227) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 099f695d31326c39595264c34988a0f4b7cbc698 --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 099f695d31326c39595264c34988a0f4b7cbc698 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d6a7e047ef025f707024e163000001c3-1448429650.365608-351810649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-24 21:41:38,868 truncating revision to first 12 chars 2015-11-24 21:41:38,868 Setting DEBUG logging. 2015-11-24 21:41:38,868 attempt 1/10 2015-11-24 21:41:38,869 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/099f695d3132?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-24 21:41:39,129 unpacking tar archive at: mozilla-central-099f695d3132/testing/mozharness/ program finished with exit code 0 elapsedTime=0.539677 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:39.228283) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:39.228637) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:39.238320) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:39.238680) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 21:41:39.239107) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 0 secs) (at 2015-11-24 21:41:39.239443) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=d6a7e047ef025f707024e163000001c3-1448429650.365608-351810649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:41:39 INFO - MultiFileLogger online at 20151124 21:41:39 in /builds/slave/test-pgo 21:41:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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 21:41:39 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 21:41:39 INFO - {'append_to_log': False, 21:41:39 INFO - 'base_work_dir': '/builds/slave/test-pgo', 21:41:39 INFO - 'blob_upload_branch': 'Firefox', 21:41:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 21:41:39 INFO - 'branch': 'Firefox', 21:41:39 INFO - 'buildbot_json_path': 'buildprops.json', 21:41:39 INFO - 'config_files': ('talos/linux_config.py',), 21:41:39 INFO - 'default_actions': ('clobber', 21:41:39 INFO - 'read-buildbot-config', 21:41:39 INFO - 'download-and-extract', 21:41:39 INFO - 'populate-webroot', 21:41:39 INFO - 'create-virtualenv', 21:41:39 INFO - 'install', 21:41:39 INFO - 'run-tests'), 21:41:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:41:39 INFO - 'download_minidump_stackwalk': True, 21:41:39 INFO - 'download_symbols': 'ondemand', 21:41:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:41:39 INFO - 'tooltool.py': '/tools/tooltool.py', 21:41:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:41:39 INFO - '/tools/misc-python/virtualenv.py')}, 21:41:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:41:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:41:39 INFO - 'installer_path': 'installer.exe', 21:41:39 INFO - 'log_level': 'info', 21:41:39 INFO - 'log_name': 'talos', 21:41:39 INFO - 'log_to_console': True, 21:41:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:41:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:41:39 INFO - 'opt_config_files': (), 21:41:39 INFO - 'pip_index': False, 21:41:39 INFO - 'sps_profile': False, 21:41:39 INFO - 'sps_profile_interval': 0, 21:41:39 INFO - 'suite': 'dromaeojs', 21:41:39 INFO - 'system_bits': '64', 21:41:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:41:39 INFO - 'title': 'talos-linux64-ix-016', 21:41:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:41:39 INFO - 'use_talos_json': True, 21:41:39 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 21:41:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:41:39 INFO - 'work_dir': 'build'} 21:41:39 INFO - ##### 21:41:39 INFO - ##### Running clobber step. 21:41:39 INFO - ##### 21:41:39 INFO - Running pre-action listener: _resource_record_pre_action 21:41:39 INFO - Running main action method: clobber 21:41:39 INFO - rmtree: /builds/slave/test-pgo/build 21:41:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 21:41:40 INFO - Running post-action listener: _resource_record_post_action 21:41:40 INFO - ##### 21:41:40 INFO - ##### Running read-buildbot-config step. 21:41:40 INFO - ##### 21:41:40 INFO - Running pre-action listener: _resource_record_pre_action 21:41:40 INFO - Running main action method: read_buildbot_config 21:41:40 INFO - Using buildbot properties: 21:41:40 INFO - { 21:41:40 INFO - "properties": { 21:41:40 INFO - "buildnumber": 578, 21:41:40 INFO - "product": "firefox", 21:41:40 INFO - "script_repo_revision": "production", 21:41:40 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 21:41:40 INFO - "repository": "", 21:41:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 21:41:40 INFO - "buildid": "20151124193113", 21:41:40 INFO - "slavename": "talos-linux64-ix-016", 21:41:40 INFO - "pgo_build": "True", 21:41:40 INFO - "basedir": "/builds/slave/test-pgo", 21:41:40 INFO - "project": "", 21:41:40 INFO - "platform": "ubuntu64_hw", 21:41:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:41:40 INFO - "slavebuilddir": "test", 21:41:40 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 21:41:40 INFO - "branch": "mozilla-central", 21:41:40 INFO - "repo_path": "mozilla-central", 21:41:40 INFO - "stage_platform": "linux64-pgo", 21:41:40 INFO - "builduid": "590e711305b84e53954fabe160086e49", 21:41:40 INFO - "revision": "099f695d31326c39595264c34988a0f4b7cbc698" 21:41:40 INFO - }, 21:41:40 INFO - "sourcestamp": { 21:41:40 INFO - "repository": "", 21:41:40 INFO - "hasPatch": false, 21:41:40 INFO - "project": "", 21:41:40 INFO - "branch": "mozilla-central-linux64-pgo-talos", 21:41:40 INFO - "changes": [ 21:41:40 INFO - { 21:41:40 INFO - "category": null, 21:41:40 INFO - "files": [ 21:41:40 INFO - { 21:41:40 INFO - "url": null, 21:41:40 INFO - "name": "https://queue.taskcluster.net/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 21:41:40 INFO - } 21:41:40 INFO - ], 21:41:40 INFO - "repository": "", 21:41:40 INFO - "rev": "099f695d31326c39595264c34988a0f4b7cbc698", 21:41:40 INFO - "who": "sendchange", 21:41:40 INFO - "when": 1448430049, 21:41:40 INFO - "number": 6703833, 21:41:40 INFO - "comments": "", 21:41:40 INFO - "project": "", 21:41:40 INFO - "at": "Tue 24 Nov 2015 21:40:49", 21:41:40 INFO - "branch": "mozilla-central-linux64-pgo-talos", 21:41:40 INFO - "revlink": "", 21:41:40 INFO - "properties": [ 21:41:40 INFO - [ 21:41:40 INFO - "buildid", 21:41:40 INFO - "20151124193113", 21:41:40 INFO - "Change" 21:41:40 INFO - ], 21:41:40 INFO - [ 21:41:40 INFO - "builduid", 21:41:40 INFO - "590e711305b84e53954fabe160086e49", 21:41:40 INFO - "Change" 21:41:40 INFO - ], 21:41:40 INFO - [ 21:41:40 INFO - "pgo_build", 21:41:40 INFO - "True", 21:41:40 INFO - "Change" 21:41:40 INFO - ] 21:41:40 INFO - ], 21:41:40 INFO - "revision": "099f695d31326c39595264c34988a0f4b7cbc698" 21:41:40 INFO - } 21:41:40 INFO - ], 21:41:40 INFO - "revision": "099f695d31326c39595264c34988a0f4b7cbc698" 21:41:40 INFO - } 21:41:40 INFO - } 21:41:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 21:41:40 INFO - Running post-action listener: _resource_record_post_action 21:41:40 INFO - ##### 21:41:40 INFO - ##### Running download-and-extract step. 21:41:40 INFO - ##### 21:41:40 INFO - Running pre-action listener: _resource_record_pre_action 21:41:40 INFO - Running main action method: download_and_extract 21:41:40 INFO - mkdir: /builds/slave/test-pgo/build/tests 21:41:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:41:40 INFO - https://queue.taskcluster.net/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 21:41:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/test_packages.json 21:41:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/test_packages.json 21:41:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 21:41:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 21:41:45 INFO - Downloaded 1302 bytes. 21:41:45 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 21:41:45 INFO - Using the following test package requirements: 21:41:45 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 21:41:45 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:41:45 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:41:45 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:41:45 INFO - u'jsshell-linux-x86_64.zip'], 21:41:45 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:41:45 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:41:45 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 21:41:45 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:41:45 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:41:45 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:41:45 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:41:45 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:41:45 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:41:45 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 21:41:45 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:41:45 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:41:45 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:41: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')]} 21:41:45 INFO - https://queue.taskcluster.net/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:41:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:41:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:41:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/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 21:41:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/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 21:41:49 INFO - Downloaded 22530715 bytes. 21:41: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 21:41:49 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:41: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 21:41:50 INFO - Return code: 0 21:41:50 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 21:41: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')]} 21:41:50 INFO - https://queue.taskcluster.net/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:41:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:41:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:41:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/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 21:41:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/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 21:41:58 INFO - Downloaded 22530715 bytes. 21:41:58 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 21:41:58 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:41:58 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 21:41:59 INFO - Return code: 0 21:41:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:41:59 INFO - https://queue.taskcluster.net/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:41:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 21:41:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 21:41:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/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 21:41:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/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 21:42:01 INFO - Downloaded 11131717 bytes. 21:42:01 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 21:42:01 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 21:42:01 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 21:42:01 INFO - Return code: 0 21:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:42:01 INFO - https://queue.taskcluster.net/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:42:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:42:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:42:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/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 21:42:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn4xKcegSVW8EyvJlBayaQ/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 21:42:03 INFO - Downloaded 58388014 bytes. 21:42:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:42:03 INFO - mkdir: /builds/slave/test-pgo/properties 21:42:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 21:42:03 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 21:42:03 INFO - Contents: 21:42:03 INFO - build_url:https://queue.taskcluster.net/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:42:03 INFO - Running post-action listener: _resource_record_post_action 21:42:03 INFO - Running post-action listener: set_extra_try_arguments 21:42:03 INFO - ##### 21:42:03 INFO - ##### Running populate-webroot step. 21:42:03 INFO - ##### 21:42:03 INFO - Running pre-action listener: _resource_record_pre_action 21:42:03 INFO - Running main action method: populate_webroot 21:42:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:42:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:42:03 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 21:42:03 INFO - '../fennec_ids.txt'], 21:42:03 INFO - 'tests': ['tcheck2']}, 21:42:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:42:03 INFO - '--tppagecycles', 21:42:03 INFO - '7'], 21:42:03 INFO - 'tests': ['tsvgm']}}, 21:42:03 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 21:42:03 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 21:42:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:42:03 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:42:03 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 21:42:03 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:42:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:42:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:42:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:42:03 INFO - 'talos_options': ['--e10s'], 21:42:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:42:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:42:03 INFO - 'tests': ['damp', 'tps']}, 21:42:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:42:03 INFO - 'talos_options': ['--e10s'], 21:42:03 INFO - 'tests': ['damp', 'tps']}, 21:42:03 INFO - 'g3': {'tests': ['dromaeo_dom']}, 21:42:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:42:03 INFO - 'other': {'tests': ['a11yr', 21:42:03 INFO - 'ts_paint', 21:42:03 INFO - 'tpaint', 21:42:03 INFO - 'sessionrestore', 21:42:03 INFO - 'sessionrestore_no_auto_restore']}, 21:42:03 INFO - 'other-e10s': {'talos_options': ['--e10s'], 21:42:03 INFO - 'tests': ['a11yr', 21:42:03 INFO - 'ts_paint', 21:42:03 INFO - 'tpaint', 21:42:03 INFO - 'sessionrestore', 21:42:03 INFO - 'sessionrestore_no_auto_restore']}, 21:42:03 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 21:42:03 INFO - 'tests': ['a11yr', 21:42:03 INFO - 'ts_paint', 21:42:03 INFO - 'tpaint', 21:42:03 INFO - 'sessionrestore', 21:42:03 INFO - 'sessionrestore_no_auto_restore']}, 21:42:03 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 21:42:03 INFO - 'tests': ['a11yr', 21:42:03 INFO - 'ts_paint', 21:42:03 INFO - 'tpaint', 21:42:03 INFO - 'sessionrestore', 21:42:03 INFO - 'sessionrestore_no_auto_restore']}, 21:42:03 INFO - 'other_l64': {'tests': ['a11yr', 21:42:03 INFO - 'ts_paint', 21:42:03 INFO - 'tpaint', 21:42:03 INFO - 'sessionrestore', 21:42:03 INFO - 'sessionrestore_no_auto_restore']}, 21:42:03 INFO - 'other_nol64': {'tests': ['a11yr', 21:42:03 INFO - 'ts_paint', 21:42:03 INFO - 'tpaint', 21:42:03 INFO - 'sessionrestore', 21:42:03 INFO - 'sessionrestore_no_auto_restore']}, 21:42:03 INFO - 'svgr': {'tests': ['tsvgx', 21:42:03 INFO - 'tsvgr_opacity', 21:42:03 INFO - 'tart', 21:42:03 INFO - 'tscrollx', 21:42:03 INFO - 'cart']}, 21:42:03 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 21:42:03 INFO - 'tests': ['tsvgx', 21:42:03 INFO - 'tsvgr_opacity', 21:42:03 INFO - 'tart', 21:42:03 INFO - 'tscrollx', 21:42:03 INFO - 'cart']}, 21:42:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:42:03 INFO - 'tests': ['tp5o']}, 21:42:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:42:03 INFO - 'talos_options': ['--e10s'], 21:42:03 INFO - 'tests': ['tp5o']}, 21:42:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:42:03 INFO - 'talos_options': ['--xperf_path', 21:42:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:42:03 INFO - 'tests': ['tp5n']}, 21:42:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:42:03 INFO - 'talos_options': ['--e10s', 21:42:03 INFO - '--xperf_path', 21:42:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:42:03 INFO - 'tests': ['tp5n']}}, 21:42:03 INFO - 'talos.zip': {'path': '', 21:42:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:42:03 INFO - Running post-action listener: _resource_record_post_action 21:42:03 INFO - ##### 21:42:03 INFO - ##### Running create-virtualenv step. 21:42:03 INFO - ##### 21:42:03 INFO - Running pre-action listener: _resource_record_pre_action 21:42:03 INFO - Running main action method: create_virtualenv 21:42:03 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 21:42:03 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 21:42:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 21:42:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:42:03 INFO - Using real prefix '/usr' 21:42:03 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 21:42:03 INFO - Installing distribute.............................................................................................................................................................................................done. 21:42:05 INFO - Installing pip.................done. 21:42:05 INFO - Return code: 0 21:42:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 21:42: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')]} 21:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42: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')]} 21:42:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:05 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 0x2032030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x225f7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ee640>, '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 0x22ef540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ef9d0>, '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': 'd6a7e047ef025f707024e163000001c3-1448429650.365608-351810649', '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 21:42:05 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 21:42:05 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 21:42:05 INFO - Using env: {'DISPLAY': ':0', 21:42:05 INFO - 'HOME': '/home/cltbld', 21:42:05 INFO - 'LANG': 'en_US.UTF-8', 21:42:05 INFO - 'LANGUAGE': 'en_US:en', 21:42:05 INFO - 'LOGNAME': 'cltbld', 21:42:05 INFO - 'MAIL': '/var/mail/cltbld', 21:42:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:05 INFO - 'MOZ_NO_REMOTE': '1', 21:42:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:42:05 INFO - 'NO_EM_RESTART': '1', 21:42:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:42:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:42:05 INFO - 'PWD': '/builds/slave/test-pgo', 21:42:05 INFO - 'SHELL': '/bin/bash', 21:42:05 INFO - 'SHLVL': '1', 21:42:05 INFO - 'TERM': 'linux', 21:42:05 INFO - 'TMOUT': '86400', 21:42:05 INFO - 'USER': 'cltbld', 21:42:05 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1448429650.365608-351810649', 21:42:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:05 INFO - '_': '/tools/buildbot/bin/python'} 21:42:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:06 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 21:42:06 INFO - Cleaning up... 21:42:06 INFO - Return code: 0 21:42:06 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 21:42:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:42:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:42:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:06 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x2032030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x225f7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ee640>, '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 0x22ef540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ef9d0>, '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': 'd6a7e047ef025f707024e163000001c3-1448429650.365608-351810649', '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 21:42:06 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 21:42:06 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 21:42:06 INFO - Using env: {'DISPLAY': ':0', 21:42:06 INFO - 'HOME': '/home/cltbld', 21:42:06 INFO - 'LANG': 'en_US.UTF-8', 21:42:06 INFO - 'LANGUAGE': 'en_US:en', 21:42:06 INFO - 'LOGNAME': 'cltbld', 21:42:06 INFO - 'MAIL': '/var/mail/cltbld', 21:42:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:06 INFO - 'MOZ_NO_REMOTE': '1', 21:42:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:42:06 INFO - 'NO_EM_RESTART': '1', 21:42:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:42:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:42:06 INFO - 'PWD': '/builds/slave/test-pgo', 21:42:06 INFO - 'SHELL': '/bin/bash', 21:42:06 INFO - 'SHLVL': '1', 21:42:06 INFO - 'TERM': 'linux', 21:42:06 INFO - 'TMOUT': '86400', 21:42:06 INFO - 'USER': 'cltbld', 21:42:06 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1448429650.365608-351810649', 21:42:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:06 INFO - '_': '/tools/buildbot/bin/python'} 21:42:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:06 INFO - Downloading/unpacking psutil>=0.7.1 21:42:06 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 21:42:06 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 21:42:06 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 21:42:06 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 21:42:06 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 21:42:06 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 21:42:07 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 21:42:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 21:42:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 21:42:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:42:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:42:08 INFO - Installing collected packages: psutil 21:42:08 INFO - Running setup.py install for psutil 21:42:08 INFO - building 'psutil._psutil_linux' extension 21:42:08 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 21:42:08 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 21:42:08 INFO - building 'psutil._psutil_posix' extension 21:42:08 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 21:42:08 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 21:42:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:42:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:42:08 INFO - Successfully installed psutil 21:42:08 INFO - Cleaning up... 21:42:08 INFO - Return code: 0 21:42:08 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 21:42: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')]} 21:42:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42: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')]} 21:42:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42: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', '--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 0x2032030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x225f7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ee640>, '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 0x22ef540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ef9d0>, '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': 'd6a7e047ef025f707024e163000001c3-1448429650.365608-351810649', '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 21:42:08 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 21:42:08 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 21:42:08 INFO - Using env: {'DISPLAY': ':0', 21:42:08 INFO - 'HOME': '/home/cltbld', 21:42:08 INFO - 'LANG': 'en_US.UTF-8', 21:42:08 INFO - 'LANGUAGE': 'en_US:en', 21:42:08 INFO - 'LOGNAME': 'cltbld', 21:42:08 INFO - 'MAIL': '/var/mail/cltbld', 21:42:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:08 INFO - 'MOZ_NO_REMOTE': '1', 21:42:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:42:08 INFO - 'NO_EM_RESTART': '1', 21:42:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:42:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:42:08 INFO - 'PWD': '/builds/slave/test-pgo', 21:42:08 INFO - 'SHELL': '/bin/bash', 21:42:08 INFO - 'SHLVL': '1', 21:42:08 INFO - 'TERM': 'linux', 21:42:08 INFO - 'TMOUT': '86400', 21:42:08 INFO - 'USER': 'cltbld', 21:42:08 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1448429650.365608-351810649', 21:42:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:08 INFO - '_': '/tools/buildbot/bin/python'} 21:42:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:42:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:42:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:42:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:42:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:42:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:42:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:42:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:42:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 21:42:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:42:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:42:10 INFO - Installing collected packages: mozsystemmonitor 21:42:10 INFO - Running setup.py install for mozsystemmonitor 21:42:10 INFO - Successfully installed mozsystemmonitor 21:42:10 INFO - Cleaning up... 21:42:10 INFO - Return code: 0 21:42:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 21:42:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:42:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:42:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:10 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2032030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x225f7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ee640>, '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 0x22ef540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ef9d0>, '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': 'd6a7e047ef025f707024e163000001c3-1448429650.365608-351810649', '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 21:42:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 21:42:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:42:10 INFO - Using env: {'DISPLAY': ':0', 21:42:10 INFO - 'HOME': '/home/cltbld', 21:42:10 INFO - 'LANG': 'en_US.UTF-8', 21:42:10 INFO - 'LANGUAGE': 'en_US:en', 21:42:10 INFO - 'LOGNAME': 'cltbld', 21:42:10 INFO - 'MAIL': '/var/mail/cltbld', 21:42:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:10 INFO - 'MOZ_NO_REMOTE': '1', 21:42:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:42:10 INFO - 'NO_EM_RESTART': '1', 21:42:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:42:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:42:10 INFO - 'PWD': '/builds/slave/test-pgo', 21:42:10 INFO - 'SHELL': '/bin/bash', 21:42:10 INFO - 'SHLVL': '1', 21:42:10 INFO - 'TERM': 'linux', 21:42:10 INFO - 'TMOUT': '86400', 21:42:10 INFO - 'USER': 'cltbld', 21:42:10 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1448429650.365608-351810649', 21:42:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:10 INFO - '_': '/tools/buildbot/bin/python'} 21:42:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:10 INFO - Downloading/unpacking blobuploader==1.2.4 21:42: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 21:42: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 21:42: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 21:42: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 21:42: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 21:42: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 21:42:12 INFO - Downloading blobuploader-1.2.4.tar.gz 21:42:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 21:42:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:42:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:42: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 21:42: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 21:42: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 21:42: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 21:42: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 21:42: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 21:42:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 21:42:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 21:42:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:42:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:42:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:42:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:42:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:42:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:42:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:42:13 INFO - Downloading docopt-0.6.1.tar.gz 21:42:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 21:42:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 21:42:13 INFO - Installing collected packages: blobuploader, requests, docopt 21:42:13 INFO - Running setup.py install for blobuploader 21:42:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:42:13 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 21:42:13 INFO - Running setup.py install for requests 21:42:13 INFO - Running setup.py install for docopt 21:42:13 INFO - Successfully installed blobuploader requests docopt 21:42:13 INFO - Cleaning up... 21:42:13 INFO - Return code: 0 21:42:13 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:42:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:42:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:13 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 0x2032030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x225f7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ee640>, '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 0x22ef540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ef9d0>, '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': 'd6a7e047ef025f707024e163000001c3-1448429650.365608-351810649', '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 21:42:13 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 21:42:13 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 21:42:13 INFO - Using env: {'DISPLAY': ':0', 21:42:13 INFO - 'HOME': '/home/cltbld', 21:42:13 INFO - 'LANG': 'en_US.UTF-8', 21:42:13 INFO - 'LANGUAGE': 'en_US:en', 21:42:13 INFO - 'LOGNAME': 'cltbld', 21:42:13 INFO - 'MAIL': '/var/mail/cltbld', 21:42:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:13 INFO - 'MOZ_NO_REMOTE': '1', 21:42:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:42:13 INFO - 'NO_EM_RESTART': '1', 21:42:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:42:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:42:13 INFO - 'PWD': '/builds/slave/test-pgo', 21:42:13 INFO - 'SHELL': '/bin/bash', 21:42:13 INFO - 'SHLVL': '1', 21:42:13 INFO - 'TERM': 'linux', 21:42:13 INFO - 'TMOUT': '86400', 21:42:13 INFO - 'USER': 'cltbld', 21:42:13 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1448429650.365608-351810649', 21:42:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:13 INFO - '_': '/tools/buildbot/bin/python'} 21:42:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:42:14 INFO - Running setup.py (path:/tmp/pip-4nJHxY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:42:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:42:14 INFO - Running setup.py (path:/tmp/pip-cCwfcs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:42:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:42:14 INFO - Running setup.py (path:/tmp/pip-HkzaMk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:42:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:42:14 INFO - Running setup.py (path:/tmp/pip-sbrL5Y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:42:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:42:14 INFO - Running setup.py (path:/tmp/pip-ZK5AL1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:42:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:42:14 INFO - Running setup.py (path:/tmp/pip-aH3Q0t-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:42:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:42:14 INFO - Running setup.py (path:/tmp/pip-wB8Yh_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:42:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:42:14 INFO - Running setup.py (path:/tmp/pip-nMgDuA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:42:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:42:14 INFO - Running setup.py (path:/tmp/pip-xa_i55-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:42:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:42:14 INFO - Running setup.py (path:/tmp/pip-MQyriu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:42:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:42:14 INFO - Running setup.py (path:/tmp/pip-5uVWy6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:42:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:42:14 INFO - Running setup.py (path:/tmp/pip-ndXy_G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:42:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:42:15 INFO - Running setup.py (path:/tmp/pip-dz3e9x-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:42:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:42:15 INFO - Running setup.py (path:/tmp/pip-9pZWWQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:42:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:42:15 INFO - Running setup.py (path:/tmp/pip-EE5wmr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:42:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:42:15 INFO - Running setup.py (path:/tmp/pip-Qf_OjX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:42:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:42:15 INFO - Running setup.py (path:/tmp/pip-X5f5Hv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:42:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:42:15 INFO - Running setup.py install for manifestparser 21:42:15 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 21:42:15 INFO - Running setup.py install for mozcrash 21:42:15 INFO - Running setup.py install for mozdebug 21:42:15 INFO - Running setup.py install for mozdevice 21:42:15 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 21:42:15 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 21:42:15 INFO - Running setup.py install for mozfile 21:42:15 INFO - Running setup.py install for mozhttpd 21:42:16 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 21:42:16 INFO - Running setup.py install for mozinfo 21:42:16 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 21:42:16 INFO - Running setup.py install for mozInstall 21:42:16 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 21:42:16 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 21:42:16 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 21:42:16 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 21:42:16 INFO - Running setup.py install for mozleak 21:42:16 INFO - Running setup.py install for mozlog 21:42:16 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 21:42:16 INFO - Running setup.py install for moznetwork 21:42:16 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 21:42:16 INFO - Running setup.py install for mozprocess 21:42:16 INFO - Running setup.py install for mozprofile 21:42:16 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 21:42:16 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 21:42:16 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 21:42:16 INFO - Running setup.py install for mozrunner 21:42:16 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 21:42:16 INFO - Running setup.py install for mozscreenshot 21:42:16 INFO - Running setup.py install for moztest 21:42:17 INFO - Running setup.py install for mozversion 21:42:17 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 21:42:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:42:17 INFO - Cleaning up... 21:42:17 INFO - Return code: 0 21:42:17 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:42:17 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')]} 21:42:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:17 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')]} 21:42:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:17 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 0x2032030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x225f7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ee640>, '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 0x22ef540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ef9d0>, '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': 'd6a7e047ef025f707024e163000001c3-1448429650.365608-351810649', '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 21:42:17 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 21:42:17 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 21:42:17 INFO - Using env: {'DISPLAY': ':0', 21:42:17 INFO - 'HOME': '/home/cltbld', 21:42:17 INFO - 'LANG': 'en_US.UTF-8', 21:42:17 INFO - 'LANGUAGE': 'en_US:en', 21:42:17 INFO - 'LOGNAME': 'cltbld', 21:42:17 INFO - 'MAIL': '/var/mail/cltbld', 21:42:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:17 INFO - 'MOZ_NO_REMOTE': '1', 21:42:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:42:17 INFO - 'NO_EM_RESTART': '1', 21:42:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:42:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:42:17 INFO - 'PWD': '/builds/slave/test-pgo', 21:42:17 INFO - 'SHELL': '/bin/bash', 21:42:17 INFO - 'SHLVL': '1', 21:42:17 INFO - 'TERM': 'linux', 21:42:17 INFO - 'TMOUT': '86400', 21:42:17 INFO - 'USER': 'cltbld', 21:42:17 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1448429650.365608-351810649', 21:42:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:17 INFO - '_': '/tools/buildbot/bin/python'} 21:42:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:42:17 INFO - Running setup.py (path:/tmp/pip-FrCaNu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:42:17 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)) 21:42:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:42:17 INFO - Running setup.py (path:/tmp/pip-xZzMq2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:42:17 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)) 21:42:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:42:17 INFO - Running setup.py (path:/tmp/pip-x2uN2f-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:42:17 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)) 21:42:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:42:17 INFO - Running setup.py (path:/tmp/pip-Fxf1TY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:42:17 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)) 21:42:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:42:17 INFO - Running setup.py (path:/tmp/pip-qrH9yU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:42:17 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)) 21:42:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:42:17 INFO - Running setup.py (path:/tmp/pip-huLzYQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:42:17 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)) 21:42:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:42:17 INFO - Running setup.py (path:/tmp/pip-RoPwJ6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:42:17 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)) 21:42:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:42:17 INFO - Running setup.py (path:/tmp/pip-vH1A0s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:42:17 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)) 21:42:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:42:17 INFO - Running setup.py (path:/tmp/pip-znaNf9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:42:18 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)) 21:42:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:42:18 INFO - Running setup.py (path:/tmp/pip-mCEhGU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:42:18 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)) 21:42:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:42:18 INFO - Running setup.py (path:/tmp/pip-O7N7QT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:42:18 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)) 21:42:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:42:18 INFO - Running setup.py (path:/tmp/pip-CYCrcV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:42:18 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)) 21:42:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:42:18 INFO - Running setup.py (path:/tmp/pip-7ZmLpq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:42:18 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)) 21:42:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:42:18 INFO - Running setup.py (path:/tmp/pip-CXX8lP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:42:18 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)) 21:42:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:42:18 INFO - Running setup.py (path:/tmp/pip-fSZFl9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:42:18 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)) 21:42:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:42:18 INFO - Running setup.py (path:/tmp/pip-YIEivu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:42:18 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)) 21:42:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:42:18 INFO - Running setup.py (path:/tmp/pip-BD2Oee-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:42:18 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)) 21:42:18 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)) 21:42:18 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)) 21:42:18 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)) 21:42:18 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)) 21:42:18 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)) 21:42:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 21:42:18 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 21:42:18 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 21:42:18 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 21:42:18 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 21:42:18 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 21:42:18 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 21:42:20 INFO - Downloading blessings-1.5.1.tar.gz 21:42:20 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 21:42:20 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 21:42:20 INFO - Installing collected packages: blessings 21:42:20 INFO - Running setup.py install for blessings 21:42:20 INFO - Successfully installed blessings 21:42:20 INFO - Cleaning up... 21:42:20 INFO - Return code: 0 21:42:20 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 21:42:20 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:42:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:42:20 INFO - Reading from file tmpfile_stdout 21:42:20 INFO - Current package versions: 21:42:20 INFO - argparse == 1.2.1 21:42:20 INFO - blessings == 1.5.1 21:42:20 INFO - blobuploader == 1.2.4 21:42:20 INFO - docopt == 0.6.1 21:42:20 INFO - manifestparser == 1.1 21:42:20 INFO - mozInstall == 1.12 21:42:20 INFO - mozcrash == 0.16 21:42:20 INFO - mozdebug == 0.1 21:42:20 INFO - mozdevice == 0.47 21:42:20 INFO - mozfile == 1.2 21:42:20 INFO - mozhttpd == 0.7 21:42:20 INFO - mozinfo == 0.9 21:42:20 INFO - mozleak == 0.1 21:42:20 INFO - mozlog == 3.1 21:42:20 INFO - moznetwork == 0.27 21:42:20 INFO - mozprocess == 0.22 21:42:20 INFO - mozprofile == 0.27 21:42:20 INFO - mozrunner == 6.11 21:42:20 INFO - mozscreenshot == 0.1 21:42:20 INFO - mozsystemmonitor == 0.0 21:42:20 INFO - moztest == 0.7 21:42:20 INFO - mozversion == 1.4 21:42:20 INFO - psutil == 3.1.1 21:42:20 INFO - requests == 1.2.3 21:42:20 INFO - wsgiref == 0.1.2 21:42:20 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:42:20 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')]} 21:42:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:20 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')]} 21:42:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:20 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 0x2032030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x225f7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ee640>, '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 0x22ef540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ef9d0>, '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': 'd6a7e047ef025f707024e163000001c3-1448429650.365608-351810649', '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 21:42:20 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 21:42:20 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 21:42:20 INFO - Using env: {'DISPLAY': ':0', 21:42:20 INFO - 'HOME': '/home/cltbld', 21:42:20 INFO - 'LANG': 'en_US.UTF-8', 21:42:20 INFO - 'LANGUAGE': 'en_US:en', 21:42:20 INFO - 'LOGNAME': 'cltbld', 21:42:20 INFO - 'MAIL': '/var/mail/cltbld', 21:42:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:20 INFO - 'MOZ_NO_REMOTE': '1', 21:42:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:42:20 INFO - 'NO_EM_RESTART': '1', 21:42:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:42:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:42:20 INFO - 'PWD': '/builds/slave/test-pgo', 21:42:20 INFO - 'SHELL': '/bin/bash', 21:42:20 INFO - 'SHLVL': '1', 21:42:20 INFO - 'TERM': 'linux', 21:42:20 INFO - 'TMOUT': '86400', 21:42:20 INFO - 'USER': 'cltbld', 21:42:20 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1448429650.365608-351810649', 21:42:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:20 INFO - '_': '/tools/buildbot/bin/python'} 21:42:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:21 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)) 21:42:21 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)) 21:42:21 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)) 21:42:21 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)) 21:42:21 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)) 21:42:21 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)) 21:42:21 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)) 21:42:21 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)) 21:42:21 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)) 21:42:21 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)) 21:42:21 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)) 21:42:21 INFO - Cleaning up... 21:42:21 INFO - Return code: 0 21:42:21 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 21:42:21 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')]} 21:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:21 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')]} 21:42:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:21 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 0x2032030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x225f7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ee640>, '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 0x22ef540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ef9d0>, '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': 'd6a7e047ef025f707024e163000001c3-1448429650.365608-351810649', '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 21:42:21 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 21:42:21 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 21:42:21 INFO - Using env: {'DISPLAY': ':0', 21:42:21 INFO - 'HOME': '/home/cltbld', 21:42:21 INFO - 'LANG': 'en_US.UTF-8', 21:42:21 INFO - 'LANGUAGE': 'en_US:en', 21:42:21 INFO - 'LOGNAME': 'cltbld', 21:42:21 INFO - 'MAIL': '/var/mail/cltbld', 21:42:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:21 INFO - 'MOZ_NO_REMOTE': '1', 21:42:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:42:21 INFO - 'NO_EM_RESTART': '1', 21:42:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:42:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:42:21 INFO - 'PWD': '/builds/slave/test-pgo', 21:42:21 INFO - 'SHELL': '/bin/bash', 21:42:21 INFO - 'SHLVL': '1', 21:42:21 INFO - 'TERM': 'linux', 21:42:21 INFO - 'TMOUT': '86400', 21:42:21 INFO - 'USER': 'cltbld', 21:42:21 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1448429650.365608-351810649', 21:42:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:21 INFO - '_': '/tools/buildbot/bin/python'} 21:42:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:21 INFO - Downloading/unpacking jsonschema 21:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:42:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:42:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:42:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:42:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:42:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:42:23 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 21:42:23 INFO - Downloading/unpacking functools32 (from jsonschema) 21:42:23 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 21:42:23 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 21:42:23 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 21:42:23 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 21:42:23 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 21:42:23 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 21:42:23 INFO - Downloading functools32-3.2.3-2.tar.gz 21:42:23 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 21:42:23 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 21:42:23 INFO - warning: no files found matching '*.txt' 21:42:23 INFO - no previously-included directories found matching 'build' 21:42:23 INFO - no previously-included directories found matching 'dist' 21:42:23 INFO - no previously-included directories found matching '.git*' 21:42:23 INFO - Installing collected packages: jsonschema, functools32 21:42:23 INFO - Running setup.py install for functools32 21:42:23 INFO - warning: no files found matching '*.txt' 21:42:23 INFO - no previously-included directories found matching 'build' 21:42:23 INFO - no previously-included directories found matching 'dist' 21:42:23 INFO - no previously-included directories found matching '.git*' 21:42:23 INFO - Successfully installed jsonschema functools32 21:42:23 INFO - Cleaning up... 21:42:23 INFO - Return code: 0 21:42:23 INFO - Running post-action listener: _resource_record_post_action 21:42:23 INFO - Running post-action listener: _start_resource_monitoring 21:42:23 INFO - Starting resource monitoring. 21:42:23 INFO - ##### 21:42:23 INFO - ##### Running install step. 21:42:23 INFO - ##### 21:42:23 INFO - Running pre-action listener: _resource_record_pre_action 21:42:23 INFO - Running main action method: install 21:42:23 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:42:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:42:23 INFO - Reading from file tmpfile_stdout 21:42:23 INFO - Detecting whether we're running mozinstall >=1.0... 21:42:23 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 21:42:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 21:42:23 INFO - Reading from file tmpfile_stdout 21:42:23 INFO - Output received: 21:42:23 INFO - Usage: mozinstall [options] installer 21:42:23 INFO - Options: 21:42:23 INFO - -h, --help show this help message and exit 21:42:23 INFO - -d DEST, --destination=DEST 21:42:23 INFO - Directory to install application into. [default: 21:42:23 INFO - "/builds/slave/test-pgo"] 21:42:23 INFO - --app=APP Application being installed. [default: firefox] 21:42:23 INFO - mkdir: /builds/slave/test-pgo/build/application 21:42:23 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'] 21:42:23 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 21:42:38 INFO - Reading from file tmpfile_stdout 21:42:38 INFO - Output received: 21:42:38 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 21:42:38 INFO - Running post-action listener: _resource_record_post_action 21:42:38 INFO - ##### 21:42:38 INFO - ##### Running run-tests step. 21:42:38 INFO - ##### 21:42:38 INFO - Running pre-action listener: _resource_record_pre_action 21:42:38 INFO - Running main action method: run_tests 21:42:38 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 21:42:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 21:42:38 INFO - Python 2.7.3 21:42:38 INFO - Return code: 0 21:42:38 INFO - grabbing minidump binary from tooltool 21:42:38 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')]} 21:42:38 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 0x22ee640>, '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 0x22ef540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ef9d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 21:42:38 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 21:42:38 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 21:42:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:42:38 INFO - Return code: 0 21:42:38 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 21:42:38 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 21:42:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 21:42:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 21:42:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 21:42:38 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', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 21:42:38 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 --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --webServer localhost 21:42:38 INFO - Using env: {'DISPLAY': ':0', 21:42:38 INFO - 'HOME': '/home/cltbld', 21:42:38 INFO - 'LANG': 'en_US.UTF-8', 21:42:38 INFO - 'LANGUAGE': 'en_US:en', 21:42:38 INFO - 'LOGNAME': 'cltbld', 21:42:38 INFO - 'MAIL': '/var/mail/cltbld', 21:42:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:42:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 21:42:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:38 INFO - 'MOZ_NO_REMOTE': '1', 21:42:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:42:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:42:38 INFO - 'NO_EM_RESTART': '1', 21:42:38 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:42:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:42:38 INFO - 'PWD': '/builds/slave/test-pgo', 21:42:38 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 21:42:38 INFO - 'SHELL': '/bin/bash', 21:42:38 INFO - 'SHLVL': '1', 21:42:38 INFO - 'TERM': 'linux', 21:42:38 INFO - 'TMOUT': '86400', 21:42:38 INFO - 'USER': 'cltbld', 21:42:38 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1448429650.365608-351810649', 21:42:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:38 INFO - '_': '/tools/buildbot/bin/python'} 21:42:38 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', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost'] with output_timeout 3600 21:42:38 INFO - mozversion INFO | application_buildid: 20151124193113 21:42:38 INFO - mozversion INFO | application_changeset: 099f695d31326c39595264c34988a0f4b7cbc698 21:42:38 INFO - mozversion INFO | application_display_name: Nightly 21:42:38 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:42:38 INFO - mozversion INFO | application_name: Firefox 21:42:38 INFO - mozversion INFO | application_remotingname: firefox 21:42:38 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 21:42:38 INFO - mozversion INFO | application_vendor: Mozilla 21:42:38 INFO - mozversion INFO | application_version: 45.0a1 21:42:38 INFO - mozversion INFO | platform_buildid: 20151124193113 21:42:38 INFO - mozversion INFO | platform_changeset: 099f695d31326c39595264c34988a0f4b7cbc698 21:42:38 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 21:42:38 INFO - mozversion INFO | platform_version: 45.0a1 21:42:38 INFO - 2015-11-24 21:42:38,728 DEBUG : using testdate: 1448430158 21:42:38 INFO - 2015-11-24 21:42:38,728 DEBUG : actual date: 1448430158 21:42:38 INFO - 2015-11-24 21:42:38,737 INFO : starting webserver on 'localhost:58590' 21:42:38 INFO - 2015-11-24 21:42:38,738 INFO : Starting test suite talos-linux64-ix-016 21:42:38 INFO - 2015-11-24 21:42:38,738 INFO : Starting test dromaeo_css 21:42:38 INFO - 2015-11-24 21:42:38,738 DEBUG : operating with platform_type : linux_ 21:42:38 INFO - 2015-11-24 21:42:38,738 INFO : Initialising browser for dromaeo_css test... 21:42:38 INFO - 2015-11-24 21:42:38,752 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKdY4pv/profile http://localhost:58590/getInfo.html 21:42:42 INFO - 2015-11-24 21:42:42,688 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 21:42:42 INFO - 2015-11-24 21:42:42,688 DEBUG : BROWSER_OUTPUT: colorDepth:24 21:42:42 INFO - 2015-11-24 21:42:42,688 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 21:42:42 INFO - 2015-11-24 21:42:42,688 DEBUG : BROWSER_OUTPUT: __metrics 21:42:42 INFO - 2015-11-24 21:42:42,833 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:42:44 INFO - 2015-11-24 21:42:44,021 INFO : Browser initialized. 21:42:44 INFO - 2015-11-24 21:42:44,022 INFO : Running cycle 1/1 for dromaeo_css test... 21:42:44 INFO - 2015-11-24 21:42:44,022 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKdY4pv/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 21:42:55 INFO - 2015-11-24 21:42:55,679 DEBUG : BROWSER_OUTPUT: RSS: Main: 139112448 21:42:55 INFO - 2015-11-24 21:42:55,679 DEBUG : BROWSER_OUTPUT: 21:43:46 INFO - 2015-11-24 21:43:46,490 DEBUG : BROWSER_OUTPUT: 1448430226485 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] 21:45:56 INFO - 2015-11-24 21:45:56,299 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58590/tests/dromaeo/cssquery-ext.html) 21:45:57 INFO - 2015-11-24 21:45:57,560 DEBUG : BROWSER_OUTPUT: RSS: Main: 268906496 21:45:57 INFO - 2015-11-24 21:45:57,560 DEBUG : BROWSER_OUTPUT: 21:48:59 INFO - 2015-11-24 21:48:59,989 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/dromaeo/cssquery-ext.html (next: http://localhost:58590/tests/dromaeo/cssquery-jquery.html) 21:49:00 INFO - 2015-11-24 21:49:00,550 DEBUG : BROWSER_OUTPUT: RSS: Main: 222162944 21:49:00 INFO - 2015-11-24 21:49:00,550 DEBUG : BROWSER_OUTPUT: 21:52:02 INFO - 2015-11-24 21:52:02,516 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58590/tests/dromaeo/cssquery-mootools.html) 21:52:03 INFO - 2015-11-24 21:52:03,083 DEBUG : BROWSER_OUTPUT: RSS: Main: 218566656 21:52:03 INFO - 2015-11-24 21:52:03,083 DEBUG : BROWSER_OUTPUT: 21:55:04 INFO - 2015-11-24 21:55:04,933 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58590/tests/dromaeo/cssquery-prototype.html) 21:55:05 INFO - 2015-11-24 21:55:05,522 DEBUG : BROWSER_OUTPUT: RSS: Main: 202395648 21:55:05 INFO - 2015-11-24 21:55:05,523 DEBUG : BROWSER_OUTPUT: 21:58:07 INFO - 2015-11-24 21:58:07,993 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58590/tests/dromaeo/cssquery-yui.html) 21:58:08 INFO - 2015-11-24 21:58:08,559 DEBUG : BROWSER_OUTPUT: RSS: Main: 205545472 21:58:08 INFO - 2015-11-24 21:58:08,560 DEBUG : BROWSER_OUTPUT: 22:01:11 INFO - 2015-11-24 22:01:11,188 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/dromaeo/cssquery-yui.html (next: http://localhost:58590/tests/dromaeo/cssquery-dojo.html) 22:01:11 INFO - 2015-11-24 22:01:11,447 DEBUG : BROWSER_OUTPUT: RSS: Main: 274407424 22:01:11 INFO - 2015-11-24 22:01:11,447 DEBUG : BROWSER_OUTPUT: 22:01:11 INFO - 2015-11-24 22:01:11,447 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:01:11 INFO - 2015-11-24 22:01:11,447 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:01:11 INFO - 2015-11-24 22:01:11,447 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:01:11 INFO - 2015-11-24 22:01:11,448 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:01:11 INFO - 2015-11-24 22:01:11,448 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3238.31995280991;3289.276359200978;3339.74951878609;3340.1148695595657;3365.4951757236418;5286.550643195333;5384.9499347627225;5486.533644640205;5488.7530061147245;5546.140348246024;5414.133738601825;5447.536959358459;5519.254900588419;5562.02664533706;5585.804496842611;4962.826301079463;4968.776405061772;4989.126902791997;5020.945601423706;5030.496950304977;5046.243063540462;5172.896542069157;5176.5341119299255;5195.766190521435;5207.036698210824;4942.703437793726;5015.473375295578;5049.64652474327;5125.359330083739;5144.845654630367;5132.3327967364;5134.306868572732;5136.768845401966;5164.767585458663;5181.689098654055;5071.1632580624155;5106.412762532319;5118.462561431071;5124.461931497176;5168.966206758663;5309.973450132725;5312.335958005249;5399.676019438846;5441.646292990943;5512.448755124456;6008.969955150196;6023.488003519706;6028.5478589106;6047.969760151215;6076.513878889678;6053.788117415869;6066.211392518944;6090.786822461193;6098.298695650013;6112.480439162677;5433.972830135864;5532.806351777709;5559.6664200148125;5600.0759874620235;5665.1502274658305;6186.4741496972365;6203.875922481525;6214.25428948529;6214.316425193225;6222.657753823583;5883.499902508249;6026.457618814327;6028.457438830438;6035.064564992434;6037.18498002764;5795.217645617788;5826.970865145647;5907.911381329283;5917.408259174048;6028.216331876915;5987.730552125165;5990.400959904062;5990.6705131218205;6002.639841609517;6064.66644334557;5863.941360586425;5989.670568118708;6007.06890431975;6009.759609615567;6018.668973206428;4541.477730061019;4546.068056048568;4549.363089167519;4604.884877878013;4663.556962088596;4077;4083;4095.4266402703647;4132.152908653718;4139.917201656011;5484.451554844483;5780.71096445176;5784.4504772046585;5786.913196302059;5792.275965504312;5896.911546326723;5983.19226904371;5990.341062483123;6048.909266361008;6079.270487541523;5730.226419433323;5731.942680573225;5744.626599271034;5745.3105627324985;5763.2507773989955;5029.27075574047;5253.658512196772;5254.7635356409055;5268.9736551317;5270.130428479335;6118.357572454917;6168.1056246844855;6178.6601736904;6180.351063138306;6196.752129914933;6092.025275955826;6098;6102.328743838086;6107.083937409424;6127.509799216141;5951.970240148945;5955.344912059583;6313;6313.3686631336495;6323.873522529616;5760.567957403296;5854.882902342015;5907.763689452546;5908.763649453974;5949.702514874152;6001.849953751192;6003.609765365237;6010.849728756816;6019.608725432833;6070.6357618543025;5745.109508026263;5832.9708351457975;5867.823965281048;5877.324107727666;5894.6757928314355;5749.540036796962;5816.21481100063;5888.411158884077;5893.057110862413;5949.137375080502;5715.057015592381;5717.742701578356;5717.942820571741;5727.828165155052;5730.856728581819;5641.294838145232;5753.942460575341;5833.766649333979;5883.764649413975;5893.381194974466;5801.216835727123;5844.444777746107;5875.41245875409;5880;5923.911141332797 22:01:11 INFO - 2015-11-24 22:01:11,448 DEBUG : BROWSER_OUTPUT: |1;ext.html;542.8396632257686;586.0769288370815;589.5961266532423;599.5084031094503;609.5428428678491;31903.840480797593;40294.79852600736;40364.596354036454;40370;40371.59628403715;43382.78308608461;44071.77964110175;44094.33858492117;44253.77873110638;44312.3353149703;7678.42411819113;7788.766337009871;8675.69635062773;8844.11558844117;10230.130438912702;10399.53202105907;10454.947725261402;10492.265541412104;10512.579496820117;10522.736931576695;10478.68563943079;10594.099501542378;10599.152067834555;10602.469876506182;10602.734931626732;7911.090224624156;8070.515769053875;10642.148628109733;10839.187060970458;10852.023317901427;10357.896421035768;10508.684739457829;10509.632162874339;10519.474026298654;10522.894771052344;40738.59261407378;41000.79499602513;41042.58957410447;41091;41101.79449102765;3244.772865899388;3315.287213249163;3330.8667653293837;3366.73066154707;3413.2320227948762;30492.695073049206;30736.385272294654;30919.227019324473;31342.843285783652;31873;3813.561440434358;3915.4126880967797;3926.5680775114715;4052.675785937118;4095.877123686294;259644;259800;259870;260819;261391;58292.417075829544;58519;58706;58726.412735872946;58752.41247587555;61083.694581526805;62277.37722622806;63147.368526314145;63715.68142159352;64669.67665161644;14333;14333.283335833166;14333.56999290023;14409.78385324221;14429.278536073365;11257.268277561932;11322.773544529064;11382.715432114263;11387.658370248906;11419.31484110956;20013.199472020955;20091;20223.696644550342;20322.796772032092;20349.898250508653;7830.530168189927;8050.19498050202;8064.6370913307865;8070.596470176585;8080.313173380328;1032.4218437674926;1040.1626690514058;1072.292287090532;1074.8011617850566;1152.3258893547368;27581.58627620587;27784.305392365353;27979.860100699367;28003;28098.85950570234;1068.3269540189785;1105.2926127278547;1121.4448847820217;1151.5336288803048;1203.4945322964377;14594.48919287841;14735.336909838868;14750.262486875614;15012.249387530579;15080.170590617621;314689;318753;320018;320215;320648;52817;53066.46933530692;53129;53165.2025219622;53203;1347.6294019144814;1360.4490181476517;1361.918284902909;1365.378752667534;1371.8765311122047;27479.450410992067;28043.48259301342;28316.150515483743;28492.715072849835;28781.424371512872;46760.53239467562;46806.297905530766;46947.765261173474;47088.52911470841;47208;284686;286793;286812;286979;287123.5150593953;28479.572806407505;33909.15227119243;34445.31109377849;34494.82752586321;34811.651883481856;41663.37504937489;42111.578884211995;42909.57090429181;43184.35223471588;43225;33520.49719254262;36048.4592731104;36119.58937982303;36685;36761;28743.137705868856;34077.65922340844;35656.28687426185;36896;37195.814020930804;32534.83732581322;32906.67093329036;33062.83468582642;33496.49755253722;33526.16184595307;31555.053348399586;32241.838790805898;33072;33704.49443258402;33859.32281354408;23621.173258935854;23685.52628947446;23701.407464813517;23791;23952.760472395054 22:01:11 INFO - 2015-11-24 22:01:11,448 DEBUG : BROWSER_OUTPUT: |2;jquery.html;346.14674826552545;351.15897425665514;353.1982399952109;354.67901549098065;355.64790857051526;14240.287985600731;14573.781393279109;16204.513864584067;16661.666766664675;17839.28642854286;16106.516804495885;16228.513144605651;16252.349906003776;16262.593435164126;16274.104924229163;4687.671862969585;4865.5134486551415;4870.147724148278;4870.975645121769;4872.537108974641;3801.4107813288847;3916.8237429315604;4840.177169881129;5328.5470734987375;5493.340799104113;5372.140457526796;5437.32033495813;5442.292501974738;5469.316335458067;5474.014677358074;5355.384130824966;6059.091136329542;6063.757449702007;6063.848403789896;6066.63600183986;5375.032494151052;5378.085725426687;5386.838394848121;5401.918971215435;5403.2705584745945;17867.910660446745;18693.719594206097;19206.135723892457;20271.08780104884;20271.89864050685;2050.805173508529;2062.6287268291703;2089.905954232048;2095.9371218863457;2107.200596940679;8926.73219803407;8971.372003959725;9200.30997675177;9285.953570232174;9332;1768.5843826700716;1796.4341232511847;1850.1489314915154;1869.4858913798678;1948.8635795494322;58567;60655.39344606541;61002;61257;61755;10158.746031349276;10179.949100254451;10252.38485690861;10258.846117308247;10309.84535231972;13226.867731322756;13367.7968982792;13377.264250466123;13395.397207125703;13418.127821691558;7090.255523170096;7231.674574644048;7254.948032535252;7277.708891644275;7357.926420735724;7027.332403421667;7094.858102838018;7098.396636285974;7129.215786263507;7147.392471639865;4193.51774545925;4259.212045771542;4279.9786001070415;4431.645468362523;4439.467263928284;1720.9225584848712;1769.3188122572644;1771.9822801772075;1804.143032059761;1833.9449816505526;928.9345160968776;937.8968313485511;946.9005754395827;1004.9346792458467;1068.3589846092282;16476.340946362252;16929.830701692827;17061.146942652817;17251.65496690059;17310.567235819206;937.3579098317674;952.79991201847;953.6471505542994;956.5695437053215;970.7912798748162;9669.323147379757;9804.803903922024;9853.113219810251;9873.654422095193;9873.901260987443;506248;510866;513554;521105;527083;75952.24047759562;78744.60627696825;79691;79783;80421.19578804255;1037.6575730009079;1050.138886113395;1055.3456856748865;1058.6982709927634;1077.4666540062562;15007.624809379853;15107.622309442351;15151.469698560497;15327.233638318263;15499.612509687347;34540.4094692894;35021.64978350184;35170.47244291286;35362.823185883906;36056;45567.31649025201;45678;45779;46156.307655384524;49044.50955490399;9208;9434.773479447627;9528.571214295513;9551.188149007412;9554.904450955402;11274.323540587591;11287.887121128684;16882.24029918632;17209.913950430167;17218.913905430392;10805.135589152691;12056.156069075081;12286.262824230575;13958.651033724238;14160.716785664436;13342.399592018188;13611;13640.590782276548;13861.898951667043;14067.366968486238;11816.527338906348;13333.200017997835;14186.92906535502;14349.713005740035;14783.186924719244;8942.73171804847;8951.41815781972;8989.056149104512;9058.90941090607;9062.773430664287;9375.10936461035;9493.193078588121;9499.905000949902;9589.952050239704;10588.788224235319 22:01:11 INFO - 2015-11-24 22:01:11,448 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1333.9399727012283;1450.7316146512894;1504.804375431194;1618.9190540472973;1774.1395423219735;6685.398314151726;7541.132769731483;7884.093329267136;7905.881411778828;7924.6433910474025;7702.114256860463;7844.3332316753;7849.411294152947;7856.332211761993;7867.2132786721295;2251.0995601759305;2279.680844681746;2284.120613563773;2285.074544809355;2286.7713228677167;2290.1640901071073;2304.44693273614;2386.4988352446007;2395.018042602533;2398.784109430151;2256.8758718270487;2263.7736226377397;2290.656401539774;2302.1366987379733;2303.67748515208;2294.162630639813;2317.5133222023396;2320.953580928372;2321.083172147001;2322.280093171114;2299.7470278269375;2322.941926451835;2323.186884590397;2324.2097686786574;2326.1160758911674;6554.93445065548;6704.865902681968;7820.960895195488;7864.882026769603;7889.368850491946;4559.726416415026;4614.077184563067;4646.9767651161865;4656.231721765905;4656.697314674535;3120.3915236528887;3192.2498212919945;3199.808011519293;3209.935801283961;3320.186554294189;1031.2265800649513;1035.9847349597362;1049.5906596427324;1100.7963526747496;1105.6351404036648;10736.624218152327;10828.72928176794;10855.185861060452;10866.945665271624;10876.401797901033;4272.786360682016;4300.61294483498;4302.7633480158265;4337.479502459725;4340.956590434118;8711.912880871238;8717.171868672465;8721.08428614999;8723;8733.56332183401;4744.264638980894;4758.286257061399;4785.042991401733;4786.497417771084;4797.616190704735;4362.716423432466;4386.583274588973;4387;4390.07808360248;4408.9559104408545;3474.4961980512685;3525.806080665588;3532.4877892705413;3552.5381700379303;3553.1650062235353;1091.6724982505216;1095.315427857589;1097.160672085855;1101.2786624760795;1112.1825458288151;1300.3173333999725;1301.7852054411105;1303.7066660001612;1313.507434711983;1340.7184491256946;7322.377597904131;7396.223396543393;7397.926020739724;7400.555966642019;7438.516496427823;477.03638649926955;477.18877907557294;481.35017726070225;481.4415278277182;488.7776061891831;5325.227841962894;5406.59450541211;5757.165211044375;5804.332501762267;5808.157817116495;9078.182963533314;9117.088291170963;9286.489243091695;9322.300827437834;9325.720228393158;9038.412503187403;9110.36227464071;9175.816483670289;9183.40307879999;9189;7210.60341681202;7304.196538380774;7405.851882962311;7407.777766667009;7424.071991001125;7125.3230943060325;7250.673719682733;7271.236520165518;7280.199178090512;7281.963590182015;7887.526748395115;8305.501669899826;8435.578221089043;8471.364647651328;8492.108328625653;8333.708320208763;8386;8495.787605309917;8660.523671198145;8667.783305417415;7121.4302855770375;7198.604076775828;7207.747728829466;7208;7212.3148300911325;7568.772936811904;7587.430942679211;7618.847623047619;7621.618919054136;7667.0416197975255;7219.819504512429;7301.598412087386;7317.451191160578;7380.815479613053;7429.07136607924;6999.30006999296;7003.54476958996;7029.824254393681;7123.145222573117;7193.172785129777;6691.263960964193;6691.832704182434;6701.966490167518;6702.028205910213;6728.394444500019;6267.059941008885;6412.39082287182;6550.148480697479;6568.441682456853;6572.4084832363405;6684;6717.697703603252;6733.764318248838;6736.494762892705;6739.898901516579 22:01:11 INFO - 2015-11-24 22:01:11,448 DEBUG : BROWSER_OUTPUT: |4;prototype.html;329.46461999251216;332.8968019913827;333.94823802310634;334.45316906857283;340.7904138954544;5889.146073819305;5889.263842019747;5958.493528050116;6021.367756385582;6025.608335458198;5768.192453056565;5901.232839730829;5906.763729450828;5935.28776546815;5955.791547295846;4590.265557510799;4590.862274131781;4591.816327346902;4596.7931443085145;4604.217283061887;4408.71343362682;4489.371487991683;4493.438320209973;4499.235130027903;4603.677742558022;4474.127545128701;4499.527549607277;4551.5676010778925;4568.931466027996;4586.793594288249;4559.635229181674;4568.314752787109;4568.908621827562;4575.885602859922;4581.816727330903;4032.112935154291;4044.312466880637;4049.6962727795535;4052.4326594276827;4053.047533829548;5595.720213989284;5688.5733569982;5699.202111704365;5739.741711622945;5744.655320680772;5225.1117310057;5226.73866306687;5230;5235.6073294502685;5237.895242095136;6009.639421634716;6303.527235457359;6309.968450157719;6315.8736825263695;6317.368263173692;4228.682848786353;4238.87283381499;4375.803088861009;4377.34339849022;4390.824367025315;6457.903131453032;6510.9023364649565;6522.119513865662;6527.15147030883;6545.083688283644;4460.130274596422;4462.41988541494;4463.330500424962;4465.955340446619;4466.263066593976;4446.244138496463;4518.480374756879;4525.298578720303;4530.546945305443;4543.4093567836635;5237.869053273623;5246.527812496894;5247.449017853147;5247.816326428633;5249.685018898878;6347.682615869188;6356.269029061625;6357.300696923335;6366.395192456709;6368.29948705642;1556.6419723463666;1596.7764512968195;1598.4325564424694;1598.8001499812524;1605.4541455905048;1017.2218253036433;1040.9011143941314;1041.2815157541272;1041.338749893823;1043.9791204175874;6155.138280640714;6155.676529546169;6162.352952939877;6162.568620196633;6163.96918015416;6529.412352888168;6531.216254049545;6540.117084193668;6542.34576542342;6553.4101930826455;6015.518758499309;6142.416470435302;6158.1686472326555;6158.414950579688;6164.938350616526;3538.221591249921;3552.2007548301426;3556.110972256936;3556.751027428107;3560.2345495718546;6752.054712340277;6763.797086087426;6768;6769.966150169218;6773.9322606774285;6564;6695.129633147757;6697.665116744143;6701.899471507932;6705.430038446689;6724.260331363546;6740.393364597112;6748.932510674928;6751.831204219836;6754.493412993947;6341.953577659635;6484.837879053061;6540.117084193763;6543.345665433419;6547.934520654732;6398.968005159944;6410.711517981609;6412.935870641234;6516.804495865132;6556.704948277244;6824.761133360308;6825.453963682993;6837.1111755471475;6845.623490708059;6846.691898864464;4417.536158703419;4479.865604031885;4500.302453119772;4507.8196872124745;4509.729416235036;6573.967130164318;6584.144061272004;6588.670566471753;6590.373914478117;6593.967030164818;6422.903656445251;6430.1319321892815;6432.581882177644;6432.742690292336;6434.903476447764;6258.655773932477;6412.807615771534;6414.454771344302;6414.61512309263;6421.1010458536775;6277.686115694106;6281.497480201481;6286.748530058747;6290.874182516415;6291.4023167800815;6429.485641148791;6439.742410303723;6457.418832305115;6469.385408386196;6476.935230647633;6201.534884883562;6286.339934306832;6300.432961033529;6304.338044505261;6315.179026726496 22:01:11 INFO - 2015-11-24 22:01:11,449 DEBUG : BROWSER_OUTPUT: |5;yui.html;708.6315116139607;726.6148941061236;746.9327760501553;767.9808004799879;791.4143533784998;11848.407579621016;12171.33057681827;12185.329806860631;12202;12207.633770986862;11362.261453005549;11428.828567571492;11432.71418214545;11441.370724610144;11450.08399328054;665.1419668627465;678.6878036103399;806.5967016491754;824.983500329993;826.2316046077145;806.9072056713494;809.4212637963855;812.0539571399343;817.358373676664;819.7909533069059;714.653393104344;723.1105739939877;869.6260607938584;877.4165180155189;878.0341624213378;843.9367047471434;853.2960307746072;856.2336708645789;861.2248975921658;862.6420035685214;637.8819918315127;641.6310621392671;642.2421542579754;643.4177069751883;643.9420452159281;11223.32660040392;11326.32042077478;11332.263402878869;11347.716307092307;11372.37451940143;12016.218945768585;12749.023901295313;12831.690295746761;12913.160644558073;12941;10588.470576471223;10669.970247868083;10782.46087695612;10889.346639201594;10959.232853700245;3589.6410358964154;3620.8008559529217;3719.4420836874415;3762.1347090169143;3764.9435258471144;47526.7623661883;49106.50893491055;49153.50846491525;49167.50832491629;49312.75343623259;9768.316217864824;9844.556999925233;9908.20734341251;9924.255680823968;10276.075153236245;19118.33085841989;19201.903990479957;19222.327218547285;19224.231030758892;19236.903815480833;7707.768766937001;7722.613869306512;7898.328642065374;7956.602169891482;8112.675492980215;4179.770112643834;4233.724807887477;4268.381084742695;4317.41714868495;4384.868453946387;3158.921026974344;3162.7153556180056;3183.7771356004855;3325.0523600773663;3410.5736782902136;597.0238659791186;599.9940000599938;613.5796979069352;619.9473044791154;624.2415465209792;624.7594676049663;643.8776632439822;664.6311297229987;672.7477196051481;680.3332733920786;7962.442629016029;8262.8347433051;8329.833403331899;8529.872051919227;8579.14208579137;606.7178761875749;641.1842118282718;668.1547841979899;679.512933065498;698.8078278473461;3586.408242639935;3869.3228684980577;3876.399158130438;3909.4526766252748;3942.842286308573;58123.4187658118;58536.707316463144;58674.70662646659;58718.41281587129;58844.705776470844;50834;51747.482525174266;52390.47609523932;52485.47514524882;52600.21099683508;44401.77799110984;44830.55169448329;45458.31812522815;45880.541194588295;45938;7811.804704882424;8120.187981201832;8131.9593402032615;8229.177082291722;8399.19417325278;35732.285354293286;35858.28283434369;36066.63933360633;38460.03849903775;38915.6108438912;44528;44795;44868.32697509605;45748.771256143504;46494.53505464902;10371.481425928523;10612.734681633021;10808.243422960317;10956;11281.153913456688;11259.437028148724;11447.313161210353;11488.425578721197;11825.467853946766;12366.258024518558;10262.948685256526;10369.325993810378;10399.584016639552;10449.68650940473;10565.418901960466;10132.44271565071;10265.641627465311;10340.844887326546;10350.378977261389;10609.575616975235;7093.609851458219;10138.341007834468;10379.429131397847;10466.790664186521;10472.895271047191;9850.310478266452;9978.650747223812;10043.598256069969;10072.345297555636;10223.233257505568;7867.488613240121;9050.786970625333;9353;9417.952910235404;9527.26153296156 22:01:11 INFO - 2015-11-24 22:01:11,449 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:01:11 INFO - 2015-11-24 22:01:11,449 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:01:11 INFO - 2015-11-24 22:01:11,449 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1041 22:01:11 INFO - 2015-11-24 22:01:11,449 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:01:11 INFO - 2015-11-24 22:01:11,449 DEBUG : BROWSER_OUTPUT: __startTimestamp1448431271439__endTimestamp 22:01:11 INFO - 2015-11-24 22:01:11,449 DEBUG : BROWSER_OUTPUT: 22:01:11 INFO - 2015-11-24 22:01:11,451 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:01:11 INFO - 2015-11-24 22:01:11,451 DEBUG : BROWSER_OUTPUT: Number of tests: 6 22:01:11 INFO - 2015-11-24 22:01:11,451 DEBUG : BROWSER_OUTPUT: 22:01:11 INFO - 2015-11-24 22:01:11,451 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5566.87 Median:5784.45 stddev:631.40 (10.9%) stddev-sans-first:606.34 22:01:11 INFO - 2015-11-24 22:01:11,451 DEBUG : BROWSER_OUTPUT: Values: 3238.3 3289.3 3339.7 3340.1 3365.5 5286.6 5384.9 5486.5 5488.8 5546.1 5414.1 5447.5 5519.3 5562.0 5585.8 4962.8 4968.8 4989.1 5020.9 5030.5 5046.2 5172.9 5176.5 5195.8 5207.0 4942.7 5015.5 5049.6 5125.4 5144.8 5132.3 5134.3 5136.8 5164.8 5181.7 5071.2 5106.4 5118.5 5124.5 5169.0 5310.0 5312.3 5399.7 5441.6 5512.4 6009.0 6023.5 6028.5 6048.0 6076.5 6053.8 6066.2 6090.8 6098.3 6112.5 5434.0 5532.8 5559.7 5600.1 5665.2 6186.5 6203.9 6214.3 6214.3 6222.7 5883.5 6026.5 6028.5 6035.1 6037.2 5795.2 5827.0 5907.9 5917.4 6028.2 5987.7 5990.4 5990.7 6002.6 6064.7 5863.9 5989.7 6007.1 6009.8 6018.7 4541.5 4546.1 4549.4 4604.9 4663.6 4077.0 4083.0 4095.4 4132.2 4139.9 5484.5 5780.7 5784.5 5786.9 5792.3 5896.9 5983.2 5990.3 6048.9 6079.3 5730.2 5731.9 5744.6 5745.3 5763.3 5029.3 5253.7 5254.8 5269.0 5270.1 6118.4 6168.1 6178.7 6180.4 6196.8 6092.0 6098.0 6102.3 6107.1 6127.5 5952.0 5955.3 6313.0 6313.4 6323.9 5760.6 5854.9 5907.8 5908.8 5949.7 6001.8 6003.6 6010.8 6019.6 6070.6 5745.1 5833.0 5867.8 5877.3 5894.7 5749.5 5816.2 5888.4 5893.1 5949.1 5715.1 5717.7 5717.9 5727.8 5730.9 5641.3 5753.9 5833.8 5883.8 5893.4 5801.2 5844.4 5875.4 5880.0 5923.9 22:01:11 INFO - 2015-11-24 22:01:11,451 DEBUG : BROWSER_OUTPUT: 22:01:11 INFO - 2015-11-24 22:01:11,451 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:46225.62 Median:320116.50 stddev:75559.38 (23.6%) stddev-sans-first:75693.49 22:01:11 INFO - 2015-11-24 22:01:11,452 DEBUG : BROWSER_OUTPUT: Values: 542.8 586.1 589.6 599.5 609.5 31903.8 40294.8 40364.6 40370.0 40371.6 43382.8 44071.8 44094.3 44253.8 44312.3 7678.4 7788.8 8675.7 8844.1 10230.1 10399.5 10454.9 10492.3 10512.6 10522.7 10478.7 10594.1 10599.2 10602.5 10602.7 7911.1 8070.5 10642.1 10839.2 10852.0 10357.9 10508.7 10509.6 10519.5 10522.9 40738.6 41000.8 41042.6 41091.0 41101.8 3244.8 3315.3 3330.9 3366.7 3413.2 30492.7 30736.4 30919.2 31342.8 31873.0 3813.6 3915.4 3926.6 4052.7 4095.9 259644.0 259800.0 259870.0 260819.0 261391.0 58292.4 58519.0 58706.0 58726.4 58752.4 61083.7 62277.4 63147.4 63715.7 64669.7 14333.0 14333.3 14333.6 14409.8 14429.3 11257.3 11322.8 11382.7 11387.7 11419.3 20013.2 20091.0 20223.7 20322.8 20349.9 7830.5 8050.2 8064.6 8070.6 8080.3 1032.4 1040.2 1072.3 1074.8 1152.3 27581.6 27784.3 27979.9 28003.0 28098.9 1068.3 1105.3 1121.4 1151.5 1203.5 14594.5 14735.3 14750.3 15012.2 15080.2 314689.0 318753.0 320018.0 320215.0 320648.0 52817.0 53066.5 53129.0 53165.2 53203.0 1347.6 1360.4 1361.9 1365.4 1371.9 27479.5 28043.5 28316.2 28492.7 28781.4 46760.5 46806.3 46947.8 47088.5 47208.0 284686.0 286793.0 286812.0 286979.0 287123.5 28479.6 33909.2 34445.3 34494.8 34811.7 41663.4 42111.6 42909.6 43184.4 43225.0 33520.5 36048.5 36119.6 36685.0 36761.0 28743.1 34077.7 35656.3 36896.0 37195.8 32534.8 32906.7 33062.8 33496.5 33526.2 31555.1 32241.8 33072.0 33704.5 33859.3 23621.2 23685.5 23701.4 23791.0 23952.8 22:01:11 INFO - 2015-11-24 22:01:11,452 DEBUG : BROWSER_OUTPUT: 22:01:11 INFO - 2015-11-24 22:01:11,453 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:27781.59 Median:4226.36 stddev:84360.63 (1996.1%) stddev-sans-first:84572.13 22:01:11 INFO - 2015-11-24 22:01:11,453 DEBUG : BROWSER_OUTPUT: Values: 346.1 351.2 353.2 354.7 355.6 14240.3 14573.8 16204.5 16661.7 17839.3 16106.5 16228.5 16252.3 16262.6 16274.1 4687.7 4865.5 4870.1 4871.0 4872.5 3801.4 3916.8 4840.2 5328.5 5493.3 5372.1 5437.3 5442.3 5469.3 5474.0 5355.4 6059.1 6063.8 6063.8 6066.6 5375.0 5378.1 5386.8 5401.9 5403.3 17867.9 18693.7 19206.1 20271.1 20271.9 2050.8 2062.6 2089.9 2095.9 2107.2 8926.7 8971.4 9200.3 9286.0 9332.0 1768.6 1796.4 1850.1 1869.5 1948.9 58567.0 60655.4 61002.0 61257.0 61755.0 10158.7 10179.9 10252.4 10258.8 10309.8 13226.9 13367.8 13377.3 13395.4 13418.1 7090.3 7231.7 7254.9 7277.7 7357.9 7027.3 7094.9 7098.4 7129.2 7147.4 4193.5 4259.2 4280.0 4431.6 4439.5 1720.9 1769.3 1772.0 1804.1 1833.9 928.9 937.9 946.9 1004.9 1068.4 16476.3 16929.8 17061.1 17251.7 17310.6 937.4 952.8 953.6 956.6 970.8 9669.3 9804.8 9853.1 9873.7 9873.9 506248.0 510866.0 513554.0 521105.0 527083.0 75952.2 78744.6 79691.0 79783.0 80421.2 1037.7 1050.1 1055.3 1058.7 1077.5 15007.6 15107.6 15151.5 15327.2 15499.6 34540.4 35021.6 35170.5 35362.8 36056.0 45567.3 45678.0 45779.0 46156.3 49044.5 9208.0 9434.8 9528.6 9551.2 9554.9 11274.3 11287.9 16882.2 17209.9 17218.9 10805.1 12056.2 12286.3 13958.7 14160.7 13342.4 13611.0 13640.6 13861.9 14067.4 11816.5 13333.2 14186.9 14349.7 14783.2 8942.7 8951.4 8989.1 9058.9 9062.8 9375.1 9493.2 9499.9 9590.0 10588.8 22:01:11 INFO - 2015-11-24 22:01:11,453 DEBUG : BROWSER_OUTPUT: 22:01:11 INFO - 2015-11-24 22:01:11,453 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5447.51 Median:6339.73 stddev:2823.46 (44.5%) stddev-sans-first:2814.45 22:01:11 INFO - 2015-11-24 22:01:11,454 DEBUG : BROWSER_OUTPUT: Values: 1333.9 1450.7 1504.8 1618.9 1774.1 6685.4 7541.1 7884.1 7905.9 7924.6 7702.1 7844.3 7849.4 7856.3 7867.2 2251.1 2279.7 2284.1 2285.1 2286.8 2290.2 2304.4 2386.5 2395.0 2398.8 2256.9 2263.8 2290.7 2302.1 2303.7 2294.2 2317.5 2321.0 2321.1 2322.3 2299.7 2322.9 2323.2 2324.2 2326.1 6554.9 6704.9 7821.0 7864.9 7889.4 4559.7 4614.1 4647.0 4656.2 4656.7 3120.4 3192.2 3199.8 3209.9 3320.2 1031.2 1036.0 1049.6 1100.8 1105.6 10736.6 10828.7 10855.2 10866.9 10876.4 4272.8 4300.6 4302.8 4337.5 4341.0 8711.9 8717.2 8721.1 8723.0 8733.6 4744.3 4758.3 4785.0 4786.5 4797.6 4362.7 4386.6 4387.0 4390.1 4409.0 3474.5 3525.8 3532.5 3552.5 3553.2 1091.7 1095.3 1097.2 1101.3 1112.2 1300.3 1301.8 1303.7 1313.5 1340.7 7322.4 7396.2 7397.9 7400.6 7438.5 477.0 477.2 481.4 481.4 488.8 5325.2 5406.6 5757.2 5804.3 5808.2 9078.2 9117.1 9286.5 9322.3 9325.7 9038.4 9110.4 9175.8 9183.4 9189.0 7210.6 7304.2 7405.9 7407.8 7424.1 7125.3 7250.7 7271.2 7280.2 7282.0 7887.5 8305.5 8435.6 8471.4 8492.1 8333.7 8386.0 8495.8 8660.5 8667.8 7121.4 7198.6 7207.7 7208.0 7212.3 7568.8 7587.4 7618.8 7621.6 7667.0 7219.8 7301.6 7317.5 7380.8 7429.1 6999.3 7003.5 7029.8 7123.1 7193.2 6691.3 6691.8 6702.0 6702.0 6728.4 6267.1 6412.4 6550.1 6568.4 6572.4 6684.0 6717.7 6733.8 6736.5 6739.9 22:01:11 INFO - 2015-11-24 22:01:11,454 DEBUG : BROWSER_OUTPUT: 22:01:11 INFO - 2015-11-24 22:01:11,454 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5302.98 Median:6023.49 stddev:1607.35 (26.7%) stddev-sans-first:1567.91 22:01:11 INFO - 2015-11-24 22:01:11,456 DEBUG : BROWSER_OUTPUT: Values: 329.5 332.9 333.9 334.5 340.8 5889.1 5889.3 5958.5 6021.4 6025.6 5768.2 5901.2 5906.8 5935.3 5955.8 4590.3 4590.9 4591.8 4596.8 4604.2 4408.7 4489.4 4493.4 4499.2 4603.7 4474.1 4499.5 4551.6 4568.9 4586.8 4559.6 4568.3 4568.9 4575.9 4581.8 4032.1 4044.3 4049.7 4052.4 4053.0 5595.7 5688.6 5699.2 5739.7 5744.7 5225.1 5226.7 5230.0 5235.6 5237.9 6009.6 6303.5 6310.0 6315.9 6317.4 4228.7 4238.9 4375.8 4377.3 4390.8 6457.9 6510.9 6522.1 6527.2 6545.1 4460.1 4462.4 4463.3 4466.0 4466.3 4446.2 4518.5 4525.3 4530.5 4543.4 5237.9 5246.5 5247.4 5247.8 5249.7 6347.7 6356.3 6357.3 6366.4 6368.3 1556.6 1596.8 1598.4 1598.8 1605.5 1017.2 1040.9 1041.3 1041.3 1044.0 6155.1 6155.7 6162.4 6162.6 6164.0 6529.4 6531.2 6540.1 6542.3 6553.4 6015.5 6142.4 6158.2 6158.4 6164.9 3538.2 3552.2 3556.1 3556.8 3560.2 6752.1 6763.8 6768.0 6770.0 6773.9 6564.0 6695.1 6697.7 6701.9 6705.4 6724.3 6740.4 6748.9 6751.8 6754.5 6342.0 6484.8 6540.1 6543.3 6547.9 6399.0 6410.7 6412.9 6516.8 6556.7 6824.8 6825.5 6837.1 6845.6 6846.7 4417.5 4479.9 4500.3 4507.8 4509.7 6574.0 6584.1 6588.7 6590.4 6594.0 6422.9 6430.1 6432.6 6432.7 6434.9 6258.7 6412.8 6414.5 6414.6 6421.1 6277.7 6281.5 6286.7 6290.9 6291.4 6429.5 6439.7 6457.4 6469.4 6476.9 6201.5 6286.3 6300.4 6304.3 6315.2 22:01:11 INFO - 2015-11-24 22:01:11,456 DEBUG : BROWSER_OUTPUT: 22:01:11 INFO - 2015-11-24 22:01:11,456 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13680.61 Median:45909.27 stddev:16221.35 (35.3%) stddev-sans-first:16237.61 22:01:11 INFO - 2015-11-24 22:01:11,457 DEBUG : BROWSER_OUTPUT: Values: 708.6 726.6 746.9 768.0 791.4 11848.4 12171.3 12185.3 12202.0 12207.6 11362.3 11428.8 11432.7 11441.4 11450.1 665.1 678.7 806.6 825.0 826.2 806.9 809.4 812.1 817.4 819.8 714.7 723.1 869.6 877.4 878.0 843.9 853.3 856.2 861.2 862.6 637.9 641.6 642.2 643.4 643.9 11223.3 11326.3 11332.3 11347.7 11372.4 12016.2 12749.0 12831.7 12913.2 12941.0 10588.5 10670.0 10782.5 10889.3 10959.2 3589.6 3620.8 3719.4 3762.1 3764.9 47526.8 49106.5 49153.5 49167.5 49312.8 9768.3 9844.6 9908.2 9924.3 10276.1 19118.3 19201.9 19222.3 19224.2 19236.9 7707.8 7722.6 7898.3 7956.6 8112.7 4179.8 4233.7 4268.4 4317.4 4384.9 3158.9 3162.7 3183.8 3325.1 3410.6 597.0 600.0 613.6 619.9 624.2 624.8 643.9 664.6 672.7 680.3 7962.4 8262.8 8329.8 8529.9 8579.1 606.7 641.2 668.2 679.5 698.8 3586.4 3869.3 3876.4 3909.5 3942.8 58123.4 58536.7 58674.7 58718.4 58844.7 50834.0 51747.5 52390.5 52485.5 52600.2 44401.8 44830.6 45458.3 45880.5 45938.0 7811.8 8120.2 8132.0 8229.2 8399.2 35732.3 35858.3 36066.6 38460.0 38915.6 44528.0 44795.0 44868.3 45748.8 46494.5 10371.5 10612.7 10808.2 10956.0 11281.2 11259.4 11447.3 11488.4 11825.5 12366.3 10262.9 10369.3 10399.6 10449.7 10565.4 10132.4 10265.6 10340.8 10350.4 10609.6 7093.6 10138.3 10379.4 10466.8 10472.9 9850.3 9978.7 10043.6 10072.3 10223.2 7867.5 9050.8 9353.0 9418.0 9527.3 22:01:11 INFO - 2015-11-24 22:01:11,457 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:01:11 INFO - 2015-11-24 22:01:11,457 DEBUG : BROWSER_OUTPUT: 22:01:12 INFO - 2015-11-24 22:01:12,186 INFO : Browser exited with error code: 0 22:01:12 INFO - 2015-11-24 22:01:12,205 INFO : Completed test dromaeo_css (00:18:33) 22:01:12 INFO - 2015-11-24 22:01:12,205 INFO : Starting test kraken 22:01:12 INFO - 2015-11-24 22:01:12,205 DEBUG : operating with platform_type : linux_ 22:01:12 INFO - 2015-11-24 22:01:12,205 INFO : Initialising browser for kraken test... 22:01:12 INFO - 2015-11-24 22:01:12,211 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXxA78Q/profile http://localhost:58590/getInfo.html 22:01:15 INFO - 2015-11-24 22:01:15,908 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:01:15 INFO - 2015-11-24 22:01:15,908 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:01:15 INFO - 2015-11-24 22:01:15,908 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 22:01:15 INFO - 2015-11-24 22:01:15,908 DEBUG : BROWSER_OUTPUT: __metrics 22:01:16 INFO - 2015-11-24 22:01:16,051 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:01:17 INFO - 2015-11-24 22:01:17,134 INFO : Browser initialized. 22:01:17 INFO - 2015-11-24 22:01:17,134 INFO : Running cycle 1/1 for kraken test... 22:01:17 INFO - 2015-11-24 22:01:17,134 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXxA78Q/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:01:28 INFO - 2015-11-24 22:01:28,834 DEBUG : BROWSER_OUTPUT: RSS: Main: 138780672 22:01:28 INFO - 2015-11-24 22:01:28,834 DEBUG : BROWSER_OUTPUT: 22:01:31 INFO - 2015-11-24 22:01:31,273 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58590/tests/kraken/driver.html?testName=audio-beat-detection) 22:01:31 INFO - 2015-11-24 22:01:31,847 DEBUG : BROWSER_OUTPUT: RSS: Main: 175747072 22:01:31 INFO - 2015-11-24 22:01:31,847 DEBUG : BROWSER_OUTPUT: 22:01:34 INFO - 2015-11-24 22:01:34,436 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58590/tests/kraken/driver.html?testName=audio-dft) 22:01:35 INFO - 2015-11-24 22:01:35,002 DEBUG : BROWSER_OUTPUT: RSS: Main: 164900864 22:01:35 INFO - 2015-11-24 22:01:35,002 DEBUG : BROWSER_OUTPUT: 22:01:37 INFO - 2015-11-24 22:01:37,840 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58590/tests/kraken/driver.html?testName=audio-fft) 22:01:38 INFO - 2015-11-24 22:01:38,393 DEBUG : BROWSER_OUTPUT: RSS: Main: 148500480 22:01:38 INFO - 2015-11-24 22:01:38,393 DEBUG : BROWSER_OUTPUT: 22:01:40 INFO - 2015-11-24 22:01:40,350 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58590/tests/kraken/driver.html?testName=audio-oscillator) 22:01:40 INFO - 2015-11-24 22:01:40,915 DEBUG : BROWSER_OUTPUT: RSS: Main: 153702400 22:01:40 INFO - 2015-11-24 22:01:40,916 DEBUG : BROWSER_OUTPUT: 22:01:43 INFO - 2015-11-24 22:01:43,094 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58590/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:01:43 INFO - 2015-11-24 22:01:43,655 DEBUG : BROWSER_OUTPUT: RSS: Main: 154689536 22:01:43 INFO - 2015-11-24 22:01:43,655 DEBUG : BROWSER_OUTPUT: 22:01:46 INFO - 2015-11-24 22:01:46,534 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58590/tests/kraken/driver.html?testName=imaging-darkroom) 22:01:47 INFO - 2015-11-24 22:01:47,100 DEBUG : BROWSER_OUTPUT: RSS: Main: 155013120 22:01:47 INFO - 2015-11-24 22:01:47,100 DEBUG : BROWSER_OUTPUT: 22:01:50 INFO - 2015-11-24 22:01:50,502 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58590/tests/kraken/driver.html?testName=imaging-desaturate) 22:01:51 INFO - 2015-11-24 22:01:51,064 DEBUG : BROWSER_OUTPUT: RSS: Main: 156459008 22:01:51 INFO - 2015-11-24 22:01:51,064 DEBUG : BROWSER_OUTPUT: 22:01:53 INFO - 2015-11-24 22:01:53,867 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58590/tests/kraken/driver.html?testName=json-parse-financial) 22:01:54 INFO - 2015-11-24 22:01:54,434 DEBUG : BROWSER_OUTPUT: RSS: Main: 156561408 22:01:54 INFO - 2015-11-24 22:01:54,434 DEBUG : BROWSER_OUTPUT: 22:01:55 INFO - 2015-11-24 22:01:55,989 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58590/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:01:56 INFO - 2015-11-24 22:01:56,562 DEBUG : BROWSER_OUTPUT: RSS: Main: 167481344 22:01:56 INFO - 2015-11-24 22:01:56,562 DEBUG : BROWSER_OUTPUT: 22:01:58 INFO - 2015-11-24 22:01:58,358 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58590/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:01:58 INFO - 2015-11-24 22:01:58,959 DEBUG : BROWSER_OUTPUT: RSS: Main: 164020224 22:01:58 INFO - 2015-11-24 22:01:58,960 DEBUG : BROWSER_OUTPUT: 22:02:01 INFO - 2015-11-24 22:02:01,023 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58590/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:02:01 INFO - 2015-11-24 22:02:01,603 DEBUG : BROWSER_OUTPUT: RSS: Main: 170483712 22:02:01 INFO - 2015-11-24 22:02:01,604 DEBUG : BROWSER_OUTPUT: 22:02:04 INFO - 2015-11-24 22:02:04,030 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58590/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:02:04 INFO - 2015-11-24 22:02:04,611 DEBUG : BROWSER_OUTPUT: RSS: Main: 178126848 22:02:04 INFO - 2015-11-24 22:02:04,611 DEBUG : BROWSER_OUTPUT: 22:02:07 INFO - 2015-11-24 22:02:07,126 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58590/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:02:07 INFO - 2015-11-24 22:02:07,699 DEBUG : BROWSER_OUTPUT: RSS: Main: 176766976 22:02:07 INFO - 2015-11-24 22:02:07,699 DEBUG : BROWSER_OUTPUT: 22:02:09 INFO - 2015-11-24 22:02:09,369 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58590/tests/kraken/driver.html?testName=ai-astar) 22:02:09 INFO - 2015-11-24 22:02:09,615 DEBUG : BROWSER_OUTPUT: RSS: Main: 234692608 22:02:09 INFO - 2015-11-24 22:02:09,615 DEBUG : BROWSER_OUTPUT: 22:02:09 INFO - 2015-11-24 22:02:09,616 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:02:09 INFO - 2015-11-24 22:02:09,617 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:02:09 INFO - 2015-11-24 22:02:09,618 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:02:09 INFO - 2015-11-24 22:02:09,618 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:02:09 INFO - 2015-11-24 22:02:09,618 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;104;109;110;109;99;109;108;101 22:02:09 INFO - 2015-11-24 22:02:09,618 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;124;152;164;125;154;150;126;167;132 22:02:09 INFO - 2015-11-24 22:02:09,619 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;160;162;159;163;161;162;161;161;161 22:02:09 INFO - 2015-11-24 22:02:09,619 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;89;93;71;73;72;104;93;71 22:02:09 INFO - 2015-11-24 22:02:09,619 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;94;130;92;94;94;136;92;93;135 22:02:09 INFO - 2015-11-24 22:02:09,619 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;109;110;109;110;110;110;110 22:02:09 INFO - 2015-11-24 22:02:09,619 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;146;145;146;146;145;146;146;145 22:02:09 INFO - 2015-11-24 22:02:09,619 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;94;96;96;95;95;96;95;96;95 22:02:09 INFO - 2015-11-24 22:02:09,619 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;56;57;57;57;58;56;56;56;56 22:02:09 INFO - 2015-11-24 22:02:09,620 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;70;69;70;70;71;69;70;70;69 22:02:09 INFO - 2015-11-24 22:02:09,620 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;86;85;89;86;84;84;86;86;87 22:02:09 INFO - 2015-11-24 22:02:09,620 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;120;117;121;121;127;133;135;116;119;120 22:02:09 INFO - 2015-11-24 22:02:09,620 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;155;148;148;146;137;148;150;138;136 22:02:09 INFO - 2015-11-24 22:02:09,620 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;66;66;66;67;65;67;67;67;65 22:02:09 INFO - 2015-11-24 22:02:09,620 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:02:09 INFO - 2015-11-24 22:02:09,620 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:02:09 INFO - 2015-11-24 22:02:09,621 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,908 22:02:09 INFO - 2015-11-24 22:02:09,621 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:02:09 INFO - 2015-11-24 22:02:09,621 DEBUG : BROWSER_OUTPUT: __startTimestamp1448431329616__endTimestamp 22:02:09 INFO - 2015-11-24 22:02:09,621 DEBUG : BROWSER_OUTPUT: 22:02:09 INFO - 2015-11-24 22:02:09,624 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:02:09 INFO - 2015-11-24 22:02:09,625 DEBUG : BROWSER_OUTPUT: Number of tests: 14 22:02:09 INFO - 2015-11-24 22:02:09,625 DEBUG : BROWSER_OUTPUT: 22:02:09 INFO - 2015-11-24 22:02:09,625 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.70 Median:109.00 stddev:3.95 (3.6%) stddev-sans-first:4.16 22:02:09 INFO - 2015-11-24 22:02:09,625 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 104.0 109.0 110.0 109.0 99.0 109.0 108.0 101.0 22:02:09 INFO - 2015-11-24 22:02:09,625 DEBUG : BROWSER_OUTPUT: 22:02:09 INFO - 2015-11-24 22:02:09,625 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:144.50 Median:151.50 stddev:16.35 (10.8%) stddev-sans-first:17.17 22:02:09 INFO - 2015-11-24 22:02:09,626 DEBUG : BROWSER_OUTPUT: Values: 151.0 124.0 152.0 164.0 125.0 154.0 150.0 126.0 167.0 132.0 22:02:09 INFO - 2015-11-24 22:02:09,626 DEBUG : BROWSER_OUTPUT: 22:02:09 INFO - 2015-11-24 22:02:09,626 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.15 (0.7%) stddev-sans-first:1.17 22:02:09 INFO - 2015-11-24 22:02:09,626 DEBUG : BROWSER_OUTPUT: Values: 160.0 160.0 162.0 159.0 163.0 161.0 162.0 161.0 161.0 161.0 22:02:09 INFO - 2015-11-24 22:02:09,626 DEBUG : BROWSER_OUTPUT: 22:02:09 INFO - 2015-11-24 22:02:09,626 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.90 Median:72.50 stddev:12.50 (17.2%) stddev-sans-first:12.74 22:02:09 INFO - 2015-11-24 22:02:09,626 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 89.0 93.0 71.0 73.0 72.0 104.0 93.0 71.0 22:02:09 INFO - 2015-11-24 22:02:09,626 DEBUG : BROWSER_OUTPUT: 22:02:09 INFO - 2015-11-24 22:02:09,626 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.30 Median:93.00 stddev:19.65 (21.1%) stddev-sans-first:20.33 22:02:09 INFO - 2015-11-24 22:02:09,626 DEBUG : BROWSER_OUTPUT: Values: 93.0 94.0 130.0 92.0 94.0 94.0 136.0 92.0 93.0 135.0 22:02:09 INFO - 2015-11-24 22:02:09,626 DEBUG : BROWSER_OUTPUT: 22:02:09 INFO - 2015-11-24 22:02:09,627 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.60 Median:110.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 22:02:09 INFO - 2015-11-24 22:02:09,627 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 109.0 110.0 109.0 110.0 110.0 110.0 110.0 22:02:09 INFO - 2015-11-24 22:02:09,627 DEBUG : BROWSER_OUTPUT: 22:02:09 INFO - 2015-11-24 22:02:09,627 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.50 22:02:09 INFO - 2015-11-24 22:02:09,627 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 146.0 145.0 146.0 146.0 145.0 146.0 146.0 145.0 22:02:09 INFO - 2015-11-24 22:02:09,627 DEBUG : BROWSER_OUTPUT: 22:02:09 INFO - 2015-11-24 22:02:09,627 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 22:02:09 INFO - 2015-11-24 22:02:09,627 DEBUG : BROWSER_OUTPUT: Values: 95.0 94.0 96.0 96.0 95.0 95.0 96.0 95.0 96.0 95.0 22:02:09 INFO - 2015-11-24 22:02:09,627 DEBUG : BROWSER_OUTPUT: 22:02:09 INFO - 2015-11-24 22:02:09,627 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.50 Median:56.50 stddev:0.71 (1.3%) stddev-sans-first:0.73 22:02:09 INFO - 2015-11-24 22:02:09,627 DEBUG : BROWSER_OUTPUT: Values: 56.0 56.0 57.0 57.0 57.0 58.0 56.0 56.0 56.0 56.0 22:02:09 INFO - 2015-11-24 22:02:09,627 DEBUG : BROWSER_OUTPUT: 22:02:09 INFO - 2015-11-24 22:02:09,628 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:70.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 22:02:09 INFO - 2015-11-24 22:02:09,628 DEBUG : BROWSER_OUTPUT: Values: 69.0 70.0 69.0 70.0 70.0 71.0 69.0 70.0 70.0 69.0 22:02:09 INFO - 2015-11-24 22:02:09,628 DEBUG : BROWSER_OUTPUT: 22:02:09 INFO - 2015-11-24 22:02:09,628 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:86.00 stddev:1.71 (2.0%) stddev-sans-first:1.54 22:02:09 INFO - 2015-11-24 22:02:09,628 DEBUG : BROWSER_OUTPUT: Values: 83.0 86.0 85.0 89.0 86.0 84.0 84.0 86.0 86.0 87.0 22:02:09 INFO - 2015-11-24 22:02:09,628 DEBUG : BROWSER_OUTPUT: 22:02:09 INFO - 2015-11-24 22:02:09,628 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:122.90 Median:121.00 stddev:6.56 (5.4%) stddev-sans-first:6.87 22:02:09 INFO - 2015-11-24 22:02:09,628 DEBUG : BROWSER_OUTPUT: Values: 120.0 117.0 121.0 121.0 127.0 133.0 135.0 116.0 119.0 120.0 22:02:09 INFO - 2015-11-24 22:02:09,628 DEBUG : BROWSER_OUTPUT: 22:02:09 INFO - 2015-11-24 22:02:09,628 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:144.70 Median:148.00 stddev:6.34 (4.3%) stddev-sans-first:6.58 22:02:09 INFO - 2015-11-24 22:02:09,628 DEBUG : BROWSER_OUTPUT: Values: 141.0 155.0 148.0 148.0 146.0 137.0 148.0 150.0 138.0 136.0 22:02:09 INFO - 2015-11-24 22:02:09,628 DEBUG : BROWSER_OUTPUT: 22:02:09 INFO - 2015-11-24 22:02:09,628 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.50 stddev:0.88 (1.3%) stddev-sans-first:0.83 22:02:09 INFO - 2015-11-24 22:02:09,628 DEBUG : BROWSER_OUTPUT: Values: 65.0 66.0 66.0 66.0 67.0 65.0 67.0 67.0 67.0 65.0 22:02:09 INFO - 2015-11-24 22:02:09,628 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:02:09 INFO - 2015-11-24 22:02:09,628 DEBUG : BROWSER_OUTPUT: 22:02:10 INFO - 2015-11-24 22:02:10,406 INFO : Browser exited with error code: 0 22:02:10 INFO - 2015-11-24 22:02:10,457 INFO : Completed test kraken (00:00:58) 22:02:10 INFO - 2015-11-24 22:02:10,457 INFO : Starting test v8_7 22:02:10 INFO - 2015-11-24 22:02:10,457 DEBUG : operating with platform_type : linux_ 22:02:10 INFO - 2015-11-24 22:02:10,458 INFO : Initialising browser for v8_7 test... 22:02:10 INFO - 2015-11-24 22:02:10,464 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpWpZIlV/profile http://localhost:58590/getInfo.html 22:02:14 INFO - 2015-11-24 22:02:14,000 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:02:14 INFO - 2015-11-24 22:02:14,000 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:02:14 INFO - 2015-11-24 22:02:14,000 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 22:02:14 INFO - 2015-11-24 22:02:14,000 DEBUG : BROWSER_OUTPUT: __metrics 22:02:14 INFO - 2015-11-24 22:02:14,062 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:02:15 INFO - 2015-11-24 22:02:15,334 INFO : Browser initialized. 22:02:15 INFO - 2015-11-24 22:02:15,334 INFO : Running cycle 1/1 for v8_7 test... 22:02:15 INFO - 2015-11-24 22:02:15,335 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpWpZIlV/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:02:26 INFO - 2015-11-24 22:02:26,991 DEBUG : BROWSER_OUTPUT: RSS: Main: 139096064 22:02:26 INFO - 2015-11-24 22:02:26,991 DEBUG : BROWSER_OUTPUT: 22:02:48 INFO - 2015-11-24 22:02:48,436 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58590/tests/v8_7/run.html (next: http://localhost:58590/tests/v8_7/run.html) 22:02:48 INFO - 2015-11-24 22:02:48,686 DEBUG : BROWSER_OUTPUT: RSS: Main: 628895744 22:02:48 INFO - 2015-11-24 22:02:48,686 DEBUG : BROWSER_OUTPUT: 22:02:48 INFO - 2015-11-24 22:02:48,686 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:02:48 INFO - 2015-11-24 22:02:48,686 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:02:48 INFO - 2015-11-24 22:02:48,687 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:02:48 INFO - 2015-11-24 22:02:48,687 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:02:48 INFO - 2015-11-24 22:02:48,687 DEBUG : BROWSER_OUTPUT: |0;Richards;165.53550736633008 22:02:48 INFO - 2015-11-24 22:02:48,687 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;195.96315892612188 22:02:48 INFO - 2015-11-24 22:02:48,687 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.60748723766307 22:02:48 INFO - 2015-11-24 22:02:48,688 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5133.333333333333 22:02:48 INFO - 2015-11-24 22:02:48,688 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1098.901098901099 22:02:48 INFO - 2015-11-24 22:02:48,688 DEBUG : BROWSER_OUTPUT: |5;Earley;780.0312012480499 22:02:48 INFO - 2015-11-24 22:02:48,688 DEBUG : BROWSER_OUTPUT: |6;Boyer;12419.753086419752 22:02:48 INFO - 2015-11-24 22:02:48,688 DEBUG : BROWSER_OUTPUT: |7;RegExp;25100 22:02:48 INFO - 2015-11-24 22:02:48,689 DEBUG : BROWSER_OUTPUT: |8;Splay;666.4544875875239 22:02:48 INFO - 2015-11-24 22:02:48,689 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 22:02:48 INFO - 2015-11-24 22:02:48,689 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:02:48 INFO - 2015-11-24 22:02:48,689 DEBUG : BROWSER_OUTPUT: __startTimestamp1448431368685__endTimestamp 22:02:48 INFO - 2015-11-24 22:02:48,689 DEBUG : BROWSER_OUTPUT: 22:02:48 INFO - 2015-11-24 22:02:48,690 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:02:48 INFO - 2015-11-24 22:02:48,690 DEBUG : BROWSER_OUTPUT: Number of tests: 10 22:02:48 INFO - 2015-11-24 22:02:48,690 DEBUG : BROWSER_OUTPUT: 22:02:48 INFO - 2015-11-24 22:02:48,690 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.54 Median:165.54 stddev:0.00 (0.0%) 22:02:48 INFO - 2015-11-24 22:02:48,690 DEBUG : BROWSER_OUTPUT: Values: 165.5 22:02:48 INFO - 2015-11-24 22:02:48,690 DEBUG : BROWSER_OUTPUT: 22:02:48 INFO - 2015-11-24 22:02:48,691 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:195.96 Median:195.96 stddev:0.00 (0.0%) 22:02:48 INFO - 2015-11-24 22:02:48,691 DEBUG : BROWSER_OUTPUT: Values: 196.0 22:02:48 INFO - 2015-11-24 22:02:48,691 DEBUG : BROWSER_OUTPUT: 22:02:48 INFO - 2015-11-24 22:02:48,691 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.61 Median:283.61 stddev:0.00 (0.0%) 22:02:48 INFO - 2015-11-24 22:02:48,691 DEBUG : BROWSER_OUTPUT: Values: 283.6 22:02:48 INFO - 2015-11-24 22:02:48,691 DEBUG : BROWSER_OUTPUT: 22:02:48 INFO - 2015-11-24 22:02:48,692 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5133.33 Median:5133.33 stddev:0.00 (0.0%) 22:02:48 INFO - 2015-11-24 22:02:48,692 DEBUG : BROWSER_OUTPUT: Values: 5133.3 22:02:48 INFO - 2015-11-24 22:02:48,692 DEBUG : BROWSER_OUTPUT: 22:02:48 INFO - 2015-11-24 22:02:48,692 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1098.90 Median:1098.90 stddev:0.00 (0.0%) 22:02:48 INFO - 2015-11-24 22:02:48,692 DEBUG : BROWSER_OUTPUT: Values: 1098.9 22:02:48 INFO - 2015-11-24 22:02:48,693 DEBUG : BROWSER_OUTPUT: 22:02:48 INFO - 2015-11-24 22:02:48,693 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:780.03 Median:780.03 stddev:0.00 (0.0%) 22:02:48 INFO - 2015-11-24 22:02:48,693 DEBUG : BROWSER_OUTPUT: Values: 780.0 22:02:48 INFO - 2015-11-24 22:02:48,693 DEBUG : BROWSER_OUTPUT: 22:02:48 INFO - 2015-11-24 22:02:48,694 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12419.75 Median:12419.75 stddev:0.00 (0.0%) 22:02:48 INFO - 2015-11-24 22:02:48,694 DEBUG : BROWSER_OUTPUT: Values: 12419.8 22:02:48 INFO - 2015-11-24 22:02:48,695 DEBUG : BROWSER_OUTPUT: 22:02:48 INFO - 2015-11-24 22:02:48,695 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25100.00 Median:25100.00 stddev:0.00 (0.0%) 22:02:48 INFO - 2015-11-24 22:02:48,695 DEBUG : BROWSER_OUTPUT: Values: 25100.0 22:02:48 INFO - 2015-11-24 22:02:48,695 DEBUG : BROWSER_OUTPUT: 22:02:48 INFO - 2015-11-24 22:02:48,696 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:666.45 Median:666.45 stddev:0.00 (0.0%) 22:02:48 INFO - 2015-11-24 22:02:48,696 DEBUG : BROWSER_OUTPUT: Values: 666.5 22:02:48 INFO - 2015-11-24 22:02:48,696 DEBUG : BROWSER_OUTPUT: 22:02:48 INFO - 2015-11-24 22:02:48,696 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 22:02:48 INFO - 2015-11-24 22:02:48,696 DEBUG : BROWSER_OUTPUT: Values: 4830.9 22:02:48 INFO - 2015-11-24 22:02:48,696 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:02:48 INFO - 2015-11-24 22:02:48,696 DEBUG : BROWSER_OUTPUT: 22:02:49 INFO - 2015-11-24 22:02:49,530 INFO : Browser exited with error code: 0 22:02:49 INFO - 2015-11-24 22:02:49,542 INFO : Completed test v8_7 (00:00:39) 22:02:49 INFO - 2015-11-24 22:02:49,649 INFO : Completed test suite (00:20:10) 22:02:49 INFO - 2015-11-24 22:02:49,649 DEBUG : Working with test: dromaeo_css 22:02:49 INFO - 2015-11-24 22:02:49,649 DEBUG : Generating results file: dromaeo_css 22:02:49 INFO - 2015-11-24 22:02:49,650 DEBUG : Working with test: kraken 22:02:49 INFO - 2015-11-24 22:02:49,650 DEBUG : Generating results file: kraken 22:02:49 INFO - 2015-11-24 22:02:49,651 INFO : javascript benchmark 22:02:49 INFO - 2015-11-24 22:02:49,651 DEBUG : Working with test: v8_7 22:02:49 INFO - 2015-11-24 22:02:49,651 DEBUG : Generating results file: v8_7 22:02:49 INFO - 2015-11-24 22:02:49,651 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:02:49 INFO - 2015-11-24 22:02:49,741 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 22:02:49 INFO - 2015-11-24 22:02:49,741 DEBUG : process_Request line: dromaeo_css 6988.35 graph.html#tests=[[72,1,35]] 22:02:49 INFO - 2015-11-24 22:02:49,741 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:02:49 INFO - 2015-11-24 22:02:49,774 DEBUG : process_Request line: kraken 1494.60 graph.html#tests=[[232,1,35]] 22:02:49 INFO - 2015-11-24 22:02:49,775 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:02:49 INFO - 2015-11-24 22:02:49,806 DEBUG : process_Request line: v8_7 20824.08 graph.html#tests=[[230,1,35]] 22:02:49 INFO - 2015-11-24 22:02:49,811 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3238.31995280991, 3289.276359200978, 3339.74951878609, 3340.1148695595657, 3365.4951757236418, 5286.550643195333, 5384.9499347627225, 5486.533644640205, 5488.7530061147245, 5546.140348246024, 5414.133738601825, 5447.536959358459, 5519.254900588419, 5562.02664533706, 5585.804496842611, 4962.826301079463, 4968.776405061772, 4989.126902791997, 5020.945601423706, 5030.496950304977, 5046.243063540462, 5172.896542069157, 5176.5341119299255, 5195.766190521435, 5207.036698210824, 4942.703437793726, 5015.473375295578, 5049.64652474327, 5125.359330083739, 5144.845654630367, 5132.3327967364, 5134.306868572732, 5136.768845401966, 5164.767585458663, 5181.689098654055, 5071.1632580624155, 5106.412762532319, 5118.462561431071, 5124.461931497176, 5168.966206758663, 5309.973450132725, 5312.335958005249, 5399.676019438846, 5441.646292990943, 5512.448755124456, 6008.969955150196, 6023.488003519706, 6028.5478589106, 6047.969760151215, 6076.513878889678, 6053.788117415869, 6066.211392518944, 6090.786822461193, 6098.298695650013, 6112.480439162677, 5433.972830135864, 5532.806351777709, 5559.6664200148125, 5600.0759874620235, 5665.1502274658305, 6186.4741496972365, 6203.875922481525, 6214.25428948529, 6214.316425193225, 6222.657753823583, 5883.499902508249, 6026.457618814327, 6028.457438830438, 6035.064564992434, 6037.18498002764, 5795.217645617788, 5826.970865145647, 5907.911381329283, 5917.408259174048, 6028.216331876915, 5987.730552125165, 5990.400959904062, 5990.6705131218205, 6002.639841609517, 6064.66644334557, 5863.941360586425, 5989.670568118708, 6007.06890431975, 6009.759609615567, 6018.668973206428, 4541.477730061019, 4546.068056048568, 4549.363089167519, 4604.884877878013, 4663.556962088596, 4077.0, 4083.0, 4095.4266402703647, 4132.152908653718, 4139.917201656011, 5484.451554844483, 5780.71096445176, 5784.4504772046585, 5786.913196302059, 5792.275965504312, 5896.911546326723, 5983.19226904371, 5990.341062483123, 6048.909266361008, 6079.270487541523, 5730.226419433323, 5731.942680573225, 5744.626599271034, 5745.3105627324985, 5763.2507773989955, 5029.27075574047, 5253.658512196772, 5254.7635356409055, 5268.9736551317, 5270.130428479335, 6118.357572454917, 6168.1056246844855, 6178.6601736904, 6180.351063138306, 6196.752129914933, 6092.025275955826, 6098.0, 6102.328743838086, 6107.083937409424, 6127.509799216141, 5951.970240148945, 5955.344912059583, 6313.0, 6313.3686631336495, 6323.873522529616, 5760.567957403296, 5854.882902342015, 5907.763689452546, 5908.763649453974, 5949.702514874152, 6001.849953751192, 6003.609765365237, 6010.849728756816, 6019.608725432833, 6070.6357618543025, 5745.109508026263, 5832.9708351457975, 5867.823965281048, 5877.324107727666, 5894.6757928314355, 5749.540036796962, 5816.21481100063, 5888.411158884077, 5893.057110862413, 5949.137375080502, 5715.057015592381, 5717.742701578356, 5717.942820571741, 5727.828165155052, 5730.856728581819, 5641.294838145232, 5753.942460575341, 5833.766649333979, 5883.764649413975, 5893.381194974466, 5801.216835727123, 5844.444777746107, 5875.41245875409, 5880.0, 5923.911141332797], "unit": "ms", "name": "dojo.html", "value": 5525.080394970586}, {"lowerIsBetter": false, "replicates": [542.8396632257686, 586.0769288370815, 589.5961266532423, 599.5084031094503, 609.5428428678491, 31903.840480797593, 40294.79852600736, 40364.596354036454, 40370.0, 40371.59628403715, 43382.78308608461, 44071.77964110175, 44094.33858492117, 44253.77873110638, 44312.3353149703, 7678.42411819113, 7788.766337009871, 8675.69635062773, 8844.11558844117, 10230.130438912702, 10399.53202105907, 10454.947725261402, 10492.265541412104, 10512.579496820117, 10522.736931576695, 10478.68563943079, 10594.099501542378, 10599.152067834555, 10602.469876506182, 10602.734931626732, 7911.090224624156, 8070.515769053875, 10642.148628109733, 10839.187060970458, 10852.023317901427, 10357.896421035768, 10508.684739457829, 10509.632162874339, 10519.474026298654, 10522.894771052344, 40738.59261407378, 41000.79499602513, 41042.58957410447, 41091.0, 41101.79449102765, 3244.772865899388, 3315.287213249163, 3330.8667653293837, 3366.73066154707, 3413.2320227948762, 30492.695073049206, 30736.385272294654, 30919.227019324473, 31342.843285783652, 31873.0, 3813.561440434358, 3915.4126880967797, 3926.5680775114715, 4052.675785937118, 4095.877123686294, 259644.0, 259800.0, 259870.0, 260819.0, 261391.0, 58292.417075829544, 58519.0, 58706.0, 58726.412735872946, 58752.41247587555, 61083.694581526805, 62277.37722622806, 63147.368526314145, 63715.68142159352, 64669.67665161644, 14333.0, 14333.283335833166, 14333.56999290023, 14409.78385324221, 14429.278536073365, 11257.268277561932, 11322.773544529064, 11382.715432114263, 11387.658370248906, 11419.31484110956, 20013.199472020955, 20091.0, 20223.696644550342, 20322.796772032092, 20349.898250508653, 7830.530168189927, 8050.19498050202, 8064.6370913307865, 8070.596470176585, 8080.313173380328, 1032.4218437674926, 1040.1626690514058, 1072.292287090532, 1074.8011617850566, 1152.3258893547368, 27581.58627620587, 27784.305392365353, 27979.860100699367, 28003.0, 28098.85950570234, 1068.3269540189785, 1105.2926127278547, 1121.4448847820217, 1151.5336288803048, 1203.4945322964377, 14594.48919287841, 14735.336909838868, 14750.262486875614, 15012.249387530579, 15080.170590617621, 314689.0, 318753.0, 320018.0, 320215.0, 320648.0, 52817.0, 53066.46933530692, 53129.0, 53165.2025219622, 53203.0, 1347.6294019144814, 1360.4490181476517, 1361.918284902909, 1365.378752667534, 1371.8765311122047, 27479.450410992067, 28043.48259301342, 28316.150515483743, 28492.715072849835, 28781.424371512872, 46760.53239467562, 46806.297905530766, 46947.765261173474, 47088.52911470841, 47208.0, 284686.0, 286793.0, 286812.0, 286979.0, 287123.5150593953, 28479.572806407505, 33909.15227119243, 34445.31109377849, 34494.82752586321, 34811.651883481856, 41663.37504937489, 42111.578884211995, 42909.57090429181, 43184.35223471588, 43225.0, 33520.49719254262, 36048.4592731104, 36119.58937982303, 36685.0, 36761.0, 28743.137705868856, 34077.65922340844, 35656.28687426185, 36896.0, 37195.814020930804, 32534.83732581322, 32906.67093329036, 33062.83468582642, 33496.49755253722, 33526.16184595307, 31555.053348399586, 32241.838790805898, 33072.0, 33704.49443258402, 33859.32281354408, 23621.173258935854, 23685.52628947446, 23701.407464813517, 23791.0, 23952.760472395054], "unit": "ms", "name": "ext.html", "value": 18856.326037713105}, {"lowerIsBetter": false, "replicates": [346.14674826552545, 351.15897425665514, 353.1982399952109, 354.67901549098065, 355.64790857051526, 14240.287985600731, 14573.781393279109, 16204.513864584067, 16661.666766664675, 17839.28642854286, 16106.516804495885, 16228.513144605651, 16252.349906003776, 16262.593435164126, 16274.104924229163, 4687.671862969585, 4865.5134486551415, 4870.147724148278, 4870.975645121769, 4872.537108974641, 3801.4107813288847, 3916.8237429315604, 4840.177169881129, 5328.5470734987375, 5493.340799104113, 5372.140457526796, 5437.32033495813, 5442.292501974738, 5469.316335458067, 5474.014677358074, 5355.384130824966, 6059.091136329542, 6063.757449702007, 6063.848403789896, 6066.63600183986, 5375.032494151052, 5378.085725426687, 5386.838394848121, 5401.918971215435, 5403.2705584745945, 17867.910660446745, 18693.719594206097, 19206.135723892457, 20271.08780104884, 20271.89864050685, 2050.805173508529, 2062.6287268291703, 2089.905954232048, 2095.9371218863457, 2107.200596940679, 8926.73219803407, 8971.372003959725, 9200.30997675177, 9285.953570232174, 9332.0, 1768.5843826700716, 1796.4341232511847, 1850.1489314915154, 1869.4858913798678, 1948.8635795494322, 58567.0, 60655.39344606541, 61002.0, 61257.0, 61755.0, 10158.746031349276, 10179.949100254451, 10252.38485690861, 10258.846117308247, 10309.84535231972, 13226.867731322756, 13367.7968982792, 13377.264250466123, 13395.397207125703, 13418.127821691558, 7090.255523170096, 7231.674574644048, 7254.948032535252, 7277.708891644275, 7357.926420735724, 7027.332403421667, 7094.858102838018, 7098.396636285974, 7129.215786263507, 7147.392471639865, 4193.51774545925, 4259.212045771542, 4279.9786001070415, 4431.645468362523, 4439.467263928284, 1720.9225584848712, 1769.3188122572644, 1771.9822801772075, 1804.143032059761, 1833.9449816505526, 928.9345160968776, 937.8968313485511, 946.9005754395827, 1004.9346792458467, 1068.3589846092282, 16476.340946362252, 16929.830701692827, 17061.146942652817, 17251.65496690059, 17310.567235819206, 937.3579098317674, 952.79991201847, 953.6471505542994, 956.5695437053215, 970.7912798748162, 9669.323147379757, 9804.803903922024, 9853.113219810251, 9873.654422095193, 9873.901260987443, 506248.0, 510866.0, 513554.0, 521105.0, 527083.0, 75952.24047759562, 78744.60627696825, 79691.0, 79783.0, 80421.19578804255, 1037.6575730009079, 1050.138886113395, 1055.3456856748865, 1058.6982709927634, 1077.4666540062562, 15007.624809379853, 15107.622309442351, 15151.469698560497, 15327.233638318263, 15499.612509687347, 34540.4094692894, 35021.64978350184, 35170.47244291286, 35362.823185883906, 36056.0, 45567.31649025201, 45678.0, 45779.0, 46156.307655384524, 49044.50955490399, 9208.0, 9434.773479447627, 9528.571214295513, 9551.188149007412, 9554.904450955402, 11274.323540587591, 11287.887121128684, 16882.24029918632, 17209.913950430167, 17218.913905430392, 10805.135589152691, 12056.156069075081, 12286.262824230575, 13958.651033724238, 14160.716785664436, 13342.399592018188, 13611.0, 13640.590782276548, 13861.898951667043, 14067.366968486238, 11816.527338906348, 13333.200017997835, 14186.92906535502, 14349.713005740035, 14783.186924719244, 8942.73171804847, 8951.41815781972, 8989.056149104512, 9058.90941090607, 9062.773430664287, 9375.10936461035, 9493.193078588121, 9499.905000949902, 9589.952050239704, 10588.788224235319], "unit": "ms", "name": "jquery.html", "value": 8568.618369740652}, {"lowerIsBetter": false, "replicates": [1333.9399727012283, 1450.7316146512894, 1504.804375431194, 1618.9190540472973, 1774.1395423219735, 6685.398314151726, 7541.132769731483, 7884.093329267136, 7905.881411778828, 7924.6433910474025, 7702.114256860463, 7844.3332316753, 7849.411294152947, 7856.332211761993, 7867.2132786721295, 2251.0995601759305, 2279.680844681746, 2284.120613563773, 2285.074544809355, 2286.7713228677167, 2290.1640901071073, 2304.44693273614, 2386.4988352446007, 2395.018042602533, 2398.784109430151, 2256.8758718270487, 2263.7736226377397, 2290.656401539774, 2302.1366987379733, 2303.67748515208, 2294.162630639813, 2317.5133222023396, 2320.953580928372, 2321.083172147001, 2322.280093171114, 2299.7470278269375, 2322.941926451835, 2323.186884590397, 2324.2097686786574, 2326.1160758911674, 6554.93445065548, 6704.865902681968, 7820.960895195488, 7864.882026769603, 7889.368850491946, 4559.726416415026, 4614.077184563067, 4646.9767651161865, 4656.231721765905, 4656.697314674535, 3120.3915236528887, 3192.2498212919945, 3199.808011519293, 3209.935801283961, 3320.186554294189, 1031.2265800649513, 1035.9847349597362, 1049.5906596427324, 1100.7963526747496, 1105.6351404036648, 10736.624218152327, 10828.72928176794, 10855.185861060452, 10866.945665271624, 10876.401797901033, 4272.786360682016, 4300.61294483498, 4302.7633480158265, 4337.479502459725, 4340.956590434118, 8711.912880871238, 8717.171868672465, 8721.08428614999, 8723.0, 8733.56332183401, 4744.264638980894, 4758.286257061399, 4785.042991401733, 4786.497417771084, 4797.616190704735, 4362.716423432466, 4386.583274588973, 4387.0, 4390.07808360248, 4408.9559104408545, 3474.4961980512685, 3525.806080665588, 3532.4877892705413, 3552.5381700379303, 3553.1650062235353, 1091.6724982505216, 1095.315427857589, 1097.160672085855, 1101.2786624760795, 1112.1825458288151, 1300.3173333999725, 1301.7852054411105, 1303.7066660001612, 1313.507434711983, 1340.7184491256946, 7322.377597904131, 7396.223396543393, 7397.926020739724, 7400.555966642019, 7438.516496427823, 477.03638649926955, 477.18877907557294, 481.35017726070225, 481.4415278277182, 488.7776061891831, 5325.227841962894, 5406.59450541211, 5757.165211044375, 5804.332501762267, 5808.157817116495, 9078.182963533314, 9117.088291170963, 9286.489243091695, 9322.300827437834, 9325.720228393158, 9038.412503187403, 9110.36227464071, 9175.816483670289, 9183.40307879999, 9189.0, 7210.60341681202, 7304.196538380774, 7405.851882962311, 7407.777766667009, 7424.071991001125, 7125.3230943060325, 7250.673719682733, 7271.236520165518, 7280.199178090512, 7281.963590182015, 7887.526748395115, 8305.501669899826, 8435.578221089043, 8471.364647651328, 8492.108328625653, 8333.708320208763, 8386.0, 8495.787605309917, 8660.523671198145, 8667.783305417415, 7121.4302855770375, 7198.604076775828, 7207.747728829466, 7208.0, 7212.3148300911325, 7568.772936811904, 7587.430942679211, 7618.847623047619, 7621.618919054136, 7667.0416197975255, 7219.819504512429, 7301.598412087386, 7317.451191160578, 7380.815479613053, 7429.07136607924, 6999.30006999296, 7003.54476958996, 7029.824254393681, 7123.145222573117, 7193.172785129777, 6691.263960964193, 6691.832704182434, 6701.966490167518, 6702.028205910213, 6728.394444500019, 6267.059941008885, 6412.39082287182, 6550.148480697479, 6568.441682456853, 6572.4084832363405, 6684.0, 6717.697703603252, 6733.764318248838, 6736.494762892705, 6739.898901516579], "unit": "ms", "name": "mootools.html", "value": 4411.106314236929}, {"lowerIsBetter": false, "replicates": [329.46461999251216, 332.8968019913827, 333.94823802310634, 334.45316906857283, 340.7904138954544, 5889.146073819305, 5889.263842019747, 5958.493528050116, 6021.367756385582, 6025.608335458198, 5768.192453056565, 5901.232839730829, 5906.763729450828, 5935.28776546815, 5955.791547295846, 4590.265557510799, 4590.862274131781, 4591.816327346902, 4596.7931443085145, 4604.217283061887, 4408.71343362682, 4489.371487991683, 4493.438320209973, 4499.235130027903, 4603.677742558022, 4474.127545128701, 4499.527549607277, 4551.5676010778925, 4568.931466027996, 4586.793594288249, 4559.635229181674, 4568.314752787109, 4568.908621827562, 4575.885602859922, 4581.816727330903, 4032.112935154291, 4044.312466880637, 4049.6962727795535, 4052.4326594276827, 4053.047533829548, 5595.720213989284, 5688.5733569982, 5699.202111704365, 5739.741711622945, 5744.655320680772, 5225.1117310057, 5226.73866306687, 5230.0, 5235.6073294502685, 5237.895242095136, 6009.639421634716, 6303.527235457359, 6309.968450157719, 6315.8736825263695, 6317.368263173692, 4228.682848786353, 4238.87283381499, 4375.803088861009, 4377.34339849022, 4390.824367025315, 6457.903131453032, 6510.9023364649565, 6522.119513865662, 6527.15147030883, 6545.083688283644, 4460.130274596422, 4462.41988541494, 4463.330500424962, 4465.955340446619, 4466.263066593976, 4446.244138496463, 4518.480374756879, 4525.298578720303, 4530.546945305443, 4543.4093567836635, 5237.869053273623, 5246.527812496894, 5247.449017853147, 5247.816326428633, 5249.685018898878, 6347.682615869188, 6356.269029061625, 6357.300696923335, 6366.395192456709, 6368.29948705642, 1556.6419723463666, 1596.7764512968195, 1598.4325564424694, 1598.8001499812524, 1605.4541455905048, 1017.2218253036433, 1040.9011143941314, 1041.2815157541272, 1041.338749893823, 1043.9791204175874, 6155.138280640714, 6155.676529546169, 6162.352952939877, 6162.568620196633, 6163.96918015416, 6529.412352888168, 6531.216254049545, 6540.117084193668, 6542.34576542342, 6553.4101930826455, 6015.518758499309, 6142.416470435302, 6158.1686472326555, 6158.414950579688, 6164.938350616526, 3538.221591249921, 3552.2007548301426, 3556.110972256936, 3556.751027428107, 3560.2345495718546, 6752.054712340277, 6763.797086087426, 6768.0, 6769.966150169218, 6773.9322606774285, 6564.0, 6695.129633147757, 6697.665116744143, 6701.899471507932, 6705.430038446689, 6724.260331363546, 6740.393364597112, 6748.932510674928, 6751.831204219836, 6754.493412993947, 6341.953577659635, 6484.837879053061, 6540.117084193763, 6543.345665433419, 6547.934520654732, 6398.968005159944, 6410.711517981609, 6412.935870641234, 6516.804495865132, 6556.704948277244, 6824.761133360308, 6825.453963682993, 6837.1111755471475, 6845.623490708059, 6846.691898864464, 4417.536158703419, 4479.865604031885, 4500.302453119772, 4507.8196872124745, 4509.729416235036, 6573.967130164318, 6584.144061272004, 6588.670566471753, 6590.373914478117, 6593.967030164818, 6422.903656445251, 6430.1319321892815, 6432.581882177644, 6432.742690292336, 6434.903476447764, 6258.655773932477, 6412.807615771534, 6414.454771344302, 6414.61512309263, 6421.1010458536775, 6277.686115694106, 6281.497480201481, 6286.748530058747, 6290.874182516415, 6291.4023167800815, 6429.485641148791, 6439.742410303723, 6457.418832305115, 6469.385408386196, 6476.935230647633, 6201.534884883562, 6286.339934306832, 6300.432961033529, 6304.338044505261, 6315.179026726496], "unit": "ms", "name": "prototype.html", "value": 4776.494536684456}, {"lowerIsBetter": false, "replicates": [708.6315116139607, 726.6148941061236, 746.9327760501553, 767.9808004799879, 791.4143533784998, 11848.407579621016, 12171.33057681827, 12185.329806860631, 12202.0, 12207.633770986862, 11362.261453005549, 11428.828567571492, 11432.71418214545, 11441.370724610144, 11450.08399328054, 665.1419668627465, 678.6878036103399, 806.5967016491754, 824.983500329993, 826.2316046077145, 806.9072056713494, 809.4212637963855, 812.0539571399343, 817.358373676664, 819.7909533069059, 714.653393104344, 723.1105739939877, 869.6260607938584, 877.4165180155189, 878.0341624213378, 843.9367047471434, 853.2960307746072, 856.2336708645789, 861.2248975921658, 862.6420035685214, 637.8819918315127, 641.6310621392671, 642.2421542579754, 643.4177069751883, 643.9420452159281, 11223.32660040392, 11326.32042077478, 11332.263402878869, 11347.716307092307, 11372.37451940143, 12016.218945768585, 12749.023901295313, 12831.690295746761, 12913.160644558073, 12941.0, 10588.470576471223, 10669.970247868083, 10782.46087695612, 10889.346639201594, 10959.232853700245, 3589.6410358964154, 3620.8008559529217, 3719.4420836874415, 3762.1347090169143, 3764.9435258471144, 47526.7623661883, 49106.50893491055, 49153.50846491525, 49167.50832491629, 49312.75343623259, 9768.316217864824, 9844.556999925233, 9908.20734341251, 9924.255680823968, 10276.075153236245, 19118.33085841989, 19201.903990479957, 19222.327218547285, 19224.231030758892, 19236.903815480833, 7707.768766937001, 7722.613869306512, 7898.328642065374, 7956.602169891482, 8112.675492980215, 4179.770112643834, 4233.724807887477, 4268.381084742695, 4317.41714868495, 4384.868453946387, 3158.921026974344, 3162.7153556180056, 3183.7771356004855, 3325.0523600773663, 3410.5736782902136, 597.0238659791186, 599.9940000599938, 613.5796979069352, 619.9473044791154, 624.2415465209792, 624.7594676049663, 643.8776632439822, 664.6311297229987, 672.7477196051481, 680.3332733920786, 7962.442629016029, 8262.8347433051, 8329.833403331899, 8529.872051919227, 8579.14208579137, 606.7178761875749, 641.1842118282718, 668.1547841979899, 679.512933065498, 698.8078278473461, 3586.408242639935, 3869.3228684980577, 3876.399158130438, 3909.4526766252748, 3942.842286308573, 58123.4187658118, 58536.707316463144, 58674.70662646659, 58718.41281587129, 58844.705776470844, 50834.0, 51747.482525174266, 52390.47609523932, 52485.47514524882, 52600.21099683508, 44401.77799110984, 44830.55169448329, 45458.31812522815, 45880.541194588295, 45938.0, 7811.804704882424, 8120.187981201832, 8131.9593402032615, 8229.177082291722, 8399.19417325278, 35732.285354293286, 35858.28283434369, 36066.63933360633, 38460.03849903775, 38915.6108438912, 44528.0, 44795.0, 44868.32697509605, 45748.771256143504, 46494.53505464902, 10371.481425928523, 10612.734681633021, 10808.243422960317, 10956.0, 11281.153913456688, 11259.437028148724, 11447.313161210353, 11488.425578721197, 11825.467853946766, 12366.258024518558, 10262.948685256526, 10369.325993810378, 10399.584016639552, 10449.68650940473, 10565.418901960466, 10132.44271565071, 10265.641627465311, 10340.844887326546, 10350.378977261389, 10609.575616975235, 7093.609851458219, 10138.341007834468, 10379.429131397847, 10466.790664186521, 10472.895271047191, 9850.310478266452, 9978.650747223812, 10043.598256069969, 10072.345297555636, 10223.233257505568, 7867.488613240121, 9050.786970625333, 9353.0, 9417.952910235404, 9527.26153296156], "unit": "ms", "name": "yui.html", "value": 6192.190056060541}], "name": "dromaeo_css"}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 104.0, 109.0, 110.0, 109.0, 99.0, 109.0, 108.0, 101.0], "unit": "ms", "name": "ai-astar", "value": 106.7}, {"lowerIsBetter": true, "replicates": [151.0, 124.0, 152.0, 164.0, 125.0, 154.0, 150.0, 126.0, 167.0, 132.0], "unit": "ms", "name": "audio-beat-detection", "value": 144.5}, {"lowerIsBetter": true, "replicates": [160.0, 160.0, 162.0, 159.0, 163.0, 161.0, 162.0, 161.0, 161.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 161.0}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 89.0, 93.0, 71.0, 73.0, 72.0, 104.0, 93.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 80.9}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 130.0, 92.0, 94.0, 94.0, 136.0, 92.0, 93.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 105.3}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 109.0, 110.0, 109.0, 110.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.6}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.8}, {"lowerIsBetter": true, "replicates": [95.0, 94.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [56.0, 56.0, 57.0, 57.0, 57.0, 58.0, 56.0, 56.0, 56.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 56.5}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 69.0, 70.0, 70.0, 71.0, 69.0, 70.0, 70.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.7}, {"lowerIsBetter": true, "replicates": [83.0, 86.0, 85.0, 89.0, 86.0, 84.0, 84.0, 86.0, 86.0, 87.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.6}, {"lowerIsBetter": true, "replicates": [120.0, 117.0, 121.0, 121.0, 127.0, 133.0, 135.0, 116.0, 119.0, 120.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 122.9}, {"lowerIsBetter": true, "replicates": [141.0, 155.0, 148.0, 148.0, 146.0, 137.0, 148.0, 150.0, 138.0, 136.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 144.7}, {"lowerIsBetter": true, "replicates": [65.0, 66.0, 66.0, 66.0, 67.0, 65.0, 67.0, 67.0, 67.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.1}], "name": "kraken"}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.53550736633008], "unit": "score", "name": "Richards", "value": 213.25938200000004}, {"lowerIsBetter": false, "replicates": [195.96315892612188], "unit": "score", "name": "DeltaBlue", "value": 337.400154}, {"lowerIsBetter": false, "replicates": [283.60748723766307], "unit": "score", "name": "Encrypt", "value": 938.5542060000004}, {"lowerIsBetter": false, "replicates": [5133.333333333333], "unit": "score", "name": "Decrypt", "value": 51.85344155844158}, {"lowerIsBetter": false, "replicates": [1098.901098901099], "unit": "score", "name": "RayTrace", "value": 673.3899900000001}, {"lowerIsBetter": false, "replicates": [780.0312012480499], "unit": "score", "name": "Earley", "value": 854.4055660000001}, {"lowerIsBetter": false, "replicates": [12419.753086419752], "unit": "score", "name": "Boyer", "value": 53.66153379721673}, {"lowerIsBetter": false, "replicates": [25100.0], "unit": "score", "name": "RegExp", "value": 36.294223107569735}, {"lowerIsBetter": false, "replicates": [666.4544875875239], "unit": "score", "name": "Splay", "value": 122.2754164278892}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7"}]} 22:02:49 INFO - RETURN: dromaeo_css: 6988.35 22:02:49 INFO - RETURN: kraken: 1494.60 22:02:49 INFO - RETURN: v8_7: 20824.08 22:02:49 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "20824.08"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1494.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6988.35"}}} 22:02:49 INFO - Return code: 0 22:02:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 22:02:49 INFO - # TBPL SUCCESS # 22:02:49 INFO - Running post-action listener: _resource_record_post_action 22:02:49 INFO - Running post-run listener: _resource_record_post_run 22:02:50 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 4096; Write bytes: 500105216; Read time: 8; Write time: 543712 22:02:50 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 235696128; Read time: 0; Write time: 344232 22:02:51 INFO - run-tests - Wall time: 1211s; CPU: 13.0%; Read bytes: 4096; Write bytes: 264409088; Read time: 8; Write time: 199480 22:02:51 INFO - Running post-run listener: _upload_blobber_files 22:02:51 INFO - Blob upload gear active. 22:02:51 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 22:02:51 INFO - Copying logs to upload dir... 22:02:51 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1271.646985 ========= master_lag: 48.87 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 0 secs) (at 2015-11-24 22:03:39.757855) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 22:03:39.761474) ========= 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=d6a7e047ef025f707024e163000001c3-1448429650.365608-351810649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010087 build_url: 'https://queue.taskcluster.net/v1/task/cn4xKcegSVW8EyvJlBayaQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.22 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 22:03:39.987620) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 22:03:39.987974) ========= 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=d6a7e047ef025f707024e163000001c3-1448429650.365608-351810649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004580 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 22:03:40.016481) ========= ========= Started reboot skipped (results: 3, elapsed: 16 secs) (at 2015-11-24 22:03:40.016786) ========= ========= Finished reboot skipped (results: 3, elapsed: 16 secs) (at 2015-11-24 22:03:56.230823) ========= ========= Total master_lag: 49.28 =========