builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-059 starttime: 1448545555.57 results: success (0) buildid: 20151126043009 builduid: c2d4a322bd634fa28133e6beab3c65c4 revision: c321d84038519dcf1670d59fd2c5c00ad8a85a55 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:55.574641) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:55.575126) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:55.575458) ========= 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=5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.003019 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:55.615435) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:55.615765) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:55.738782) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:55.739036) ========= 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=5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.033109 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:55.792168) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:55.792439) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:55.792763) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:55.793032) ========= 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=5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-26 05:45:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 227M=0s 2015-11-26 05:45:56 (227 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.221438 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:56.028762) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:56.029116) ========= 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=5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.060140 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:56.105983) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:56.106275) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c321d84038519dcf1670d59fd2c5c00ad8a85a55 --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 c321d84038519dcf1670d59fd2c5c00ad8a85a55 --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=5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-26 05:45:56,163 truncating revision to first 12 chars 2015-11-26 05:45:56,163 Setting DEBUG logging. 2015-11-26 05:45:56,164 attempt 1/10 2015-11-26 05:45:56,164 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c321d8403851?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-26 05:45:56,406 unpacking tar archive at: mozilla-central-c321d8403851/testing/mozharness/ program finished with exit code 0 elapsedTime=0.558584 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:56.676885) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:56.677160) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:56.686708) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:56.686974) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:56.687432) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2015-11-26 05:45:56.687881) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:45:56 INFO - MultiFileLogger online at 20151126 05:45:56 in /builds/slave/test-pgo 05:45:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:45:56 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:45:56 INFO - {'append_to_log': False, 05:45:56 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:45:56 INFO - 'blob_upload_branch': 'Firefox', 05:45:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:45:56 INFO - 'branch': 'Firefox', 05:45:56 INFO - 'buildbot_json_path': 'buildprops.json', 05:45:56 INFO - 'config_files': ('talos/linux_config.py',), 05:45:56 INFO - 'default_actions': ('clobber', 05:45:56 INFO - 'read-buildbot-config', 05:45:56 INFO - 'download-and-extract', 05:45:56 INFO - 'populate-webroot', 05:45:56 INFO - 'create-virtualenv', 05:45:56 INFO - 'install', 05:45:56 INFO - 'run-tests'), 05:45:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:45:56 INFO - 'download_minidump_stackwalk': True, 05:45:56 INFO - 'download_symbols': 'ondemand', 05:45:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:45:56 INFO - 'tooltool.py': '/tools/tooltool.py', 05:45:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:45:56 INFO - '/tools/misc-python/virtualenv.py')}, 05:45:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:45:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:45:56 INFO - 'installer_path': 'installer.exe', 05:45:56 INFO - 'log_level': 'info', 05:45:56 INFO - 'log_name': 'talos', 05:45:56 INFO - 'log_to_console': True, 05:45:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:45:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:45:56 INFO - 'opt_config_files': (), 05:45:56 INFO - 'pip_index': False, 05:45:56 INFO - 'sps_profile': False, 05:45:56 INFO - 'sps_profile_interval': 0, 05:45:56 INFO - 'suite': 'dromaeojs-e10s', 05:45:56 INFO - 'system_bits': '64', 05:45:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:45:56 INFO - 'title': 'talos-linux64-ix-059', 05:45:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:45:56 INFO - 'use_talos_json': True, 05:45:56 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:45:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:45:56 INFO - 'work_dir': 'build'} 05:45:56 INFO - ##### 05:45:56 INFO - ##### Running clobber step. 05:45:56 INFO - ##### 05:45:56 INFO - Running pre-action listener: _resource_record_pre_action 05:45:56 INFO - Running main action method: clobber 05:45:56 INFO - rmtree: /builds/slave/test-pgo/build 05:45:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:45:57 INFO - Running post-action listener: _resource_record_post_action 05:45:57 INFO - ##### 05:45:57 INFO - ##### Running read-buildbot-config step. 05:45:57 INFO - ##### 05:45:57 INFO - Running pre-action listener: _resource_record_pre_action 05:45:57 INFO - Running main action method: read_buildbot_config 05:45:57 INFO - Using buildbot properties: 05:45:57 INFO - { 05:45:57 INFO - "properties": { 05:45:57 INFO - "buildnumber": 252, 05:45:57 INFO - "product": "firefox", 05:45:57 INFO - "script_repo_revision": "production", 05:45:57 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:45:57 INFO - "repository": "", 05:45:57 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 05:45:57 INFO - "buildid": "20151126043009", 05:45:57 INFO - "slavename": "talos-linux64-ix-059", 05:45:57 INFO - "pgo_build": "True", 05:45:57 INFO - "basedir": "/builds/slave/test-pgo", 05:45:57 INFO - "project": "", 05:45:57 INFO - "platform": "ubuntu64_hw", 05:45:57 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:45:57 INFO - "slavebuilddir": "test", 05:45:57 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:45:57 INFO - "branch": "mozilla-central", 05:45:57 INFO - "repo_path": "mozilla-central", 05:45:57 INFO - "stage_platform": "linux64-pgo", 05:45:57 INFO - "builduid": "c2d4a322bd634fa28133e6beab3c65c4", 05:45:57 INFO - "revision": "c321d84038519dcf1670d59fd2c5c00ad8a85a55" 05:45:57 INFO - }, 05:45:57 INFO - "sourcestamp": { 05:45:57 INFO - "repository": "", 05:45:57 INFO - "hasPatch": false, 05:45:57 INFO - "project": "", 05:45:57 INFO - "branch": "mozilla-central-linux64-pgo-talos", 05:45:57 INFO - "changes": [ 05:45:57 INFO - { 05:45:57 INFO - "category": null, 05:45:57 INFO - "files": [ 05:45:57 INFO - { 05:45:57 INFO - "url": null, 05:45:57 INFO - "name": "https://queue.taskcluster.net/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 05:45:57 INFO - } 05:45:57 INFO - ], 05:45:57 INFO - "repository": "", 05:45:57 INFO - "rev": "c321d84038519dcf1670d59fd2c5c00ad8a85a55", 05:45:57 INFO - "who": "sendchange", 05:45:57 INFO - "when": 1448545524, 05:45:57 INFO - "number": 6710825, 05:45:57 INFO - "comments": "", 05:45:57 INFO - "project": "", 05:45:57 INFO - "at": "Thu 26 Nov 2015 05:45:24", 05:45:57 INFO - "branch": "mozilla-central-linux64-pgo-talos", 05:45:57 INFO - "revlink": "", 05:45:57 INFO - "properties": [ 05:45:57 INFO - [ 05:45:57 INFO - "buildid", 05:45:57 INFO - "20151126043009", 05:45:57 INFO - "Change" 05:45:57 INFO - ], 05:45:57 INFO - [ 05:45:57 INFO - "builduid", 05:45:57 INFO - "c2d4a322bd634fa28133e6beab3c65c4", 05:45:57 INFO - "Change" 05:45:57 INFO - ], 05:45:57 INFO - [ 05:45:57 INFO - "pgo_build", 05:45:57 INFO - "True", 05:45:57 INFO - "Change" 05:45:57 INFO - ] 05:45:57 INFO - ], 05:45:57 INFO - "revision": "c321d84038519dcf1670d59fd2c5c00ad8a85a55" 05:45:57 INFO - } 05:45:57 INFO - ], 05:45:57 INFO - "revision": "c321d84038519dcf1670d59fd2c5c00ad8a85a55" 05:45:57 INFO - } 05:45:57 INFO - } 05:45:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 05:45:57 INFO - Running post-action listener: _resource_record_post_action 05:45:57 INFO - ##### 05:45:57 INFO - ##### Running download-and-extract step. 05:45:57 INFO - ##### 05:45:57 INFO - Running pre-action listener: _resource_record_pre_action 05:45:57 INFO - Running main action method: download_and_extract 05:45:57 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:45:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:57 INFO - https://queue.taskcluster.net/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:45:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/test_packages.json 05:45:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/test_packages.json 05:45:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:45:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:46:02 INFO - Downloaded 1302 bytes. 05:46:02 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:46:02 INFO - Using the following test package requirements: 05:46:02 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:46:02 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:02 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:46:02 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:02 INFO - u'jsshell-linux-x86_64.zip'], 05:46:02 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:02 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:46:02 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:46:02 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:02 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:46:02 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:02 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:46:02 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:02 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:46:02 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:46:02 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:02 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:46:02 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:02 INFO - https://queue.taskcluster.net/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:46:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:46:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:46:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/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 05:46:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/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 05:46:08 INFO - Downloaded 22754434 bytes. 05:46:08 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 05:46:08 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:46:08 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 05:46:09 INFO - Return code: 0 05:46:09 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 05:46:09 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')]} 05:46:09 INFO - https://queue.taskcluster.net/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:46:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:46:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:46:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/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 05:46:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/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 05:46:10 INFO - Downloaded 22754434 bytes. 05:46:10 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 05:46:10 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:46:10 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 05:46:12 INFO - Return code: 0 05:46:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:12 INFO - https://queue.taskcluster.net/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:46:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:46:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:46:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/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 05:46:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/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 05:46:14 INFO - Downloaded 11131717 bytes. 05:46:14 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 05:46:14 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:46:14 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 05:46:14 INFO - Return code: 0 05:46:14 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')]} 05:46:14 INFO - https://queue.taskcluster.net/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:46:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:46:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:46:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/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 05:46:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/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 05:46:16 INFO - Downloaded 58391848 bytes. 05:46:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:46:16 INFO - mkdir: /builds/slave/test-pgo/properties 05:46:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:46:16 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:46:16 INFO - Contents: 05:46:16 INFO - build_url:https://queue.taskcluster.net/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:46:16 INFO - Running post-action listener: _resource_record_post_action 05:46:16 INFO - Running post-action listener: set_extra_try_arguments 05:46:16 INFO - ##### 05:46:16 INFO - ##### Running populate-webroot step. 05:46:16 INFO - ##### 05:46:16 INFO - Running pre-action listener: _resource_record_pre_action 05:46:16 INFO - Running main action method: populate_webroot 05:46:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:46:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:46:16 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 05:46:16 INFO - '../fennec_ids.txt'], 05:46:16 INFO - 'tests': ['tcheck2']}, 05:46:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:46:16 INFO - '--tppagecycles', 05:46:16 INFO - '7'], 05:46:16 INFO - 'tests': ['tsvgm']}}, 05:46:16 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:46:16 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:46:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:46:16 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:46:16 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:46:16 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:46:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:46:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:16 INFO - 'talos_options': ['--e10s'], 05:46:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:46:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:16 INFO - 'tests': ['damp', 'tps']}, 05:46:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:16 INFO - 'talos_options': ['--e10s'], 05:46:16 INFO - 'tests': ['damp', 'tps']}, 05:46:16 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:46:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:46:16 INFO - 'other': {'tests': ['a11yr', 05:46:16 INFO - 'ts_paint', 05:46:16 INFO - 'tpaint', 05:46:16 INFO - 'sessionrestore', 05:46:16 INFO - 'sessionrestore_no_auto_restore']}, 05:46:16 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:46:16 INFO - 'tests': ['a11yr', 05:46:16 INFO - 'ts_paint', 05:46:16 INFO - 'tpaint', 05:46:16 INFO - 'sessionrestore', 05:46:16 INFO - 'sessionrestore_no_auto_restore']}, 05:46:16 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:46:16 INFO - 'tests': ['a11yr', 05:46:16 INFO - 'ts_paint', 05:46:16 INFO - 'tpaint', 05:46:16 INFO - 'sessionrestore', 05:46:16 INFO - 'sessionrestore_no_auto_restore']}, 05:46:16 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:46:16 INFO - 'tests': ['a11yr', 05:46:16 INFO - 'ts_paint', 05:46:16 INFO - 'tpaint', 05:46:16 INFO - 'sessionrestore', 05:46:16 INFO - 'sessionrestore_no_auto_restore']}, 05:46:16 INFO - 'other_l64': {'tests': ['a11yr', 05:46:16 INFO - 'ts_paint', 05:46:16 INFO - 'tpaint', 05:46:16 INFO - 'sessionrestore', 05:46:16 INFO - 'sessionrestore_no_auto_restore']}, 05:46:16 INFO - 'other_nol64': {'tests': ['a11yr', 05:46:16 INFO - 'ts_paint', 05:46:16 INFO - 'tpaint', 05:46:16 INFO - 'sessionrestore', 05:46:16 INFO - 'sessionrestore_no_auto_restore']}, 05:46:16 INFO - 'svgr': {'tests': ['tsvgx', 05:46:16 INFO - 'tsvgr_opacity', 05:46:16 INFO - 'tart', 05:46:16 INFO - 'tscrollx', 05:46:16 INFO - 'cart']}, 05:46:16 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:46:16 INFO - 'tests': ['tsvgx', 05:46:16 INFO - 'tsvgr_opacity', 05:46:16 INFO - 'tart', 05:46:16 INFO - 'tscrollx', 05:46:16 INFO - 'cart']}, 05:46:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:16 INFO - 'tests': ['tp5o']}, 05:46:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:16 INFO - 'talos_options': ['--e10s'], 05:46:16 INFO - 'tests': ['tp5o']}, 05:46:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:16 INFO - 'talos_options': ['--xperf_path', 05:46:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:46:16 INFO - 'tests': ['tp5n']}, 05:46:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:16 INFO - 'talos_options': ['--e10s', 05:46:16 INFO - '--xperf_path', 05:46:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:46:16 INFO - 'tests': ['tp5n']}}, 05:46:16 INFO - 'talos.zip': {'path': '', 05:46:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:46:16 INFO - Running post-action listener: _resource_record_post_action 05:46:16 INFO - ##### 05:46:16 INFO - ##### Running create-virtualenv step. 05:46:16 INFO - ##### 05:46:16 INFO - Running pre-action listener: _resource_record_pre_action 05:46:16 INFO - Running main action method: create_virtualenv 05:46:16 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:46:16 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 05:46:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:46:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:46:16 INFO - Using real prefix '/usr' 05:46:16 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:46:17 INFO - Installing distribute.............................................................................................................................................................................................done. 05:46:19 INFO - Installing pip.................done. 05:46:19 INFO - Return code: 0 05:46:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:46:19 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')]} 05:46:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:19 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')]} 05:46:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:19 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 0x1958030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b857d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c14640>, '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 0x1c15540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c159d0>, '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': '5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742', '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 05:46:19 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 05:46:19 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 05:46:19 INFO - Using env: {'DISPLAY': ':0', 05:46:19 INFO - 'HOME': '/home/cltbld', 05:46:19 INFO - 'LANG': 'en_US.UTF-8', 05:46:19 INFO - 'LANGUAGE': 'en_US:en', 05:46:19 INFO - 'LOGNAME': 'cltbld', 05:46:19 INFO - 'MAIL': '/var/mail/cltbld', 05:46:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:19 INFO - 'MOZ_NO_REMOTE': '1', 05:46:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:19 INFO - 'NO_EM_RESTART': '1', 05:46:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:19 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:19 INFO - 'SHELL': '/bin/bash', 05:46:19 INFO - 'SHLVL': '1', 05:46:19 INFO - 'TERM': 'linux', 05:46:19 INFO - 'TMOUT': '86400', 05:46:19 INFO - 'USER': 'cltbld', 05:46:19 INFO - 'XDG_SESSION_COOKIE': '5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742', 05:46:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:19 INFO - '_': '/tools/buildbot/bin/python'} 05:46:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:20 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 05:46:20 INFO - Cleaning up... 05:46:20 INFO - Return code: 0 05:46:20 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:46: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')]} 05:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46: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')]} 05:46:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46: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', '--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 0x1958030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b857d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c14640>, '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 0x1c15540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c159d0>, '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': '5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742', '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 05:46:20 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 05:46:20 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 05:46:20 INFO - Using env: {'DISPLAY': ':0', 05:46:20 INFO - 'HOME': '/home/cltbld', 05:46:20 INFO - 'LANG': 'en_US.UTF-8', 05:46:20 INFO - 'LANGUAGE': 'en_US:en', 05:46:20 INFO - 'LOGNAME': 'cltbld', 05:46:20 INFO - 'MAIL': '/var/mail/cltbld', 05:46:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:20 INFO - 'MOZ_NO_REMOTE': '1', 05:46:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:20 INFO - 'NO_EM_RESTART': '1', 05:46:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:20 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:20 INFO - 'SHELL': '/bin/bash', 05:46:20 INFO - 'SHLVL': '1', 05:46:20 INFO - 'TERM': 'linux', 05:46:20 INFO - 'TMOUT': '86400', 05:46:20 INFO - 'USER': 'cltbld', 05:46:20 INFO - 'XDG_SESSION_COOKIE': '5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742', 05:46:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:20 INFO - '_': '/tools/buildbot/bin/python'} 05:46:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:20 INFO - Downloading/unpacking psutil>=0.7.1 05:46:20 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 05:46:20 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 05:46:20 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 05:46:20 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 05:46:20 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 05:46:20 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 05:46:22 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:46:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:46:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:46:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:46:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:46:22 INFO - Installing collected packages: psutil 05:46:22 INFO - Running setup.py install for psutil 05:46:22 INFO - building 'psutil._psutil_linux' extension 05:46:22 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 05:46:24 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 05:46:24 INFO - building 'psutil._psutil_posix' extension 05:46:24 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 05:46:24 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 05:46:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:46:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:46:24 INFO - Successfully installed psutil 05:46:24 INFO - Cleaning up... 05:46:24 INFO - Return code: 0 05:46:24 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:46:24 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')]} 05:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:24 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')]} 05:46:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:24 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 0x1958030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b857d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c14640>, '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 0x1c15540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c159d0>, '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': '5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742', '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 05:46:24 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 05:46:24 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 05:46:24 INFO - Using env: {'DISPLAY': ':0', 05:46:24 INFO - 'HOME': '/home/cltbld', 05:46:24 INFO - 'LANG': 'en_US.UTF-8', 05:46:24 INFO - 'LANGUAGE': 'en_US:en', 05:46:24 INFO - 'LOGNAME': 'cltbld', 05:46:24 INFO - 'MAIL': '/var/mail/cltbld', 05:46:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:24 INFO - 'MOZ_NO_REMOTE': '1', 05:46:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:24 INFO - 'NO_EM_RESTART': '1', 05:46:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:24 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:24 INFO - 'SHELL': '/bin/bash', 05:46:24 INFO - 'SHLVL': '1', 05:46:24 INFO - 'TERM': 'linux', 05:46:24 INFO - 'TMOUT': '86400', 05:46:24 INFO - 'USER': 'cltbld', 05:46:24 INFO - 'XDG_SESSION_COOKIE': '5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742', 05:46:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:24 INFO - '_': '/tools/buildbot/bin/python'} 05:46:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:24 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:46:24 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 05:46:24 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 05:46:24 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 05:46:24 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 05:46:24 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 05:46:24 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 05:46:26 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:46:26 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:46:26 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:46:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:46:26 INFO - Installing collected packages: mozsystemmonitor 05:46:26 INFO - Running setup.py install for mozsystemmonitor 05:46:26 INFO - Successfully installed mozsystemmonitor 05:46:26 INFO - Cleaning up... 05:46:26 INFO - Return code: 0 05:46:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:46:26 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')]} 05:46:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:26 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')]} 05:46:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:26 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 0x1958030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b857d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c14640>, '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 0x1c15540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c159d0>, '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': '5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742', '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 05:46:26 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 05:46:26 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 05:46:26 INFO - Using env: {'DISPLAY': ':0', 05:46:26 INFO - 'HOME': '/home/cltbld', 05:46:26 INFO - 'LANG': 'en_US.UTF-8', 05:46:26 INFO - 'LANGUAGE': 'en_US:en', 05:46:26 INFO - 'LOGNAME': 'cltbld', 05:46:26 INFO - 'MAIL': '/var/mail/cltbld', 05:46:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:26 INFO - 'MOZ_NO_REMOTE': '1', 05:46:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:26 INFO - 'NO_EM_RESTART': '1', 05:46:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:26 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:26 INFO - 'SHELL': '/bin/bash', 05:46:26 INFO - 'SHLVL': '1', 05:46:26 INFO - 'TERM': 'linux', 05:46:26 INFO - 'TMOUT': '86400', 05:46:26 INFO - 'USER': 'cltbld', 05:46:26 INFO - 'XDG_SESSION_COOKIE': '5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742', 05:46:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:26 INFO - '_': '/tools/buildbot/bin/python'} 05:46:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:27 INFO - Downloading/unpacking blobuploader==1.2.4 05:46:27 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 05:46:27 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 05:46:27 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 05:46:27 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 05:46:27 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 05:46:27 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 05:46:28 INFO - Downloading blobuploader-1.2.4.tar.gz 05:46:28 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:46:28 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:46:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:46:28 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 05:46:28 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 05:46:28 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 05:46:28 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 05:46:28 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 05:46:28 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 05:46:29 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:46:29 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:46:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:46:29 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 05:46:29 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 05:46:29 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 05:46:29 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 05:46:29 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 05:46:29 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 05:46:29 INFO - Downloading docopt-0.6.1.tar.gz 05:46:29 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:46:29 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:46:29 INFO - Installing collected packages: blobuploader, requests, docopt 05:46:29 INFO - Running setup.py install for blobuploader 05:46:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:46:29 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:46:29 INFO - Running setup.py install for requests 05:46:29 INFO - Running setup.py install for docopt 05:46:30 INFO - Successfully installed blobuploader requests docopt 05:46:30 INFO - Cleaning up... 05:46:30 INFO - Return code: 0 05:46:30 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:46:30 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')]} 05:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:30 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')]} 05:46:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:30 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 0x1958030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b857d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c14640>, '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 0x1c15540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c159d0>, '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': '5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742', '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 05:46:30 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 05:46:30 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 05:46:30 INFO - Using env: {'DISPLAY': ':0', 05:46:30 INFO - 'HOME': '/home/cltbld', 05:46:30 INFO - 'LANG': 'en_US.UTF-8', 05:46:30 INFO - 'LANGUAGE': 'en_US:en', 05:46:30 INFO - 'LOGNAME': 'cltbld', 05:46:30 INFO - 'MAIL': '/var/mail/cltbld', 05:46:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:30 INFO - 'MOZ_NO_REMOTE': '1', 05:46:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:30 INFO - 'NO_EM_RESTART': '1', 05:46:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:30 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:30 INFO - 'SHELL': '/bin/bash', 05:46:30 INFO - 'SHLVL': '1', 05:46:30 INFO - 'TERM': 'linux', 05:46:30 INFO - 'TMOUT': '86400', 05:46:30 INFO - 'USER': 'cltbld', 05:46:30 INFO - 'XDG_SESSION_COOKIE': '5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742', 05:46:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:30 INFO - '_': '/tools/buildbot/bin/python'} 05:46:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:30 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:46:30 INFO - Running setup.py (path:/tmp/pip-NPzTKO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:46:30 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:46:30 INFO - Running setup.py (path:/tmp/pip-P313V0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:46:30 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:46:30 INFO - Running setup.py (path:/tmp/pip-amFijo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:46:30 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:46:30 INFO - Running setup.py (path:/tmp/pip-tK4UZH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:46:30 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:46:30 INFO - Running setup.py (path:/tmp/pip-1AO8Jh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:46:30 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:46:30 INFO - Running setup.py (path:/tmp/pip-3VRKJ_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:46:30 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:46:30 INFO - Running setup.py (path:/tmp/pip-7dm8vt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:46:30 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:46:30 INFO - Running setup.py (path:/tmp/pip-ipImL9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:46:30 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:46:30 INFO - Running setup.py (path:/tmp/pip-EI6rOl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:46:30 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:46:30 INFO - Running setup.py (path:/tmp/pip-zhF7qw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:46:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:46:31 INFO - Running setup.py (path:/tmp/pip-wmPKfd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:46:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:46:31 INFO - Running setup.py (path:/tmp/pip-gUShtO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:46:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:46:31 INFO - Running setup.py (path:/tmp/pip-TSIMIp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:46:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:46:31 INFO - Running setup.py (path:/tmp/pip-dZld8W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:46:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:46:31 INFO - Running setup.py (path:/tmp/pip-XUUYeD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:46:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:46:31 INFO - Running setup.py (path:/tmp/pip-2M7uml-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:46:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:46:31 INFO - Running setup.py (path:/tmp/pip-wCkt2H-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:46:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:46:31 INFO - Running setup.py install for manifestparser 05:46:31 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:46:31 INFO - Running setup.py install for mozcrash 05:46:31 INFO - Running setup.py install for mozdebug 05:46:31 INFO - Running setup.py install for mozdevice 05:46:31 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:46:31 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:46:31 INFO - Running setup.py install for mozfile 05:46:32 INFO - Running setup.py install for mozhttpd 05:46:32 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:46:32 INFO - Running setup.py install for mozinfo 05:46:32 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:46:32 INFO - Running setup.py install for mozInstall 05:46:32 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:46:32 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:46:32 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:46:32 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:46:32 INFO - Running setup.py install for mozleak 05:46:32 INFO - Running setup.py install for mozlog 05:46:32 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:46:32 INFO - Running setup.py install for moznetwork 05:46:32 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:46:32 INFO - Running setup.py install for mozprocess 05:46:32 INFO - Running setup.py install for mozprofile 05:46:32 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:46:32 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:46:32 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:46:32 INFO - Running setup.py install for mozrunner 05:46:32 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:46:32 INFO - Running setup.py install for mozscreenshot 05:46:33 INFO - Running setup.py install for moztest 05:46:33 INFO - Running setup.py install for mozversion 05:46:33 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:46:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:46:33 INFO - Cleaning up... 05:46:33 INFO - Return code: 0 05:46:33 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:46:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:33 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x1958030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b857d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c14640>, '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 0x1c15540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c159d0>, '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': '5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742', '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 05:46:33 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 05:46:33 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 05:46:33 INFO - Using env: {'DISPLAY': ':0', 05:46:33 INFO - 'HOME': '/home/cltbld', 05:46:33 INFO - 'LANG': 'en_US.UTF-8', 05:46:33 INFO - 'LANGUAGE': 'en_US:en', 05:46:33 INFO - 'LOGNAME': 'cltbld', 05:46:33 INFO - 'MAIL': '/var/mail/cltbld', 05:46:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:33 INFO - 'MOZ_NO_REMOTE': '1', 05:46:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:33 INFO - 'NO_EM_RESTART': '1', 05:46:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:33 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:33 INFO - 'SHELL': '/bin/bash', 05:46:33 INFO - 'SHLVL': '1', 05:46:33 INFO - 'TERM': 'linux', 05:46:33 INFO - 'TMOUT': '86400', 05:46:33 INFO - 'USER': 'cltbld', 05:46:33 INFO - 'XDG_SESSION_COOKIE': '5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742', 05:46:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:33 INFO - '_': '/tools/buildbot/bin/python'} 05:46:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:46:33 INFO - Running setup.py (path:/tmp/pip-t0y5QG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:46:33 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)) 05:46:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:46:33 INFO - Running setup.py (path:/tmp/pip-obUE_M-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:46:33 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)) 05:46:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:46:33 INFO - Running setup.py (path:/tmp/pip-DVDHUP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:46:33 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)) 05:46:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:46:33 INFO - Running setup.py (path:/tmp/pip-o8_SnX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:46:33 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)) 05:46:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:46:33 INFO - Running setup.py (path:/tmp/pip-I9Jv74-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:46:33 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)) 05:46:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:46:33 INFO - Running setup.py (path:/tmp/pip-w4PrL7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:46:33 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)) 05:46:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:46:33 INFO - Running setup.py (path:/tmp/pip-p0Gw03-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:46:34 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)) 05:46:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:46:34 INFO - Running setup.py (path:/tmp/pip-WJ1OPB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:46:34 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)) 05:46:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:46:34 INFO - Running setup.py (path:/tmp/pip-rmcH0C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:46:34 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)) 05:46:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:46:34 INFO - Running setup.py (path:/tmp/pip-dgmweU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:46:34 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)) 05:46:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:46:34 INFO - Running setup.py (path:/tmp/pip-24iuNx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:46:34 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)) 05:46:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:46:34 INFO - Running setup.py (path:/tmp/pip-xCD3bN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:46:34 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)) 05:46:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:46:34 INFO - Running setup.py (path:/tmp/pip-pceqUr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:46:34 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)) 05:46:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:46:34 INFO - Running setup.py (path:/tmp/pip-3VaYO1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:46:34 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)) 05:46:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:46:34 INFO - Running setup.py (path:/tmp/pip-GDo9SO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:46:34 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)) 05:46:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:46:34 INFO - Running setup.py (path:/tmp/pip-Mcp289-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:46:34 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)) 05:46:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:46:34 INFO - Running setup.py (path:/tmp/pip-0LHAwT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:46:34 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)) 05:46:34 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)) 05:46:34 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)) 05:46:34 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)) 05:46:34 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)) 05:46:34 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)) 05:46:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:46:34 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 05:46:34 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 05:46:34 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 05:46:34 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 05:46:34 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 05:46:34 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 05:46:36 INFO - Downloading blessings-1.5.1.tar.gz 05:46:36 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 05:46:36 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:46:36 INFO - Installing collected packages: blessings 05:46:36 INFO - Running setup.py install for blessings 05:46:36 INFO - Successfully installed blessings 05:46:36 INFO - Cleaning up... 05:46:36 INFO - Return code: 0 05:46:36 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:46:36 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:46:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:46:36 INFO - Reading from file tmpfile_stdout 05:46:36 INFO - Current package versions: 05:46:36 INFO - argparse == 1.2.1 05:46:36 INFO - blessings == 1.5.1 05:46:36 INFO - blobuploader == 1.2.4 05:46:36 INFO - docopt == 0.6.1 05:46:36 INFO - manifestparser == 1.1 05:46:36 INFO - mozInstall == 1.12 05:46:36 INFO - mozcrash == 0.16 05:46:36 INFO - mozdebug == 0.1 05:46:36 INFO - mozdevice == 0.47 05:46:36 INFO - mozfile == 1.2 05:46:36 INFO - mozhttpd == 0.7 05:46:36 INFO - mozinfo == 0.9 05:46:36 INFO - mozleak == 0.1 05:46:36 INFO - mozlog == 3.1 05:46:36 INFO - moznetwork == 0.27 05:46:36 INFO - mozprocess == 0.22 05:46:36 INFO - mozprofile == 0.27 05:46:36 INFO - mozrunner == 6.11 05:46:36 INFO - mozscreenshot == 0.1 05:46:36 INFO - mozsystemmonitor == 0.0 05:46:36 INFO - moztest == 0.7 05:46:36 INFO - mozversion == 1.4 05:46:36 INFO - psutil == 3.1.1 05:46:36 INFO - requests == 1.2.3 05:46:36 INFO - wsgiref == 0.1.2 05:46:36 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:46:36 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')]} 05:46:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:36 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')]} 05:46:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:36 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 0x1958030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b857d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c14640>, '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 0x1c15540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c159d0>, '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': '5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742', '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 05:46:36 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 05:46:36 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 05:46:36 INFO - Using env: {'DISPLAY': ':0', 05:46:36 INFO - 'HOME': '/home/cltbld', 05:46:36 INFO - 'LANG': 'en_US.UTF-8', 05:46:36 INFO - 'LANGUAGE': 'en_US:en', 05:46:36 INFO - 'LOGNAME': 'cltbld', 05:46:36 INFO - 'MAIL': '/var/mail/cltbld', 05:46:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:36 INFO - 'MOZ_NO_REMOTE': '1', 05:46:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:36 INFO - 'NO_EM_RESTART': '1', 05:46:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:36 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:36 INFO - 'SHELL': '/bin/bash', 05:46:36 INFO - 'SHLVL': '1', 05:46:36 INFO - 'TERM': 'linux', 05:46:36 INFO - 'TMOUT': '86400', 05:46:36 INFO - 'USER': 'cltbld', 05:46:36 INFO - 'XDG_SESSION_COOKIE': '5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742', 05:46:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:36 INFO - '_': '/tools/buildbot/bin/python'} 05:46:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:37 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)) 05:46:37 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)) 05:46:37 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)) 05:46:37 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)) 05:46:37 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)) 05:46:37 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)) 05:46:37 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)) 05:46:37 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)) 05:46:37 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)) 05:46:37 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)) 05:46:37 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)) 05:46:37 INFO - Cleaning up... 05:46:37 INFO - Return code: 0 05:46:37 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:46:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:37 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1958030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b857d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c14640>, '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 0x1c15540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c159d0>, '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': '5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742', '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 05:46:37 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 05:46:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:46:37 INFO - Using env: {'DISPLAY': ':0', 05:46:37 INFO - 'HOME': '/home/cltbld', 05:46:37 INFO - 'LANG': 'en_US.UTF-8', 05:46:37 INFO - 'LANGUAGE': 'en_US:en', 05:46:37 INFO - 'LOGNAME': 'cltbld', 05:46:37 INFO - 'MAIL': '/var/mail/cltbld', 05:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:37 INFO - 'MOZ_NO_REMOTE': '1', 05:46:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:37 INFO - 'NO_EM_RESTART': '1', 05:46:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:37 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:37 INFO - 'SHELL': '/bin/bash', 05:46:37 INFO - 'SHLVL': '1', 05:46:37 INFO - 'TERM': 'linux', 05:46:37 INFO - 'TMOUT': '86400', 05:46:37 INFO - 'USER': 'cltbld', 05:46:37 INFO - 'XDG_SESSION_COOKIE': '5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742', 05:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:37 INFO - '_': '/tools/buildbot/bin/python'} 05:46:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:37 INFO - Downloading/unpacking jsonschema 05:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:46:39 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 05:46:39 INFO - Downloading/unpacking functools32 (from jsonschema) 05:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:39 INFO - Downloading functools32-3.2.3-2.tar.gz 05:46:39 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:46:39 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:46:39 INFO - warning: no files found matching '*.txt' 05:46:39 INFO - no previously-included directories found matching 'build' 05:46:39 INFO - no previously-included directories found matching 'dist' 05:46:39 INFO - no previously-included directories found matching '.git*' 05:46:39 INFO - Installing collected packages: jsonschema, functools32 05:46:39 INFO - Running setup.py install for functools32 05:46:39 INFO - warning: no files found matching '*.txt' 05:46:39 INFO - no previously-included directories found matching 'build' 05:46:39 INFO - no previously-included directories found matching 'dist' 05:46:39 INFO - no previously-included directories found matching '.git*' 05:46:39 INFO - Successfully installed jsonschema functools32 05:46:39 INFO - Cleaning up... 05:46:39 INFO - Return code: 0 05:46:39 INFO - Running post-action listener: _resource_record_post_action 05:46:39 INFO - Running post-action listener: _start_resource_monitoring 05:46:39 INFO - Starting resource monitoring. 05:46:39 INFO - ##### 05:46:39 INFO - ##### Running install step. 05:46:39 INFO - ##### 05:46:39 INFO - Running pre-action listener: _resource_record_pre_action 05:46:39 INFO - Running main action method: install 05:46:39 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:46:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:46:39 INFO - Reading from file tmpfile_stdout 05:46:39 INFO - Detecting whether we're running mozinstall >=1.0... 05:46:39 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:46:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:46:39 INFO - Reading from file tmpfile_stdout 05:46:39 INFO - Output received: 05:46:39 INFO - Usage: mozinstall [options] installer 05:46:39 INFO - Options: 05:46:39 INFO - -h, --help show this help message and exit 05:46:39 INFO - -d DEST, --destination=DEST 05:46:39 INFO - Directory to install application into. [default: 05:46:39 INFO - "/builds/slave/test-pgo"] 05:46:39 INFO - --app=APP Application being installed. [default: firefox] 05:46:39 INFO - mkdir: /builds/slave/test-pgo/build/application 05:46:39 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'] 05:46:39 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 05:46:54 INFO - Reading from file tmpfile_stdout 05:46:54 INFO - Output received: 05:46:54 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:46:54 INFO - Running post-action listener: _resource_record_post_action 05:46:54 INFO - ##### 05:46:54 INFO - ##### Running run-tests step. 05:46:54 INFO - ##### 05:46:54 INFO - Running pre-action listener: _resource_record_pre_action 05:46:54 INFO - Running main action method: run_tests 05:46:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:46:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:46:54 INFO - Python 2.7.3 05:46:54 INFO - Return code: 0 05:46:54 INFO - grabbing minidump binary from tooltool 05:46:54 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')]} 05:46:54 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 0x1c14640>, '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 0x1c15540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c159d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:46:54 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 05:46:54 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 05:46:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:46:54 INFO - Return code: 0 05:46:54 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:46:54 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:46:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:46:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:46:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:46:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 05:46:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-059 --webServer localhost 05:46:54 INFO - Using env: {'DISPLAY': ':0', 05:46:54 INFO - 'HOME': '/home/cltbld', 05:46:54 INFO - 'LANG': 'en_US.UTF-8', 05:46:54 INFO - 'LANGUAGE': 'en_US:en', 05:46:54 INFO - 'LOGNAME': 'cltbld', 05:46:54 INFO - 'MAIL': '/var/mail/cltbld', 05:46:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:46:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:46:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:54 INFO - 'MOZ_NO_REMOTE': '1', 05:46:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:46:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:54 INFO - 'NO_EM_RESTART': '1', 05:46:54 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:54 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:54 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:46:54 INFO - 'SHELL': '/bin/bash', 05:46:54 INFO - 'SHLVL': '1', 05:46:54 INFO - 'TERM': 'linux', 05:46:54 INFO - 'TMOUT': '86400', 05:46:54 INFO - 'USER': 'cltbld', 05:46:54 INFO - 'XDG_SESSION_COOKIE': '5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742', 05:46:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:54 INFO - '_': '/tools/buildbot/bin/python'} 05:46:54 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost'] with output_timeout 3600 05:46:54 INFO - mozversion INFO | application_buildid: 20151126043009 05:46:54 INFO - mozversion INFO | application_changeset: c321d84038519dcf1670d59fd2c5c00ad8a85a55 05:46:54 INFO - mozversion INFO | application_display_name: Nightly 05:46:54 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:46:54 INFO - mozversion INFO | application_name: Firefox 05:46:54 INFO - mozversion INFO | application_remotingname: firefox 05:46:54 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 05:46:54 INFO - mozversion INFO | application_vendor: Mozilla 05:46:54 INFO - mozversion INFO | application_version: 45.0a1 05:46:54 INFO - mozversion INFO | platform_buildid: 20151126043009 05:46:54 INFO - mozversion INFO | platform_changeset: c321d84038519dcf1670d59fd2c5c00ad8a85a55 05:46:54 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 05:46:54 INFO - mozversion INFO | platform_version: 45.0a1 05:46:54 INFO - 2015-11-26 05:46:54,908 DEBUG : using testdate: 1448545614 05:46:54 INFO - 2015-11-26 05:46:54,908 DEBUG : actual date: 1448545614 05:46:54 INFO - 2015-11-26 05:46:54,921 INFO : starting webserver on 'localhost:59715' 05:46:54 INFO - 2015-11-26 05:46:54,922 INFO : Starting test suite talos-linux64-ix-059.e 05:46:54 INFO - 2015-11-26 05:46:54,922 INFO : Starting test dromaeo_css 05:46:54 INFO - 2015-11-26 05:46:54,922 DEBUG : operating with platform_type : linux_ 05:46:54 INFO - 2015-11-26 05:46:54,922 INFO : Initialising browser for dromaeo_css test... 05:46:54 INFO - 2015-11-26 05:46:54,943 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpotj4ty/profile http://localhost:59715/getInfo.html 05:47:01 INFO - 2015-11-26 05:47:01,959 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:47:01 INFO - 2015-11-26 05:47:01,960 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:47:01 INFO - 2015-11-26 05:47:01,960 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:47:01 INFO - 2015-11-26 05:47:01,960 DEBUG : BROWSER_OUTPUT: __metrics 05:47:02 INFO - 2015-11-26 05:47:02,983 INFO : Browser initialized. 05:47:02 INFO - 2015-11-26 05:47:02,983 INFO : Running cycle 1/1 for dromaeo_css test... 05:47:02 INFO - 2015-11-26 05:47:02,983 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpotj4ty/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:47:14 INFO - 2015-11-26 05:47:14,804 DEBUG : BROWSER_OUTPUT: RSS: Main: 141348864 05:47:14 INFO - 2015-11-26 05:47:14,805 DEBUG : BROWSER_OUTPUT: 05:48:04 INFO - 2015-11-26 05:48:04,413 DEBUG : BROWSER_OUTPUT: 1448545684412 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] 05:50:12 INFO - 2015-11-26 05:50:12,794 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59715/tests/dromaeo/cssquery-ext.html) 05:50:13 INFO - 2015-11-26 05:50:13,786 DEBUG : BROWSER_OUTPUT: RSS: Main: 149000192 05:50:13 INFO - 2015-11-26 05:50:13,787 DEBUG : BROWSER_OUTPUT: 05:53:15 INFO - 2015-11-26 05:53:15,686 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/dromaeo/cssquery-ext.html (next: http://localhost:59715/tests/dromaeo/cssquery-jquery.html) 05:53:16 INFO - 2015-11-26 05:53:16,267 DEBUG : BROWSER_OUTPUT: RSS: Main: 142680064 05:53:16 INFO - 2015-11-26 05:53:16,267 DEBUG : BROWSER_OUTPUT: 05:56:17 INFO - 2015-11-26 05:56:17,939 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59715/tests/dromaeo/cssquery-mootools.html) 05:56:18 INFO - 2015-11-26 05:56:18,525 DEBUG : BROWSER_OUTPUT: RSS: Main: 143147008 05:56:18 INFO - 2015-11-26 05:56:18,525 DEBUG : BROWSER_OUTPUT: 05:59:20 INFO - 2015-11-26 05:59:20,125 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59715/tests/dromaeo/cssquery-prototype.html) 05:59:20 INFO - 2015-11-26 05:59:20,732 DEBUG : BROWSER_OUTPUT: RSS: Main: 141529088 05:59:20 INFO - 2015-11-26 05:59:20,732 DEBUG : BROWSER_OUTPUT: 06:02:23 INFO - 2015-11-26 06:02:23,006 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59715/tests/dromaeo/cssquery-yui.html) 06:02:23 INFO - 2015-11-26 06:02:23,632 DEBUG : BROWSER_OUTPUT: RSS: Main: 142827520 06:02:23 INFO - 2015-11-26 06:02:23,633 DEBUG : BROWSER_OUTPUT: 06:05:25 INFO - 2015-11-26 06:05:25,806 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/dromaeo/cssquery-yui.html (next: http://localhost:59715/tests/dromaeo/cssquery-dojo.html) 06:05:26 INFO - 2015-11-26 06:05:26,057 DEBUG : BROWSER_OUTPUT: RSS: Main: 145547264 06:05:26 INFO - 2015-11-26 06:05:26,057 DEBUG : BROWSER_OUTPUT: 06:05:26 INFO - 2015-11-26 06:05:26,057 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:05:26 INFO - 2015-11-26 06:05:26,057 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:05:26 INFO - 2015-11-26 06:05:26,057 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:05:26 INFO - 2015-11-26 06:05:26,057 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:05:26 INFO - 2015-11-26 06:05:26,059 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3226.6773322667727;3264.102371847743;3293.2425542125325;3314.3205642843213;3314.718248948839;5360.142377219646;5495.697736624489;5497;5543.113101903697;5569.582281328906;5391.406945236021;5434.972825135868;5447.073997420438;5525.834224973257;5552.305961754781;4972.0801651694555;5002.12462819007;5008.2738002989545;5107.412647545524;5148.716820574867;169.8342742503087;4884.902301953959;5010.69935803853;5012.72430016349;5043.823466178685;4964.826231081913;5032.748362581856;5036.1438555445475;5053.519915608012;5096.847094587169;5050.065737838475;5062.038212739568;5082.1360368737405;5100.591952643779;5157.819476318348;4896.192128298826;4988.201887697951;4998.925016124761;5014.59883209346;5091.032703786269;5216.947830521684;5220.4779522047875;5240.947590524084;5252.23842542833;5337.786488540453;5933.910991335133;6014.729337179838;6020.1872747179;6028.698565071729;6034.125051867499;5868.530517558628;5875.059990401515;6009.248843894513;6047.455728984369;6056.5457590680435;5663.178839068353;5675.5743319250805;5675.886482270332;5680.261565996394;5682.460166284196;6087.086936959491;6091.42131497507;6144.41628045335;6146.416090471398;6147.415995480422;5738.254026976467;5771.826845194651;5854.414558544111;5901.675407852524;5958.851028724316;5759.942400576025;5875.29496460419;5907.881842363128;5959.67221802805;5998.070299103646;5890.793822216202;5897.823065308047;5899.410058994066;5907.763689452459;5919.496842768327;5749.338826034976;5843.065109582446;5903.498202652822;5993.580449368503;6092.664903430354;4608.907821843544;4611.976940115278;4623.491415944243;4623.768811559408;4670.206064968964;4008.0581063450068;4113.465249517603;4125.91748165035;4127.876163715094;4182.351735481006;5581;5645.294338207724;5646.604737668324;5703.657780533182;5766.971165144148;6022.819315420545;6045.486133678599;6056.6971651418135;6057.7879774207695;6059.4546490815155;5678.0915053591225;5704.80033198836;5707.143928410772;5707.571932105108;5782.10377391505;5117.8720531986255;5155.9742201288755;5200.63595548308;5200.973995130076;5248.134057880483;6111.297200821962;6114.419130182626;6170.753169873245;6186.81439556814;6200.968995154995;5833.737481813244;5954.106883967266;5981.132735753378;6018.187544681499;6039.667818270037;6059.545534084873;6112.384178539333;6128.167033931239;6129.358790882775;6156.846078847885;5916.112583112567;5923.7926672566255;5924.844655292349;5925.466707996301;5944.791932282349;5867.413258674269;6007.729652165575;6009.128676341993;6020.4280593343565;6048.153258543719;5751.051076572318;5760.308762948473;5831.212786273799;5837.591368604157;5892.234009578728;5840.8247752567495;5893.705314734332;5918.408159184047;5928.318243401892;5936.673482958478;5620.606557540933;5724.599278050497;5748.913766293593;5803.390643982321;5806.970965145148;5700.802831405451;5718.742656580381;5740.425957404226;5749.252597162342;5793.449622285876;5663.969538315029;5706.029974904359;5707.771689132388;5710.143478478261;5714.485696287354 06:05:26 INFO - 2015-11-26 06:05:26,059 DEBUG : BROWSER_OUTPUT: |1;ext.html;542.1569459490494;578.2078552383235;580.4340767751443;583.0583607473931;633.4140919649324;31139.688603113962;41227;41442.58557414425;42007.36988945164;42008.789956050256;44349;44700.3294950576;44951;45163.54836451635;45164.774176129074;8647.43791653542;8830;8837.248833849131;8857.291416686681;8894;8566.742997710047;8617.784555386103;8669.913300867005;8679.609417576192;8683.522406267653;8636.568171591427;8759.386842920998;8763.693270735526;8767.167119123673;8767.693130740425;8580.485170889766;8692.304615630766;8736.956315218447;8737;8744.125587441269;8556.572171391468;8642.913570864273;8645.135486451367;8677.913220867773;8703.303735701129;40604.59395406038;40704;41000;41028.589714102774;41228.1754364914;3136.1218858719594;3203;3232.87068517259;3389.2204792897774;3527.876524321636;29626.25934351637;29706.554401683992;30321.848390758125;30727.692723072705;30988.22529436782;3864.7487913285545;3906.003968987918;3932.508436445444;3995.900102497432;4010.278149933011;263556;264153;265449;265904;451145;60116.398836011955;60621;61058;61163;61237.38762612406;64038;64557.67721161364;64762.35237647658;64965.67517162478;65249.347506524326;14991.625209369853;15190.848091519165;15204.16377099248;15225.695486090217;15235.314410851317;11441.256318339281;11590.88409115898;11651.65045048633;11660.650180494598;11673.299602023906;20443.284485042954;20656.58686826285;20682.58634827295;20685.482862928548;20778.584428311347;8126.878096828552;8326.333893288522;8399.8320033599;8408.159184081544;8435.367347448966;958.2382006305004;999.0409207161207;1029.8300780371305;1078.0513148429488;1079.8542196803487;28534.14397568035;28996.565051524245;29040.564391534142;29567.26081847971;29633.111006669835;1061.888501707325;1063.5852017713098;1082.7563798145354;1155.9479823407967;1177.9293242405483;15022.849771502364;15024.248787560578;15224.923875380773;15409.152496612794;15490.845091548941;326409;330635;331974;332468;333022;52459.73770131201;52695.73652131715;52887.47112528903;53174.734126329895;53257.467425326024;1325.5029363988504;1327.157255142972;1334.166146158651;1334.7597432462055;1347.9595612131652;27503.724962750115;28488.57267141036;28850.27874303159;28873;28881.855590721912;47064.529354706014;47307.763461182476;47308.76345618388;47314.763426182646;47635;314936;315595;316548;318609;319986;28280.575791363557;28886.42227155403;31627.52558711575;31817.5227371585;32233.194170144994;41639;41992.580074198864;42030.36954445625;42994.35508467438;43140.568594313656;35691.57987713871;35792.46311305281;36593.81703091574;36774.63225367819;36803.81598091992;35911.64088359083;39407.40888886726;43103.27586896489;43119.56880431156;43844.935605645536;37093.81453092717;37770.62229377781;37950.810245948596;38152;38249;32093.358132836747;32712.672873270963;34323.14192145217;34475.13812154716;34835.47746783851;25398.238052858444;26605;26648.33379165459;26649.33376665599;26748.732512674625 06:05:26 INFO - 2015-11-26 06:05:26,060 DEBUG : BROWSER_OUTPUT: |2;jquery.html;354.16063928489467;357.4923608475964;362.5812186924102;363.66542780641805;366.3277885080876;14090.788638170436;14656.41374345025;16576;17106.5723356916;17907.55231119224;16440;16456.588585285375;16610.837241393107;17178.74231886523;17196.312147514116;5312.096943519601;5426.131818908975;5470.2888624478965;5480.424555421683;5485.369182543998;5438.428964958682;5467.316585426822;5494.313210848644;5537.501624853762;5551.72241387931;5389.757460914268;5439.292891924064;5450.836474905759;5456.181572764097;5482.068048431776;5352.95617354616;5409.675419474844;5449.346078470569;5450.373207081197;5464.23500709899;4829;4840.782164802592;4854.004928989549;4876.585490233334;4882.902341953177;20786.896065519577;20879.164833406645;21143.259985900575;21336;21364;2177.292379976498;2210.613142700021;2211.701420308254;2284.7486776454575;2310.8266879984067;12815.743685126245;12992.870071299261;13028.283444410554;13265.336733163304;13311.534096306583;1691.3741915491223;1789.2038043070838;1795.3267524678245;1843.419322913278;1867.7478540397008;58978.70510647462;60843.08735368973;61944.69027654878;62261.37738622601;62586.37413625851;9590.184834289024;9712.562934667956;9857.55640996157;9871.40771553709;9902.158316543175;12744.171628844095;12760;12763.872361276453;13279.73440531184;13360.131591446525;7227.1327440707455;7403.444741644398;7409.8147546311775;7452.105747310357;7477.588732619758;7191.065161528968;7209.747658831916;7244.130704315516;7248.492605517563;7253.165885923081;4262.616364527207;4318.417013703172;4329.480462344476;4338.783060846945;4356.847510337123;1748.4841971618403;1773.565476458276;1778.9555261118576;1784.8036715961234;1788.3472532525795;926.2682480840195;952.2286947572488;995.8456439251928;998.0684148588131;1082.9187810914214;16290;16295.592610184603;16811.915940420466;17455.563610909576;17646.647067058588;933.3746389918771;967.6758285974278;969.8690676758547;975.1370037516849;1008.2790804574764;9553.474558899326;9723.124918757205;9836.114318563516;9839.409635421898;10000.699979000641;353807;364331;614170;631097;646213;73789;76054;78718;79938.6003069981;80134.59932700415;1126.099120703434;1133.586241022021;1213.8057910734417;1224.6509744722712;1251.5306759965013;14661.706765864836;15301.775857931561;15447.5365739028;15558.611034724223;15874.285657145225;26723.866380667972;31155.688443116185;32104.35791284208;35523.64476355203;35568.28863422769;44124.11751765011;44560.331595025455;44678.77660611806;45143.77428112838;45490;9201;9856.655017074369;9908.851367229354;9910.702678919644;9925.3548519346;17612.383566575107;17654.558636034202;17919.73120403169;18125.546861328574;18204.089795509906;14584.416623334948;14822.925885370503;15109.773353399927;15124.621884452978;15137.621559461102;13825.930870345584;13918.652033699238;14477.420903164177;14862;15109.924450377677;14526.418943242152;14583.854161458674;14701.411943522568;14799.852001479847;15064;9649.951750241204;9701.417914925127;9858.654947076819;9886.85169722469;9953.552090155816;13952.16287022782;14609.92695036518;14678.192699401636;14949;15047.322870470636 06:05:26 INFO - 2015-11-26 06:05:26,061 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1530.6556024894394;1625.520471460919;1682.1084825042728;1751.045680104343;1779.1637930172822;6971.337722916315;7071.964640176792;7712.0745510538745;7792.376609871204;7914.366850651942;7458.589777562232;7533.284337987896;7668.194839541851;7692.230776922305;7731.381489480842;2263.1400067974228;2322.814174866015;2325.500017496238;2329.324495896188;2336.4626135988733;2316.143027079983;2351.8471299365488;2360.775726305998;2361.5631108244943;2374.15717420315;2310.4801419680607;2311.456807650201;2355.5759963206615;2357.163207061494;2363.7518060603647;2282.771722827704;2293.392251053464;2294.357579877637;2322.129201549419;2334.0197117210814;2322.6283794592955;2345.8006069484113;2357.1749887539395;2358.622620380731;2365.2431222008963;7692.307692307663;7746.9225307746765;7765.611719414007;7775.766726998199;7794.259545343183;4793.568578827887;4810.927836082462;4867.805287788484;4911.45973942866;4922.064807686528;3156;3159.494480883048;3188.6333071696818;3225.2904361040532;3232.1596384940085;991.7223177510309;993.6323560282669;1014.7209517382705;1035.2132379391642;1064.69123954054;10367.326123802004;10379.325343852626;10379.84430233547;10416.114630256438;10448.738781530446;4162.292410290258;4213.2837417639075;4225.535191128974;4230.238557059759;4329.069250111178;8520.14798520143;8589.957050214709;8598.914010859937;8600;8610.138986101465;4724.126036683225;4744.24092145262;4745.667803253739;4756;4760.119377915027;4251.426057482262;4307.676924230633;4310.331898555729;4321.956780432219;4363.912721745611;3535.946960795539;3537.7523573349617;3561.2521370511963;3570.357335679603;3573.4818451324413;1102.3771569063454;1107.4960892793758;1112.7218195451137;1117.983230251547;1121.0695123047851;1125.189863298424;1125.2517076144436;1129.9548018079186;1283.0697744135614;1358.9456421743216;7135.286471352823;7175.354218120394;7211.242819503981;7216.314450127229;7222.674979625929;458.45672877639436;460.8433132734819;460.93777340058665;465.62051927678834;510.5328624308716;5692.686902220417;5734.971325143348;5736.827895163152;5740.253767010262;5744.425557444306;8818.691345802867;9105.908940910638;9115.772105697411;9242.168204861595;9280;9026.684066057787;9029.503377314179;9032.909670903338;9043.140901614335;9077.500737459372;7012.158540975013;7034.261402552761;7280.453965952574;7355.926440735631;7420.146683131401;7237.8914316286355;7285.744998925013;7298.96350518244;7309.342159205485;7342.48602597813;8079.192080791874;8142.8778568322705;8180;8342.708005219789;8382;8497.87253191215;8533.701320453754;8587.95706021466;8598.355123365647;8635.87046194295;6642.966785166043;6779.389854913082;6823.93176068233;6872.140982377203;6928.861422771617;7261.491695581259;7325.560466372035;7351.632418379167;7356.080489938758;7399.48203625741;6729.2934241903895;6795.45636349081;6880.311968803079;7013.649317534205;7019.438444924496;6687.866242675022;6688.030235615905;6762.966185169043;6844.93155068443;6889.621070841152;6772.55978361405;6783.694733737092;6799.830004249933;6814.386705196556;7010.334018268256;6254.28075771274;6262.874742505033;6304.369563043659;6306.590071645328;6309.148264984352;6360.427561519486;6361.346050026934;6366.172397588284;6384.361563843764;6421.229452465547 06:05:26 INFO - 2015-11-26 06:05:26,062 DEBUG : BROWSER_OUTPUT: |4;prototype.html;336.4885374231169;337.41626985315406;341.10800257327065;346.9791812491249;347.156409923885;6218.7201575929075;6230.190075290206;6239.906401403977;6333.936660633398;6381.936180638192;6154.169187159732;6215.3473885241965;6227.43953044226;6229.844253893643;6237.625742455444;4752.097101550703;4753.405824271966;4770.165221086313;4782.521747825224;4790.377250957371;4780.569748722614;4788.97605511972;4790.9520904791025;4794.113089078514;4797.808087676489;4658.767061646921;4724.480307166209;4747.620190384761;4751.952480475185;4752.144613969502;4704.788284527187;4736.692115012514;4743.573078422941;4744.098621261949;4745.786439610226;4091.161311931047;4097.795110244506;4102.589741025873;4104.97375656086;4106.137711080677;5939.881202375971;5960.552958528127;5960.582759206857;5968.880622387528;5980.192673989;5362.51737343641;5487.5609951203805;5493.560515158778;5500.1749737539685;5513.062779327524;6601.141851559267;6649.60102393858;6667.433268172211;6671.633060181688;6675.933240667579;4447.621952134073;4507.4365704286965;4508.887277818048;4532.093581283756;4533.659975501818;6630.171228596426;6668.9666551667415;6740.730370785162;6752.628605426748;6762.492813039042;4573.6569757268335;4596.4254468191475;4598.724076555351;4600.677952543357;4602.815887364535;4657.6273898088075;4660.533946605312;4660.720356778605;4667.976660116746;4675.111728771522;5410.8917821643345;5420.403755586883;5423.810166644149;5432.91850622241;5439.347278326547;6426.871462570816;6501.902471462932;6563.343665633399;6568.835779105465;6583.177102862142;1630.37230666192;1647.8434548717853;1666.30015393535;1666.8083170435555;1668.9165541723107;1099.5766629847567;1104.928179668319;1111.394290111891;1113.3820729495046;1115.6206889657556;6271.6550589718;6375.904361434582;6391.488680905516;6392.456641185551;6399.296077431479;6716.925291953264;6782.2200446948245;6788.830279243059;6806.353396427331;6817.216020157646;6255.46828519581;6352.523560732963;6368.745250189941;6384.52116091295;6402.327755585597;3714.832832522543;3718.200586873835;3721.330160571069;3722.2927643748144;3723.646253605903;7058.894116588255;7064.505484616027;7071.7878463646175;7074.893876591856;7080.150381954096;6945.027696122547;6991.300869912968;6999.370056694922;7018.719251229996;7019.333163349474;7162.570245785166;7222.963885180646;7226.096737907762;7231.349178573953;7236.204017558064;6876.553024053421;6914.101166848278;6919.481038922;6948.722051117898;6954;6772.424343930624;6806.285340039422;6819.215790184192;6879.036934829228;6886.035954966409;7172.211056783645;7193.640317984185;7200.855982880208;7200.8919866201;7218.747343843151;4642.210824159968;4675.345451636706;4686.578207961164;4687.554682305175;4692.554207350297;6750.223724271772;6919;6925.515213935178;6968.477364197807;6978.860422791414;6703.832404189934;6712.261651218264;6728.192616886005;6736.6968486417245;6771.119754431892;6733.595984240961;6754.257031726408;6757.020232066225;6758.121444212221;6758.594484330956;6472.838179045561;6561.311062338385;6561.40947314744;6564.376384243489;6575.473962082926;6767.323267673193;6776.796696099125;6779;6782.491313151435;6804.897926531205;6520.967395163184;6529.412352888263;6570.047343135314;6613.735450581923;6613.966930165318 06:05:26 INFO - 2015-11-26 06:05:26,062 DEBUG : BROWSER_OUTPUT: |5;yui.html;765.1353970013885;835.0897521701345;848.936329775267;855.7304449098538;856.8886044814174;11356.602518911839;11644.708882277948;11658.242214256068;11679.883201167986;11703.765924681511;11860.52557897683;12253.958413534838;12290.201136926093;12311.569095081673;12386.256824590531;817.9918200818005;829.7261903571807;830.9667613295461;832.7585000349922;835.8913341265627;831.580052073702;837.7696133563257;840.0507426607925;840.1472505407016;840.8570543007672;865.1996902864856;876.1939016105199;876.7150676030284;879.0681877210178;884.5886662701843;867.1631875240391;870.1342164546254;870.9738707838775;874.5233847553098;875.92992560595;639.6290151712043;640.3052687833706;645.1516256123183;645.3353046362255;645.5223134880155;12056.276623402624;12069.758604827943;12141.635750927486;12156.51373945041;12176.452059657337;10446.582136714522;10677.1992100592;10732.892671073345;10743.086837618734;11033.889661103367;9491.857622135674;9626.518674066268;9696.70909872705;9696.854547181798;9824;3426.9719084274616;3449.258409441982;3455.9308813823836;3472.73954453417;3481.268933523963;40460.19079618391;40463.988400290225;43382.56617433817;45414.31878521792;48975.265371019465;8841.557922103997;8850.982137054321;8892.555372231363;8905.465672059698;8933.06680358719;18108;18146.727799083023;18155;18179.363722269656;18203.908980455013;7035.050268213828;7513.774586762406;7516.5114267572435;7573.053368328959;7922.405819563556;4090.570490098556;4154.169166166779;4190.874273771792;4209.957900421018;4351.673624478177;3173.984130079335;3209.8555564999633;3212.823294718766;3214.1321843102232;3235.9352812943607;534.4388392188187;540.1735344922275;544.7548603128608;551;558.034600141757;515.4150039704962;531.8643745844785;548.4159370270648;556.3629644057531;650.5056157320375;8005.119436861941;8011.879821802678;8065.90421800935;8070.515769053875;8379.539125348165;559.712601472717;589.8260013296087;644.8900867931004;670.8255853478131;695.5965539986795;3785.716071294609;3818.7326887118193;3830.751001184914;3865.8260378283044;3870.4369976113016;59807.70096149491;60737.69631151816;60819;60868;60977.39022609717;52738.73630631822;52871.735641321546;53552.73223633857;53567.19649205265;53649;46719.29921051187;47205.76397117993;47596.7620161897;47774.76112619484;48631.5136848627;8054.879176812469;8208.425410221465;8647.437916535406;8649.567521623767;8747.91252087471;35717;36193.27613447663;36806.26387472182;37286.62713372832;37342.813285934484;46259.537404625524;46520.53479465162;49749.75125124351;49852.25221621751;51852;10727.678169654922;10785.838212426663;10959.958803913616;11076.944615276872;11477.770444590895;11426.885731142582;11543.134264929995;11718.765624687629;11768.235064720766;12101.636950891487;10659.94670026645;10684.57261709523;10869.945650271698;10984.890151098707;11009.834852477059;10895.455227238448;11167.553297867995;11430.828537571777;11496.597619083292;11625.825612615987;10324.43215623148;10344.586216551254;10427;10604.045635892806;10664.306820056672;9472.763180920532;9602.615895364108;9690.12788849007;9696.62687189246;10181.796364072825;8738.475691458532;8873.645054198018;9093.636254549743;9285.72142835716;9511.191548718436 06:05:26 INFO - 2015-11-26 06:05:26,063 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:05:26 INFO - 2015-11-26 06:05:26,063 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:05:26 INFO - 2015-11-26 06:05:26,063 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,292 06:05:26 INFO - 2015-11-26 06:05:26,063 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:05:26 INFO - 2015-11-26 06:05:26,063 DEBUG : BROWSER_OUTPUT: __startTimestamp1448546726055__endTimestamp 06:05:26 INFO - 2015-11-26 06:05:26,063 DEBUG : BROWSER_OUTPUT: 06:05:26 INFO - 2015-11-26 06:05:26,063 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:05:26 INFO - 2015-11-26 06:05:26,063 DEBUG : BROWSER_OUTPUT: Number of tests: 6 06:05:26 INFO - 2015-11-26 06:05:26,063 DEBUG : BROWSER_OUTPUT: 06:05:26 INFO - 2015-11-26 06:05:26,063 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5498.55 Median:5738.25 stddev:748.00 (13.0%) stddev-sans-first:728.75 06:05:26 INFO - 2015-11-26 06:05:26,064 DEBUG : BROWSER_OUTPUT: Values: 3226.7 3264.1 3293.2 3314.3 3314.7 5360.1 5495.7 5497.0 5543.1 5569.6 5391.4 5435.0 5447.1 5525.8 5552.3 4972.1 5002.1 5008.3 5107.4 5148.7 169.8 4884.9 5010.7 5012.7 5043.8 4964.8 5032.7 5036.1 5053.5 5096.8 5050.1 5062.0 5082.1 5100.6 5157.8 4896.2 4988.2 4998.9 5014.6 5091.0 5216.9 5220.5 5240.9 5252.2 5337.8 5933.9 6014.7 6020.2 6028.7 6034.1 5868.5 5875.1 6009.2 6047.5 6056.5 5663.2 5675.6 5675.9 5680.3 5682.5 6087.1 6091.4 6144.4 6146.4 6147.4 5738.3 5771.8 5854.4 5901.7 5958.9 5759.9 5875.3 5907.9 5959.7 5998.1 5890.8 5897.8 5899.4 5907.8 5919.5 5749.3 5843.1 5903.5 5993.6 6092.7 4608.9 4612.0 4623.5 4623.8 4670.2 4008.1 4113.5 4125.9 4127.9 4182.4 5581.0 5645.3 5646.6 5703.7 5767.0 6022.8 6045.5 6056.7 6057.8 6059.5 5678.1 5704.8 5707.1 5707.6 5782.1 5117.9 5156.0 5200.6 5201.0 5248.1 6111.3 6114.4 6170.8 6186.8 6201.0 5833.7 5954.1 5981.1 6018.2 6039.7 6059.5 6112.4 6128.2 6129.4 6156.8 5916.1 5923.8 5924.8 5925.5 5944.8 5867.4 6007.7 6009.1 6020.4 6048.2 5751.1 5760.3 5831.2 5837.6 5892.2 5840.8 5893.7 5918.4 5928.3 5936.7 5620.6 5724.6 5748.9 5803.4 5807.0 5700.8 5718.7 5740.4 5749.3 5793.4 5664.0 5706.0 5707.8 5710.1 5714.5 06:05:26 INFO - 2015-11-26 06:05:26,065 DEBUG : BROWSER_OUTPUT: 06:05:26 INFO - 2015-11-26 06:05:26,065 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48979.23 Median:37860.72 stddev:83866.12 (221.5%) stddev-sans-first:84022.54 06:05:26 INFO - 2015-11-26 06:05:26,065 DEBUG : BROWSER_OUTPUT: Values: 542.2 578.2 580.4 583.1 633.4 31139.7 41227.0 41442.6 42007.4 42008.8 44349.0 44700.3 44951.0 45163.5 45164.8 8647.4 8830.0 8837.2 8857.3 8894.0 8566.7 8617.8 8669.9 8679.6 8683.5 8636.6 8759.4 8763.7 8767.2 8767.7 8580.5 8692.3 8737.0 8737.0 8744.1 8556.6 8642.9 8645.1 8677.9 8703.3 40604.6 40704.0 41000.0 41028.6 41228.2 3136.1 3203.0 3232.9 3389.2 3527.9 29626.3 29706.6 30321.8 30727.7 30988.2 3864.7 3906.0 3932.5 3995.9 4010.3 263556.0 264153.0 265449.0 265904.0 451145.0 60116.4 60621.0 61058.0 61163.0 61237.4 64038.0 64557.7 64762.4 64965.7 65249.3 14991.6 15190.8 15204.2 15225.7 15235.3 11441.3 11590.9 11651.7 11660.7 11673.3 20443.3 20656.6 20682.6 20685.5 20778.6 8126.9 8326.3 8399.8 8408.2 8435.4 958.2 999.0 1029.8 1078.1 1079.9 28534.1 28996.6 29040.6 29567.3 29633.1 1061.9 1063.6 1082.8 1155.9 1177.9 15022.8 15024.2 15224.9 15409.2 15490.8 326409.0 330635.0 331974.0 332468.0 333022.0 52459.7 52695.7 52887.5 53174.7 53257.5 1325.5 1327.2 1334.2 1334.8 1348.0 27503.7 28488.6 28850.3 28873.0 28881.9 47064.5 47307.8 47308.8 47314.8 47635.0 314936.0 315595.0 316548.0 318609.0 319986.0 28280.6 28886.4 31627.5 31817.5 32233.2 41639.0 41992.6 42030.4 42994.4 43140.6 35691.6 35792.5 36593.8 36774.6 36803.8 35911.6 39407.4 43103.3 43119.6 43844.9 37093.8 37770.6 37950.8 38152.0 38249.0 32093.4 32712.7 34323.1 34475.1 34835.5 25398.2 26605.0 26648.3 26649.3 26748.7 06:05:26 INFO - 2015-11-26 06:05:26,065 DEBUG : BROWSER_OUTPUT: 06:05:26 INFO - 2015-11-26 06:05:26,065 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28361.72 Median:360.04 stddev:88090.70 (24467.1%) stddev-sans-first:88312.72 06:05:26 INFO - 2015-11-26 06:05:26,066 DEBUG : BROWSER_OUTPUT: Values: 354.2 357.5 362.6 363.7 366.3 14090.8 14656.4 16576.0 17106.6 17907.6 16440.0 16456.6 16610.8 17178.7 17196.3 5312.1 5426.1 5470.3 5480.4 5485.4 5438.4 5467.3 5494.3 5537.5 5551.7 5389.8 5439.3 5450.8 5456.2 5482.1 5353.0 5409.7 5449.3 5450.4 5464.2 4829.0 4840.8 4854.0 4876.6 4882.9 20786.9 20879.2 21143.3 21336.0 21364.0 2177.3 2210.6 2211.7 2284.7 2310.8 12815.7 12992.9 13028.3 13265.3 13311.5 1691.4 1789.2 1795.3 1843.4 1867.7 58978.7 60843.1 61944.7 62261.4 62586.4 9590.2 9712.6 9857.6 9871.4 9902.2 12744.2 12760.0 12763.9 13279.7 13360.1 7227.1 7403.4 7409.8 7452.1 7477.6 7191.1 7209.7 7244.1 7248.5 7253.2 4262.6 4318.4 4329.5 4338.8 4356.8 1748.5 1773.6 1779.0 1784.8 1788.3 926.3 952.2 995.8 998.1 1082.9 16290.0 16295.6 16811.9 17455.6 17646.6 933.4 967.7 969.9 975.1 1008.3 9553.5 9723.1 9836.1 9839.4 10000.7 353807.0 364331.0 614170.0 631097.0 646213.0 73789.0 76054.0 78718.0 79938.6 80134.6 1126.1 1133.6 1213.8 1224.7 1251.5 14661.7 15301.8 15447.5 15558.6 15874.3 26723.9 31155.7 32104.4 35523.6 35568.3 44124.1 44560.3 44678.8 45143.8 45490.0 9201.0 9856.7 9908.9 9910.7 9925.4 17612.4 17654.6 17919.7 18125.5 18204.1 14584.4 14822.9 15109.8 15124.6 15137.6 13825.9 13918.7 14477.4 14862.0 15109.9 14526.4 14583.9 14701.4 14799.9 15064.0 9650.0 9701.4 9858.7 9886.9 9953.6 13952.2 14609.9 14678.2 14949.0 15047.3 06:05:26 INFO - 2015-11-26 06:05:26,066 DEBUG : BROWSER_OUTPUT: 06:05:26 INFO - 2015-11-26 06:05:26,066 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5377.52 Median:6305.48 stddev:2752.10 (43.6%) stddev-sans-first:2744.63 06:05:26 INFO - 2015-11-26 06:05:26,068 DEBUG : BROWSER_OUTPUT: Values: 1530.7 1625.5 1682.1 1751.0 1779.2 6971.3 7072.0 7712.1 7792.4 7914.4 7458.6 7533.3 7668.2 7692.2 7731.4 2263.1 2322.8 2325.5 2329.3 2336.5 2316.1 2351.8 2360.8 2361.6 2374.2 2310.5 2311.5 2355.6 2357.2 2363.8 2282.8 2293.4 2294.4 2322.1 2334.0 2322.6 2345.8 2357.2 2358.6 2365.2 7692.3 7746.9 7765.6 7775.8 7794.3 4793.6 4810.9 4867.8 4911.5 4922.1 3156.0 3159.5 3188.6 3225.3 3232.2 991.7 993.6 1014.7 1035.2 1064.7 10367.3 10379.3 10379.8 10416.1 10448.7 4162.3 4213.3 4225.5 4230.2 4329.1 8520.1 8590.0 8598.9 8600.0 8610.1 4724.1 4744.2 4745.7 4756.0 4760.1 4251.4 4307.7 4310.3 4322.0 4363.9 3535.9 3537.8 3561.3 3570.4 3573.5 1102.4 1107.5 1112.7 1118.0 1121.1 1125.2 1125.3 1130.0 1283.1 1358.9 7135.3 7175.4 7211.2 7216.3 7222.7 458.5 460.8 460.9 465.6 510.5 5692.7 5735.0 5736.8 5740.3 5744.4 8818.7 9105.9 9115.8 9242.2 9280.0 9026.7 9029.5 9032.9 9043.1 9077.5 7012.2 7034.3 7280.5 7355.9 7420.1 7237.9 7285.7 7299.0 7309.3 7342.5 8079.2 8142.9 8180.0 8342.7 8382.0 8497.9 8533.7 8588.0 8598.4 8635.9 6643.0 6779.4 6823.9 6872.1 6928.9 7261.5 7325.6 7351.6 7356.1 7399.5 6729.3 6795.5 6880.3 7013.6 7019.4 6687.9 6688.0 6763.0 6844.9 6889.6 6772.6 6783.7 6799.8 6814.4 7010.3 6254.3 6262.9 6304.4 6306.6 6309.1 6360.4 6361.3 6366.2 6384.4 6421.2 06:05:26 INFO - 2015-11-26 06:05:26,068 DEBUG : BROWSER_OUTPUT: 06:05:26 INFO - 2015-11-26 06:05:26,068 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5546.14 Median:6343.23 stddev:1694.10 (26.7%) stddev-sans-first:1653.11 06:05:26 INFO - 2015-11-26 06:05:26,069 DEBUG : BROWSER_OUTPUT: Values: 336.5 337.4 341.1 347.0 347.2 6218.7 6230.2 6239.9 6333.9 6381.9 6154.2 6215.3 6227.4 6229.8 6237.6 4752.1 4753.4 4770.2 4782.5 4790.4 4780.6 4789.0 4791.0 4794.1 4797.8 4658.8 4724.5 4747.6 4752.0 4752.1 4704.8 4736.7 4743.6 4744.1 4745.8 4091.2 4097.8 4102.6 4105.0 4106.1 5939.9 5960.6 5960.6 5968.9 5980.2 5362.5 5487.6 5493.6 5500.2 5513.1 6601.1 6649.6 6667.4 6671.6 6675.9 4447.6 4507.4 4508.9 4532.1 4533.7 6630.2 6669.0 6740.7 6752.6 6762.5 4573.7 4596.4 4598.7 4600.7 4602.8 4657.6 4660.5 4660.7 4668.0 4675.1 5410.9 5420.4 5423.8 5432.9 5439.3 6426.9 6501.9 6563.3 6568.8 6583.2 1630.4 1647.8 1666.3 1666.8 1668.9 1099.6 1104.9 1111.4 1113.4 1115.6 6271.7 6375.9 6391.5 6392.5 6399.3 6716.9 6782.2 6788.8 6806.4 6817.2 6255.5 6352.5 6368.7 6384.5 6402.3 3714.8 3718.2 3721.3 3722.3 3723.6 7058.9 7064.5 7071.8 7074.9 7080.2 6945.0 6991.3 6999.4 7018.7 7019.3 7162.6 7223.0 7226.1 7231.3 7236.2 6876.6 6914.1 6919.5 6948.7 6954.0 6772.4 6806.3 6819.2 6879.0 6886.0 7172.2 7193.6 7200.9 7200.9 7218.7 4642.2 4675.3 4686.6 4687.6 4692.6 6750.2 6919.0 6925.5 6968.5 6978.9 6703.8 6712.3 6728.2 6736.7 6771.1 6733.6 6754.3 6757.0 6758.1 6758.6 6472.8 6561.3 6561.4 6564.4 6575.5 6767.3 6776.8 6779.0 6782.5 6804.9 6521.0 6529.4 6570.0 6613.7 6614.0 06:05:26 INFO - 2015-11-26 06:05:26,069 DEBUG : BROWSER_OUTPUT: 06:05:26 INFO - 2015-11-26 06:05:26,069 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13715.00 Median:26183.71 stddev:16512.89 (63.1%) stddev-sans-first:16530.58 06:05:26 INFO - 2015-11-26 06:05:26,071 DEBUG : BROWSER_OUTPUT: Values: 765.1 835.1 848.9 855.7 856.9 11356.6 11644.7 11658.2 11679.9 11703.8 11860.5 12254.0 12290.2 12311.6 12386.3 818.0 829.7 831.0 832.8 835.9 831.6 837.8 840.1 840.1 840.9 865.2 876.2 876.7 879.1 884.6 867.2 870.1 871.0 874.5 875.9 639.6 640.3 645.2 645.3 645.5 12056.3 12069.8 12141.6 12156.5 12176.5 10446.6 10677.2 10732.9 10743.1 11033.9 9491.9 9626.5 9696.7 9696.9 9824.0 3427.0 3449.3 3455.9 3472.7 3481.3 40460.2 40464.0 43382.6 45414.3 48975.3 8841.6 8851.0 8892.6 8905.5 8933.1 18108.0 18146.7 18155.0 18179.4 18203.9 7035.1 7513.8 7516.5 7573.1 7922.4 4090.6 4154.2 4190.9 4210.0 4351.7 3174.0 3209.9 3212.8 3214.1 3235.9 534.4 540.2 544.8 551.0 558.0 515.4 531.9 548.4 556.4 650.5 8005.1 8011.9 8065.9 8070.5 8379.5 559.7 589.8 644.9 670.8 695.6 3785.7 3818.7 3830.8 3865.8 3870.4 59807.7 60737.7 60819.0 60868.0 60977.4 52738.7 52871.7 53552.7 53567.2 53649.0 46719.3 47205.8 47596.8 47774.8 48631.5 8054.9 8208.4 8647.4 8649.6 8747.9 35717.0 36193.3 36806.3 37286.6 37342.8 46259.5 46520.5 49749.8 49852.3 51852.0 10727.7 10785.8 10960.0 11076.9 11477.8 11426.9 11543.1 11718.8 11768.2 12101.6 10659.9 10684.6 10869.9 10984.9 11009.8 10895.5 11167.6 11430.8 11496.6 11625.8 10324.4 10344.6 10427.0 10604.0 10664.3 9472.8 9602.6 9690.1 9696.6 10181.8 8738.5 8873.6 9093.6 9285.7 9511.2 06:05:26 INFO - 2015-11-26 06:05:26,071 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:05:26 INFO - 2015-11-26 06:05:26,071 DEBUG : BROWSER_OUTPUT: 06:05:26 INFO - 2015-11-26 06:05:26,719 INFO : Browser exited with error code: 0 06:05:26 INFO - 2015-11-26 06:05:26,737 INFO : Completed test dromaeo_css (00:18:31) 06:05:26 INFO - 2015-11-26 06:05:26,737 INFO : Starting test kraken 06:05:26 INFO - 2015-11-26 06:05:26,737 DEBUG : operating with platform_type : linux_ 06:05:26 INFO - 2015-11-26 06:05:26,737 INFO : Initialising browser for kraken test... 06:05:26 INFO - 2015-11-26 06:05:26,744 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp1SivEQ/profile http://localhost:59715/getInfo.html 06:05:31 INFO - 2015-11-26 06:05:31,068 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:05:31 INFO - 2015-11-26 06:05:31,068 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:05:31 INFO - 2015-11-26 06:05:31,068 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:05:31 INFO - 2015-11-26 06:05:31,068 DEBUG : BROWSER_OUTPUT: __metrics 06:05:31 INFO - 2015-11-26 06:05:31,991 INFO : Browser initialized. 06:05:31 INFO - 2015-11-26 06:05:31,991 INFO : Running cycle 1/1 for kraken test... 06:05:31 INFO - 2015-11-26 06:05:31,991 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp1SivEQ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:05:43 INFO - 2015-11-26 06:05:43,743 DEBUG : BROWSER_OUTPUT: RSS: Main: 138649600 06:05:43 INFO - 2015-11-26 06:05:43,743 DEBUG : BROWSER_OUTPUT: 06:05:46 INFO - 2015-11-26 06:05:46,223 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59715/tests/kraken/driver.html?testName=audio-beat-detection) 06:05:46 INFO - 2015-11-26 06:05:46,830 DEBUG : BROWSER_OUTPUT: RSS: Main: 140959744 06:05:46 INFO - 2015-11-26 06:05:46,830 DEBUG : BROWSER_OUTPUT: 06:05:49 INFO - 2015-11-26 06:05:49,488 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59715/tests/kraken/driver.html?testName=audio-dft) 06:05:50 INFO - 2015-11-26 06:05:50,081 DEBUG : BROWSER_OUTPUT: RSS: Main: 140865536 06:05:50 INFO - 2015-11-26 06:05:50,081 DEBUG : BROWSER_OUTPUT: 06:05:52 INFO - 2015-11-26 06:05:52,920 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59715/tests/kraken/driver.html?testName=audio-fft) 06:05:53 INFO - 2015-11-26 06:05:53,501 DEBUG : BROWSER_OUTPUT: RSS: Main: 135073792 06:05:53 INFO - 2015-11-26 06:05:53,501 DEBUG : BROWSER_OUTPUT: 06:05:55 INFO - 2015-11-26 06:05:55,499 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59715/tests/kraken/driver.html?testName=audio-oscillator) 06:05:56 INFO - 2015-11-26 06:05:56,080 DEBUG : BROWSER_OUTPUT: RSS: Main: 136265728 06:05:56 INFO - 2015-11-26 06:05:56,080 DEBUG : BROWSER_OUTPUT: 06:05:58 INFO - 2015-11-26 06:05:58,281 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59715/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:05:58 INFO - 2015-11-26 06:05:58,867 DEBUG : BROWSER_OUTPUT: RSS: Main: 137510912 06:05:58 INFO - 2015-11-26 06:05:58,867 DEBUG : BROWSER_OUTPUT: 06:06:01 INFO - 2015-11-26 06:06:01,867 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59715/tests/kraken/driver.html?testName=imaging-darkroom) 06:06:02 INFO - 2015-11-26 06:06:02,449 DEBUG : BROWSER_OUTPUT: RSS: Main: 137785344 06:06:02 INFO - 2015-11-26 06:06:02,449 DEBUG : BROWSER_OUTPUT: 06:06:05 INFO - 2015-11-26 06:06:05,872 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59715/tests/kraken/driver.html?testName=imaging-desaturate) 06:06:06 INFO - 2015-11-26 06:06:06,461 DEBUG : BROWSER_OUTPUT: RSS: Main: 137744384 06:06:06 INFO - 2015-11-26 06:06:06,461 DEBUG : BROWSER_OUTPUT: 06:06:09 INFO - 2015-11-26 06:06:09,311 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59715/tests/kraken/driver.html?testName=json-parse-financial) 06:06:09 INFO - 2015-11-26 06:06:09,902 DEBUG : BROWSER_OUTPUT: RSS: Main: 138215424 06:06:09 INFO - 2015-11-26 06:06:09,902 DEBUG : BROWSER_OUTPUT: 06:06:11 INFO - 2015-11-26 06:06:11,491 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59715/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:06:12 INFO - 2015-11-26 06:06:12,078 DEBUG : BROWSER_OUTPUT: RSS: Main: 139075584 06:06:12 INFO - 2015-11-26 06:06:12,078 DEBUG : BROWSER_OUTPUT: 06:06:13 INFO - 2015-11-26 06:06:13,915 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59715/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:06:14 INFO - 2015-11-26 06:06:14,520 DEBUG : BROWSER_OUTPUT: RSS: Main: 137908224 06:06:14 INFO - 2015-11-26 06:06:14,521 DEBUG : BROWSER_OUTPUT: 06:06:16 INFO - 2015-11-26 06:06:16,580 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59715/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:06:17 INFO - 2015-11-26 06:06:17,166 DEBUG : BROWSER_OUTPUT: RSS: Main: 137945088 06:06:17 INFO - 2015-11-26 06:06:17,166 DEBUG : BROWSER_OUTPUT: 06:06:19 INFO - 2015-11-26 06:06:19,646 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59715/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:06:20 INFO - 2015-11-26 06:06:20,249 DEBUG : BROWSER_OUTPUT: RSS: Main: 138375168 06:06:20 INFO - 2015-11-26 06:06:20,249 DEBUG : BROWSER_OUTPUT: 06:06:22 INFO - 2015-11-26 06:06:22,818 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59715/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:06:23 INFO - 2015-11-26 06:06:23,420 DEBUG : BROWSER_OUTPUT: RSS: Main: 139431936 06:06:23 INFO - 2015-11-26 06:06:23,420 DEBUG : BROWSER_OUTPUT: 06:06:25 INFO - 2015-11-26 06:06:25,101 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59715/tests/kraken/driver.html?testName=ai-astar) 06:06:25 INFO - 2015-11-26 06:06:25,359 DEBUG : BROWSER_OUTPUT: RSS: Main: 140025856 06:06:25 INFO - 2015-11-26 06:06:25,359 DEBUG : BROWSER_OUTPUT: 06:06:25 INFO - 2015-11-26 06:06:25,359 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:06:25 INFO - 2015-11-26 06:06:25,360 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:06:25 INFO - 2015-11-26 06:06:25,360 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:06:25 INFO - 2015-11-26 06:06:25,360 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:06:25 INFO - 2015-11-26 06:06:25,360 DEBUG : BROWSER_OUTPUT: |0;ai-astar;114;104;111;111;111;122;111;111;110;112 06:06:25 INFO - 2015-11-26 06:06:25,360 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;154;125;153;162;127;174;162;131;166;132 06:06:25 INFO - 2015-11-26 06:06:25,361 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;160;162;165;161;163;162;164;172;163 06:06:25 INFO - 2015-11-26 06:06:25,361 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;72;76;72;127;72;79;71;71 06:06:25 INFO - 2015-11-26 06:06:25,361 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;93;137;94;93;93;141;94;94;132 06:06:25 INFO - 2015-11-26 06:06:25,361 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;110;112;112;111;110;109;110 06:06:25 INFO - 2015-11-26 06:06:25,362 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;147;146;146;147;147;147;145;147;147 06:06:25 INFO - 2015-11-26 06:06:25,362 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;97;96;96;100;98;97;96;96;97 06:06:25 INFO - 2015-11-26 06:06:25,362 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;63;61;70;61;62;61;61;61;60;60 06:06:25 INFO - 2015-11-26 06:06:25,362 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;74;71;70;71;71;70;70;70;70;70 06:06:25 INFO - 2015-11-26 06:06:25,362 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;86;85;85;85;93;88;84;84;85 06:06:25 INFO - 2015-11-26 06:06:25,363 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;126;123;119;124;125;135;121;114;129;125 06:06:25 INFO - 2015-11-26 06:06:25,363 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;150;156;157;147;138;150;151;149;150 06:06:25 INFO - 2015-11-26 06:06:25,363 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;68;65;66;73;67;66;67;66;66 06:06:25 INFO - 2015-11-26 06:06:25,363 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:06:25 INFO - 2015-11-26 06:06:25,363 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:06:25 INFO - 2015-11-26 06:06:25,363 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,748 06:06:25 INFO - 2015-11-26 06:06:25,363 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:06:25 INFO - 2015-11-26 06:06:25,364 DEBUG : BROWSER_OUTPUT: __startTimestamp1448546785353__endTimestamp 06:06:25 INFO - 2015-11-26 06:06:25,364 DEBUG : BROWSER_OUTPUT: 06:06:25 INFO - 2015-11-26 06:06:25,364 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:06:25 INFO - 2015-11-26 06:06:25,364 DEBUG : BROWSER_OUTPUT: Number of tests: 14 06:06:25 INFO - 2015-11-26 06:06:25,364 DEBUG : BROWSER_OUTPUT: 06:06:25 INFO - 2015-11-26 06:06:25,364 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.70 Median:111.00 stddev:4.42 (4.0%) stddev-sans-first:4.61 06:06:25 INFO - 2015-11-26 06:06:25,364 DEBUG : BROWSER_OUTPUT: Values: 114.0 104.0 111.0 111.0 111.0 122.0 111.0 111.0 110.0 112.0 06:06:25 INFO - 2015-11-26 06:06:25,364 DEBUG : BROWSER_OUTPUT: 06:06:25 INFO - 2015-11-26 06:06:25,365 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:148.60 Median:158.00 stddev:18.15 (11.5%) stddev-sans-first:19.14 06:06:25 INFO - 2015-11-26 06:06:25,365 DEBUG : BROWSER_OUTPUT: Values: 154.0 125.0 153.0 162.0 127.0 174.0 162.0 131.0 166.0 132.0 06:06:25 INFO - 2015-11-26 06:06:25,365 DEBUG : BROWSER_OUTPUT: 06:06:25 INFO - 2015-11-26 06:06:25,365 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.30 Median:163.00 stddev:3.40 (2.1%) stddev-sans-first:3.50 06:06:25 INFO - 2015-11-26 06:06:25,365 DEBUG : BROWSER_OUTPUT: Values: 161.0 160.0 162.0 165.0 161.0 163.0 162.0 164.0 172.0 163.0 06:06:25 INFO - 2015-11-26 06:06:25,365 DEBUG : BROWSER_OUTPUT: 06:06:25 INFO - 2015-11-26 06:06:25,365 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:78.30 Median:72.00 stddev:17.31 (24.0%) stddev-sans-first:18.16 06:06:25 INFO - 2015-11-26 06:06:25,365 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 72.0 76.0 72.0 127.0 72.0 79.0 71.0 71.0 06:06:25 INFO - 2015-11-26 06:06:25,365 DEBUG : BROWSER_OUTPUT: 06:06:25 INFO - 2015-11-26 06:06:25,365 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.50 Median:93.50 stddev:20.93 (22.4%) stddev-sans-first:21.71 06:06:25 INFO - 2015-11-26 06:06:25,365 DEBUG : BROWSER_OUTPUT: Values: 94.0 93.0 137.0 94.0 93.0 93.0 141.0 94.0 94.0 132.0 06:06:25 INFO - 2015-11-26 06:06:25,366 DEBUG : BROWSER_OUTPUT: 06:06:25 INFO - 2015-11-26 06:06:25,366 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.40 Median:110.00 stddev:0.97 (0.9%) stddev-sans-first:1.01 06:06:25 INFO - 2015-11-26 06:06:25,366 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 110.0 112.0 112.0 111.0 110.0 109.0 110.0 06:06:25 INFO - 2015-11-26 06:06:25,366 DEBUG : BROWSER_OUTPUT: 06:06:25 INFO - 2015-11-26 06:06:25,366 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 06:06:25 INFO - 2015-11-26 06:06:25,366 DEBUG : BROWSER_OUTPUT: Values: 147.0 147.0 146.0 146.0 147.0 147.0 147.0 145.0 147.0 147.0 06:06:25 INFO - 2015-11-26 06:06:25,366 DEBUG : BROWSER_OUTPUT: 06:06:25 INFO - 2015-11-26 06:06:25,367 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.90 Median:96.50 stddev:1.29 (1.3%) stddev-sans-first:1.32 06:06:25 INFO - 2015-11-26 06:06:25,367 DEBUG : BROWSER_OUTPUT: Values: 96.0 97.0 96.0 96.0 100.0 98.0 97.0 96.0 96.0 97.0 06:06:25 INFO - 2015-11-26 06:06:25,367 DEBUG : BROWSER_OUTPUT: 06:06:25 INFO - 2015-11-26 06:06:25,367 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:62.00 Median:61.00 stddev:2.94 (4.8%) stddev-sans-first:3.10 06:06:25 INFO - 2015-11-26 06:06:25,367 DEBUG : BROWSER_OUTPUT: Values: 63.0 61.0 70.0 61.0 62.0 61.0 61.0 61.0 60.0 60.0 06:06:25 INFO - 2015-11-26 06:06:25,367 DEBUG : BROWSER_OUTPUT: 06:06:25 INFO - 2015-11-26 06:06:25,368 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:70.50 stddev:1.25 (1.8%) stddev-sans-first:0.50 06:06:25 INFO - 2015-11-26 06:06:25,368 DEBUG : BROWSER_OUTPUT: Values: 74.0 71.0 70.0 71.0 71.0 70.0 70.0 70.0 70.0 70.0 06:06:25 INFO - 2015-11-26 06:06:25,368 DEBUG : BROWSER_OUTPUT: 06:06:25 INFO - 2015-11-26 06:06:25,368 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:85.00 stddev:2.77 (3.3%) stddev-sans-first:2.85 06:06:25 INFO - 2015-11-26 06:06:25,368 DEBUG : BROWSER_OUTPUT: Values: 84.0 86.0 85.0 85.0 85.0 93.0 88.0 84.0 84.0 85.0 06:06:25 INFO - 2015-11-26 06:06:25,370 DEBUG : BROWSER_OUTPUT: 06:06:25 INFO - 2015-11-26 06:06:25,370 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:124.10 Median:125.00 stddev:5.65 (4.5%) stddev-sans-first:5.95 06:06:25 INFO - 2015-11-26 06:06:25,370 DEBUG : BROWSER_OUTPUT: Values: 126.0 123.0 119.0 124.0 125.0 135.0 121.0 114.0 129.0 125.0 06:06:25 INFO - 2015-11-26 06:06:25,370 DEBUG : BROWSER_OUTPUT: 06:06:25 INFO - 2015-11-26 06:06:25,370 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.80 Median:150.00 stddev:6.01 (4.0%) stddev-sans-first:5.47 06:06:25 INFO - 2015-11-26 06:06:25,370 DEBUG : BROWSER_OUTPUT: Values: 140.0 150.0 156.0 157.0 147.0 138.0 150.0 151.0 149.0 150.0 06:06:25 INFO - 2015-11-26 06:06:25,370 DEBUG : BROWSER_OUTPUT: 06:06:25 INFO - 2015-11-26 06:06:25,370 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:2.23 (3.3%) stddev-sans-first:2.37 06:06:25 INFO - 2015-11-26 06:06:25,370 DEBUG : BROWSER_OUTPUT: Values: 67.0 68.0 65.0 66.0 73.0 67.0 66.0 67.0 66.0 66.0 06:06:25 INFO - 2015-11-26 06:06:25,371 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:06:25 INFO - 2015-11-26 06:06:25,371 DEBUG : BROWSER_OUTPUT: 06:06:26 INFO - 2015-11-26 06:06:26,178 INFO : Browser exited with error code: 0 06:06:26 INFO - 2015-11-26 06:06:26,227 INFO : Completed test kraken (00:00:59) 06:06:26 INFO - 2015-11-26 06:06:26,227 INFO : Starting test v8_7 06:06:26 INFO - 2015-11-26 06:06:26,227 DEBUG : operating with platform_type : linux_ 06:06:26 INFO - 2015-11-26 06:06:26,227 INFO : Initialising browser for v8_7 test... 06:06:26 INFO - 2015-11-26 06:06:26,233 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpccRkhs/profile http://localhost:59715/getInfo.html 06:06:30 INFO - 2015-11-26 06:06:30,455 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:06:30 INFO - 2015-11-26 06:06:30,456 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:06:30 INFO - 2015-11-26 06:06:30,456 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:06:30 INFO - 2015-11-26 06:06:30,456 DEBUG : BROWSER_OUTPUT: __metrics 06:06:31 INFO - 2015-11-26 06:06:31,378 INFO : Browser initialized. 06:06:31 INFO - 2015-11-26 06:06:31,379 INFO : Running cycle 1/1 for v8_7 test... 06:06:31 INFO - 2015-11-26 06:06:31,379 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpccRkhs/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:06:43 INFO - 2015-11-26 06:06:43,094 DEBUG : BROWSER_OUTPUT: RSS: Main: 138723328 06:06:43 INFO - 2015-11-26 06:06:43,095 DEBUG : BROWSER_OUTPUT: 06:07:04 INFO - 2015-11-26 06:07:04,623 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59715/tests/v8_7/run.html (next: http://localhost:59715/tests/v8_7/run.html) 06:07:04 INFO - 2015-11-26 06:07:04,873 DEBUG : BROWSER_OUTPUT: RSS: Main: 137396224 06:07:04 INFO - 2015-11-26 06:07:04,873 DEBUG : BROWSER_OUTPUT: 06:07:04 INFO - 2015-11-26 06:07:04,874 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:07:04 INFO - 2015-11-26 06:07:04,875 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:07:04 INFO - 2015-11-26 06:07:04,875 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:07:04 INFO - 2015-11-26 06:07:04,875 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:07:04 INFO - 2015-11-26 06:07:04,875 DEBUG : BROWSER_OUTPUT: |0;Richards;166.88918558077435 06:07:04 INFO - 2015-11-26 06:07:04,875 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;185.63207722294413 06:07:04 INFO - 2015-11-26 06:07:04,875 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.3296089385475 06:07:04 INFO - 2015-11-26 06:07:04,875 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5191.709844559586 06:07:04 INFO - 2015-11-26 06:07:04,876 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1085.7763300760043 06:07:04 INFO - 2015-11-26 06:07:04,876 DEBUG : BROWSER_OUTPUT: |5;Earley;713.7758743754462 06:07:04 INFO - 2015-11-26 06:07:04,876 DEBUG : BROWSER_OUTPUT: |6;Boyer;12000 06:07:04 INFO - 2015-11-26 06:07:04,876 DEBUG : BROWSER_OUTPUT: |7;RegExp;26025.641025641027 06:07:04 INFO - 2015-11-26 06:07:04,876 DEBUG : BROWSER_OUTPUT: |8;Splay;615.3374233128834 06:07:04 INFO - 2015-11-26 06:07:04,876 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 06:07:04 INFO - 2015-11-26 06:07:04,877 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:07:04 INFO - 2015-11-26 06:07:04,877 DEBUG : BROWSER_OUTPUT: __startTimestamp1448546824873__endTimestamp 06:07:04 INFO - 2015-11-26 06:07:04,877 DEBUG : BROWSER_OUTPUT: 06:07:04 INFO - 2015-11-26 06:07:04,877 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:07:04 INFO - 2015-11-26 06:07:04,877 DEBUG : BROWSER_OUTPUT: Number of tests: 10 06:07:04 INFO - 2015-11-26 06:07:04,878 DEBUG : BROWSER_OUTPUT: 06:07:04 INFO - 2015-11-26 06:07:04,878 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.89 Median:166.89 stddev:0.00 (0.0%) 06:07:04 INFO - 2015-11-26 06:07:04,879 DEBUG : BROWSER_OUTPUT: Values: 166.9 06:07:04 INFO - 2015-11-26 06:07:04,879 DEBUG : BROWSER_OUTPUT: 06:07:04 INFO - 2015-11-26 06:07:04,879 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:185.63 Median:185.63 stddev:0.00 (0.0%) 06:07:04 INFO - 2015-11-26 06:07:04,879 DEBUG : BROWSER_OUTPUT: Values: 185.6 06:07:04 INFO - 2015-11-26 06:07:04,879 DEBUG : BROWSER_OUTPUT: 06:07:04 INFO - 2015-11-26 06:07:04,879 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.33 Median:279.33 stddev:0.00 (0.0%) 06:07:04 INFO - 2015-11-26 06:07:04,879 DEBUG : BROWSER_OUTPUT: Values: 279.3 06:07:04 INFO - 2015-11-26 06:07:04,879 DEBUG : BROWSER_OUTPUT: 06:07:04 INFO - 2015-11-26 06:07:04,879 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5191.71 Median:5191.71 stddev:0.00 (0.0%) 06:07:04 INFO - 2015-11-26 06:07:04,880 DEBUG : BROWSER_OUTPUT: Values: 5191.7 06:07:04 INFO - 2015-11-26 06:07:04,880 DEBUG : BROWSER_OUTPUT: 06:07:04 INFO - 2015-11-26 06:07:04,880 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1085.78 Median:1085.78 stddev:0.00 (0.0%) 06:07:04 INFO - 2015-11-26 06:07:04,880 DEBUG : BROWSER_OUTPUT: Values: 1085.8 06:07:04 INFO - 2015-11-26 06:07:04,880 DEBUG : BROWSER_OUTPUT: 06:07:04 INFO - 2015-11-26 06:07:04,880 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:713.78 Median:713.78 stddev:0.00 (0.0%) 06:07:04 INFO - 2015-11-26 06:07:04,880 DEBUG : BROWSER_OUTPUT: Values: 713.8 06:07:04 INFO - 2015-11-26 06:07:04,880 DEBUG : BROWSER_OUTPUT: 06:07:04 INFO - 2015-11-26 06:07:04,880 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12000.00 Median:12000.00 stddev:0.00 (0.0%) 06:07:04 INFO - 2015-11-26 06:07:04,880 DEBUG : BROWSER_OUTPUT: Values: 12000.0 06:07:04 INFO - 2015-11-26 06:07:04,880 DEBUG : BROWSER_OUTPUT: 06:07:04 INFO - 2015-11-26 06:07:04,880 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26025.64 Median:26025.64 stddev:0.00 (0.0%) 06:07:04 INFO - 2015-11-26 06:07:04,880 DEBUG : BROWSER_OUTPUT: Values: 26025.6 06:07:04 INFO - 2015-11-26 06:07:04,880 DEBUG : BROWSER_OUTPUT: 06:07:04 INFO - 2015-11-26 06:07:04,880 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:615.34 Median:615.34 stddev:0.00 (0.0%) 06:07:04 INFO - 2015-11-26 06:07:04,880 DEBUG : BROWSER_OUTPUT: Values: 615.3 06:07:04 INFO - 2015-11-26 06:07:04,881 DEBUG : BROWSER_OUTPUT: 06:07:04 INFO - 2015-11-26 06:07:04,881 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 06:07:04 INFO - 2015-11-26 06:07:04,881 DEBUG : BROWSER_OUTPUT: Values: 4840.6 06:07:04 INFO - 2015-11-26 06:07:04,881 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:07:04 INFO - 2015-11-26 06:07:04,881 DEBUG : BROWSER_OUTPUT: 06:07:05 INFO - 2015-11-26 06:07:05,723 INFO : Browser exited with error code: 0 06:07:05 INFO - 2015-11-26 06:07:05,735 INFO : Completed test v8_7 (00:00:39) 06:07:05 INFO - 2015-11-26 06:07:05,844 INFO : Completed test suite (00:20:10) 06:07:05 INFO - 2015-11-26 06:07:05,844 DEBUG : Working with test: dromaeo_css 06:07:05 INFO - 2015-11-26 06:07:05,844 DEBUG : Generating results file: dromaeo_css 06:07:05 INFO - 2015-11-26 06:07:05,845 DEBUG : Working with test: kraken 06:07:05 INFO - 2015-11-26 06:07:05,845 DEBUG : Generating results file: kraken 06:07:05 INFO - 2015-11-26 06:07:05,846 INFO : javascript benchmark 06:07:05 INFO - 2015-11-26 06:07:05,846 DEBUG : Working with test: v8_7 06:07:05 INFO - 2015-11-26 06:07:05,846 DEBUG : Generating results file: v8_7 06:07:05 INFO - 2015-11-26 06:07:05,846 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:07:05 INFO - 2015-11-26 06:07:05,911 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,43]] 06:07:05 INFO - 2015-11-26 06:07:05,912 DEBUG : process_Request line: dromaeo_css 7066.22 graph.html#tests=[[72,1,43]] 06:07:05 INFO - 2015-11-26 06:07:05,912 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:07:05 INFO - 2015-11-26 06:07:05,957 DEBUG : process_Request line: kraken 1520.90 graph.html#tests=[[232,1,43]] 06:07:05 INFO - 2015-11-26 06:07:05,958 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:07:05 INFO - 2015-11-26 06:07:05,994 DEBUG : process_Request line: v8_7 21303.15 graph.html#tests=[[230,1,43]] 06:07:05 INFO - 2015-11-26 06:07:05,996 INFO : javascript benchmark 06:07:06 INFO - 2015-11-26 06:07:05,999 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3226.6773322667727, 3264.102371847743, 3293.2425542125325, 3314.3205642843213, 3314.718248948839, 5360.142377219646, 5495.697736624489, 5497.0, 5543.113101903697, 5569.582281328906, 5391.406945236021, 5434.972825135868, 5447.073997420438, 5525.834224973257, 5552.305961754781, 4972.0801651694555, 5002.12462819007, 5008.2738002989545, 5107.412647545524, 5148.716820574867, 169.8342742503087, 4884.902301953959, 5010.69935803853, 5012.72430016349, 5043.823466178685, 4964.826231081913, 5032.748362581856, 5036.1438555445475, 5053.519915608012, 5096.847094587169, 5050.065737838475, 5062.038212739568, 5082.1360368737405, 5100.591952643779, 5157.819476318348, 4896.192128298826, 4988.201887697951, 4998.925016124761, 5014.59883209346, 5091.032703786269, 5216.947830521684, 5220.4779522047875, 5240.947590524084, 5252.23842542833, 5337.786488540453, 5933.910991335133, 6014.729337179838, 6020.1872747179, 6028.698565071729, 6034.125051867499, 5868.530517558628, 5875.059990401515, 6009.248843894513, 6047.455728984369, 6056.5457590680435, 5663.178839068353, 5675.5743319250805, 5675.886482270332, 5680.261565996394, 5682.460166284196, 6087.086936959491, 6091.42131497507, 6144.41628045335, 6146.416090471398, 6147.415995480422, 5738.254026976467, 5771.826845194651, 5854.414558544111, 5901.675407852524, 5958.851028724316, 5759.942400576025, 5875.29496460419, 5907.881842363128, 5959.67221802805, 5998.070299103646, 5890.793822216202, 5897.823065308047, 5899.410058994066, 5907.763689452459, 5919.496842768327, 5749.338826034976, 5843.065109582446, 5903.498202652822, 5993.580449368503, 6092.664903430354, 4608.907821843544, 4611.976940115278, 4623.491415944243, 4623.768811559408, 4670.206064968964, 4008.0581063450068, 4113.465249517603, 4125.91748165035, 4127.876163715094, 4182.351735481006, 5581.0, 5645.294338207724, 5646.604737668324, 5703.657780533182, 5766.971165144148, 6022.819315420545, 6045.486133678599, 6056.6971651418135, 6057.7879774207695, 6059.4546490815155, 5678.0915053591225, 5704.80033198836, 5707.143928410772, 5707.571932105108, 5782.10377391505, 5117.8720531986255, 5155.9742201288755, 5200.63595548308, 5200.973995130076, 5248.134057880483, 6111.297200821962, 6114.419130182626, 6170.753169873245, 6186.81439556814, 6200.968995154995, 5833.737481813244, 5954.106883967266, 5981.132735753378, 6018.187544681499, 6039.667818270037, 6059.545534084873, 6112.384178539333, 6128.167033931239, 6129.358790882775, 6156.846078847885, 5916.112583112567, 5923.7926672566255, 5924.844655292349, 5925.466707996301, 5944.791932282349, 5867.413258674269, 6007.729652165575, 6009.128676341993, 6020.4280593343565, 6048.153258543719, 5751.051076572318, 5760.308762948473, 5831.212786273799, 5837.591368604157, 5892.234009578728, 5840.8247752567495, 5893.705314734332, 5918.408159184047, 5928.318243401892, 5936.673482958478, 5620.606557540933, 5724.599278050497, 5748.913766293593, 5803.390643982321, 5806.970965145148, 5700.802831405451, 5718.742656580381, 5740.425957404226, 5749.252597162342, 5793.449622285876, 5663.969538315029, 5706.029974904359, 5707.771689132388, 5710.143478478261, 5714.485696287354], "unit": "ms", "name": "dojo.html", "value": 5451.157704933099}, {"lowerIsBetter": false, "replicates": [542.1569459490494, 578.2078552383235, 580.4340767751443, 583.0583607473931, 633.4140919649324, 31139.688603113962, 41227.0, 41442.58557414425, 42007.36988945164, 42008.789956050256, 44349.0, 44700.3294950576, 44951.0, 45163.54836451635, 45164.774176129074, 8647.43791653542, 8830.0, 8837.248833849131, 8857.291416686681, 8894.0, 8566.742997710047, 8617.784555386103, 8669.913300867005, 8679.609417576192, 8683.522406267653, 8636.568171591427, 8759.386842920998, 8763.693270735526, 8767.167119123673, 8767.693130740425, 8580.485170889766, 8692.304615630766, 8736.956315218447, 8737.0, 8744.125587441269, 8556.572171391468, 8642.913570864273, 8645.135486451367, 8677.913220867773, 8703.303735701129, 40604.59395406038, 40704.0, 41000.0, 41028.589714102774, 41228.1754364914, 3136.1218858719594, 3203.0, 3232.87068517259, 3389.2204792897774, 3527.876524321636, 29626.25934351637, 29706.554401683992, 30321.848390758125, 30727.692723072705, 30988.22529436782, 3864.7487913285545, 3906.003968987918, 3932.508436445444, 3995.900102497432, 4010.278149933011, 263556.0, 264153.0, 265449.0, 265904.0, 451145.0, 60116.398836011955, 60621.0, 61058.0, 61163.0, 61237.38762612406, 64038.0, 64557.67721161364, 64762.35237647658, 64965.67517162478, 65249.347506524326, 14991.625209369853, 15190.848091519165, 15204.16377099248, 15225.695486090217, 15235.314410851317, 11441.256318339281, 11590.88409115898, 11651.65045048633, 11660.650180494598, 11673.299602023906, 20443.284485042954, 20656.58686826285, 20682.58634827295, 20685.482862928548, 20778.584428311347, 8126.878096828552, 8326.333893288522, 8399.8320033599, 8408.159184081544, 8435.367347448966, 958.2382006305004, 999.0409207161207, 1029.8300780371305, 1078.0513148429488, 1079.8542196803487, 28534.14397568035, 28996.565051524245, 29040.564391534142, 29567.26081847971, 29633.111006669835, 1061.888501707325, 1063.5852017713098, 1082.7563798145354, 1155.9479823407967, 1177.9293242405483, 15022.849771502364, 15024.248787560578, 15224.923875380773, 15409.152496612794, 15490.845091548941, 326409.0, 330635.0, 331974.0, 332468.0, 333022.0, 52459.73770131201, 52695.73652131715, 52887.47112528903, 53174.734126329895, 53257.467425326024, 1325.5029363988504, 1327.157255142972, 1334.166146158651, 1334.7597432462055, 1347.9595612131652, 27503.724962750115, 28488.57267141036, 28850.27874303159, 28873.0, 28881.855590721912, 47064.529354706014, 47307.763461182476, 47308.76345618388, 47314.763426182646, 47635.0, 314936.0, 315595.0, 316548.0, 318609.0, 319986.0, 28280.575791363557, 28886.42227155403, 31627.52558711575, 31817.5227371585, 32233.194170144994, 41639.0, 41992.580074198864, 42030.36954445625, 42994.35508467438, 43140.568594313656, 35691.57987713871, 35792.46311305281, 36593.81703091574, 36774.63225367819, 36803.81598091992, 35911.64088359083, 39407.40888886726, 43103.27586896489, 43119.56880431156, 43844.935605645536, 37093.81453092717, 37770.62229377781, 37950.810245948596, 38152.0, 38249.0, 32093.358132836747, 32712.672873270963, 34323.14192145217, 34475.13812154716, 34835.47746783851, 25398.238052858444, 26605.0, 26648.33379165459, 26649.33376665599, 26748.732512674625], "unit": "ms", "name": "ext.html", "value": 18955.014601004197}, {"lowerIsBetter": false, "replicates": [354.16063928489467, 357.4923608475964, 362.5812186924102, 363.66542780641805, 366.3277885080876, 14090.788638170436, 14656.41374345025, 16576.0, 17106.5723356916, 17907.55231119224, 16440.0, 16456.588585285375, 16610.837241393107, 17178.74231886523, 17196.312147514116, 5312.096943519601, 5426.131818908975, 5470.2888624478965, 5480.424555421683, 5485.369182543998, 5438.428964958682, 5467.316585426822, 5494.313210848644, 5537.501624853762, 5551.72241387931, 5389.757460914268, 5439.292891924064, 5450.836474905759, 5456.181572764097, 5482.068048431776, 5352.95617354616, 5409.675419474844, 5449.346078470569, 5450.373207081197, 5464.23500709899, 4829.0, 4840.782164802592, 4854.004928989549, 4876.585490233334, 4882.902341953177, 20786.896065519577, 20879.164833406645, 21143.259985900575, 21336.0, 21364.0, 2177.292379976498, 2210.613142700021, 2211.701420308254, 2284.7486776454575, 2310.8266879984067, 12815.743685126245, 12992.870071299261, 13028.283444410554, 13265.336733163304, 13311.534096306583, 1691.3741915491223, 1789.2038043070838, 1795.3267524678245, 1843.419322913278, 1867.7478540397008, 58978.70510647462, 60843.08735368973, 61944.69027654878, 62261.37738622601, 62586.37413625851, 9590.184834289024, 9712.562934667956, 9857.55640996157, 9871.40771553709, 9902.158316543175, 12744.171628844095, 12760.0, 12763.872361276453, 13279.73440531184, 13360.131591446525, 7227.1327440707455, 7403.444741644398, 7409.8147546311775, 7452.105747310357, 7477.588732619758, 7191.065161528968, 7209.747658831916, 7244.130704315516, 7248.492605517563, 7253.165885923081, 4262.616364527207, 4318.417013703172, 4329.480462344476, 4338.783060846945, 4356.847510337123, 1748.4841971618403, 1773.565476458276, 1778.9555261118576, 1784.8036715961234, 1788.3472532525795, 926.2682480840195, 952.2286947572488, 995.8456439251928, 998.0684148588131, 1082.9187810914214, 16290.0, 16295.592610184603, 16811.915940420466, 17455.563610909576, 17646.647067058588, 933.3746389918771, 967.6758285974278, 969.8690676758547, 975.1370037516849, 1008.2790804574764, 9553.474558899326, 9723.124918757205, 9836.114318563516, 9839.409635421898, 10000.699979000641, 353807.0, 364331.0, 614170.0, 631097.0, 646213.0, 73789.0, 76054.0, 78718.0, 79938.6003069981, 80134.59932700415, 1126.099120703434, 1133.586241022021, 1213.8057910734417, 1224.6509744722712, 1251.5306759965013, 14661.706765864836, 15301.775857931561, 15447.5365739028, 15558.611034724223, 15874.285657145225, 26723.866380667972, 31155.688443116185, 32104.35791284208, 35523.64476355203, 35568.28863422769, 44124.11751765011, 44560.331595025455, 44678.77660611806, 45143.77428112838, 45490.0, 9201.0, 9856.655017074369, 9908.851367229354, 9910.702678919644, 9925.3548519346, 17612.383566575107, 17654.558636034202, 17919.73120403169, 18125.546861328574, 18204.089795509906, 14584.416623334948, 14822.925885370503, 15109.773353399927, 15124.621884452978, 15137.621559461102, 13825.930870345584, 13918.652033699238, 14477.420903164177, 14862.0, 15109.924450377677, 14526.418943242152, 14583.854161458674, 14701.411943522568, 14799.852001479847, 15064.0, 9649.951750241204, 9701.417914925127, 9858.654947076819, 9886.85169722469, 9953.552090155816, 13952.16287022782, 14609.92695036518, 14678.192699401636, 14949.0, 15047.322870470636], "unit": "ms", "name": "jquery.html", "value": 8973.594353791741}, {"lowerIsBetter": false, "replicates": [1530.6556024894394, 1625.520471460919, 1682.1084825042728, 1751.045680104343, 1779.1637930172822, 6971.337722916315, 7071.964640176792, 7712.0745510538745, 7792.376609871204, 7914.366850651942, 7458.589777562232, 7533.284337987896, 7668.194839541851, 7692.230776922305, 7731.381489480842, 2263.1400067974228, 2322.814174866015, 2325.500017496238, 2329.324495896188, 2336.4626135988733, 2316.143027079983, 2351.8471299365488, 2360.775726305998, 2361.5631108244943, 2374.15717420315, 2310.4801419680607, 2311.456807650201, 2355.5759963206615, 2357.163207061494, 2363.7518060603647, 2282.771722827704, 2293.392251053464, 2294.357579877637, 2322.129201549419, 2334.0197117210814, 2322.6283794592955, 2345.8006069484113, 2357.1749887539395, 2358.622620380731, 2365.2431222008963, 7692.307692307663, 7746.9225307746765, 7765.611719414007, 7775.766726998199, 7794.259545343183, 4793.568578827887, 4810.927836082462, 4867.805287788484, 4911.45973942866, 4922.064807686528, 3156.0, 3159.494480883048, 3188.6333071696818, 3225.2904361040532, 3232.1596384940085, 991.7223177510309, 993.6323560282669, 1014.7209517382705, 1035.2132379391642, 1064.69123954054, 10367.326123802004, 10379.325343852626, 10379.84430233547, 10416.114630256438, 10448.738781530446, 4162.292410290258, 4213.2837417639075, 4225.535191128974, 4230.238557059759, 4329.069250111178, 8520.14798520143, 8589.957050214709, 8598.914010859937, 8600.0, 8610.138986101465, 4724.126036683225, 4744.24092145262, 4745.667803253739, 4756.0, 4760.119377915027, 4251.426057482262, 4307.676924230633, 4310.331898555729, 4321.956780432219, 4363.912721745611, 3535.946960795539, 3537.7523573349617, 3561.2521370511963, 3570.357335679603, 3573.4818451324413, 1102.3771569063454, 1107.4960892793758, 1112.7218195451137, 1117.983230251547, 1121.0695123047851, 1125.189863298424, 1125.2517076144436, 1129.9548018079186, 1283.0697744135614, 1358.9456421743216, 7135.286471352823, 7175.354218120394, 7211.242819503981, 7216.314450127229, 7222.674979625929, 458.45672877639436, 460.8433132734819, 460.93777340058665, 465.62051927678834, 510.5328624308716, 5692.686902220417, 5734.971325143348, 5736.827895163152, 5740.253767010262, 5744.425557444306, 8818.691345802867, 9105.908940910638, 9115.772105697411, 9242.168204861595, 9280.0, 9026.684066057787, 9029.503377314179, 9032.909670903338, 9043.140901614335, 9077.500737459372, 7012.158540975013, 7034.261402552761, 7280.453965952574, 7355.926440735631, 7420.146683131401, 7237.8914316286355, 7285.744998925013, 7298.96350518244, 7309.342159205485, 7342.48602597813, 8079.192080791874, 8142.8778568322705, 8180.0, 8342.708005219789, 8382.0, 8497.87253191215, 8533.701320453754, 8587.95706021466, 8598.355123365647, 8635.87046194295, 6642.966785166043, 6779.389854913082, 6823.93176068233, 6872.140982377203, 6928.861422771617, 7261.491695581259, 7325.560466372035, 7351.632418379167, 7356.080489938758, 7399.48203625741, 6729.2934241903895, 6795.45636349081, 6880.311968803079, 7013.649317534205, 7019.438444924496, 6687.866242675022, 6688.030235615905, 6762.966185169043, 6844.93155068443, 6889.621070841152, 6772.55978361405, 6783.694733737092, 6799.830004249933, 6814.386705196556, 7010.334018268256, 6254.28075771274, 6262.874742505033, 6304.369563043659, 6306.590071645328, 6309.148264984352, 6360.427561519486, 6361.346050026934, 6366.172397588284, 6384.361563843764, 6421.229452465547], "unit": "ms", "name": "mootools.html", "value": 4369.497131617654}, {"lowerIsBetter": false, "replicates": [336.4885374231169, 337.41626985315406, 341.10800257327065, 346.9791812491249, 347.156409923885, 6218.7201575929075, 6230.190075290206, 6239.906401403977, 6333.936660633398, 6381.936180638192, 6154.169187159732, 6215.3473885241965, 6227.43953044226, 6229.844253893643, 6237.625742455444, 4752.097101550703, 4753.405824271966, 4770.165221086313, 4782.521747825224, 4790.377250957371, 4780.569748722614, 4788.97605511972, 4790.9520904791025, 4794.113089078514, 4797.808087676489, 4658.767061646921, 4724.480307166209, 4747.620190384761, 4751.952480475185, 4752.144613969502, 4704.788284527187, 4736.692115012514, 4743.573078422941, 4744.098621261949, 4745.786439610226, 4091.161311931047, 4097.795110244506, 4102.589741025873, 4104.97375656086, 4106.137711080677, 5939.881202375971, 5960.552958528127, 5960.582759206857, 5968.880622387528, 5980.192673989, 5362.51737343641, 5487.5609951203805, 5493.560515158778, 5500.1749737539685, 5513.062779327524, 6601.141851559267, 6649.60102393858, 6667.433268172211, 6671.633060181688, 6675.933240667579, 4447.621952134073, 4507.4365704286965, 4508.887277818048, 4532.093581283756, 4533.659975501818, 6630.171228596426, 6668.9666551667415, 6740.730370785162, 6752.628605426748, 6762.492813039042, 4573.6569757268335, 4596.4254468191475, 4598.724076555351, 4600.677952543357, 4602.815887364535, 4657.6273898088075, 4660.533946605312, 4660.720356778605, 4667.976660116746, 4675.111728771522, 5410.8917821643345, 5420.403755586883, 5423.810166644149, 5432.91850622241, 5439.347278326547, 6426.871462570816, 6501.902471462932, 6563.343665633399, 6568.835779105465, 6583.177102862142, 1630.37230666192, 1647.8434548717853, 1666.30015393535, 1666.8083170435555, 1668.9165541723107, 1099.5766629847567, 1104.928179668319, 1111.394290111891, 1113.3820729495046, 1115.6206889657556, 6271.6550589718, 6375.904361434582, 6391.488680905516, 6392.456641185551, 6399.296077431479, 6716.925291953264, 6782.2200446948245, 6788.830279243059, 6806.353396427331, 6817.216020157646, 6255.46828519581, 6352.523560732963, 6368.745250189941, 6384.52116091295, 6402.327755585597, 3714.832832522543, 3718.200586873835, 3721.330160571069, 3722.2927643748144, 3723.646253605903, 7058.894116588255, 7064.505484616027, 7071.7878463646175, 7074.893876591856, 7080.150381954096, 6945.027696122547, 6991.300869912968, 6999.370056694922, 7018.719251229996, 7019.333163349474, 7162.570245785166, 7222.963885180646, 7226.096737907762, 7231.349178573953, 7236.204017558064, 6876.553024053421, 6914.101166848278, 6919.481038922, 6948.722051117898, 6954.0, 6772.424343930624, 6806.285340039422, 6819.215790184192, 6879.036934829228, 6886.035954966409, 7172.211056783645, 7193.640317984185, 7200.855982880208, 7200.8919866201, 7218.747343843151, 4642.210824159968, 4675.345451636706, 4686.578207961164, 4687.554682305175, 4692.554207350297, 6750.223724271772, 6919.0, 6925.515213935178, 6968.477364197807, 6978.860422791414, 6703.832404189934, 6712.261651218264, 6728.192616886005, 6736.6968486417245, 6771.119754431892, 6733.595984240961, 6754.257031726408, 6757.020232066225, 6758.121444212221, 6758.594484330956, 6472.838179045561, 6561.311062338385, 6561.40947314744, 6564.376384243489, 6575.473962082926, 6767.323267673193, 6776.796696099125, 6779.0, 6782.491313151435, 6804.897926531205, 6520.967395163184, 6529.412352888263, 6570.047343135314, 6613.735450581923, 6613.966930165318], "unit": "ms", "name": "prototype.html", "value": 4990.906679399783}, {"lowerIsBetter": false, "replicates": [765.1353970013885, 835.0897521701345, 848.936329775267, 855.7304449098538, 856.8886044814174, 11356.602518911839, 11644.708882277948, 11658.242214256068, 11679.883201167986, 11703.765924681511, 11860.52557897683, 12253.958413534838, 12290.201136926093, 12311.569095081673, 12386.256824590531, 817.9918200818005, 829.7261903571807, 830.9667613295461, 832.7585000349922, 835.8913341265627, 831.580052073702, 837.7696133563257, 840.0507426607925, 840.1472505407016, 840.8570543007672, 865.1996902864856, 876.1939016105199, 876.7150676030284, 879.0681877210178, 884.5886662701843, 867.1631875240391, 870.1342164546254, 870.9738707838775, 874.5233847553098, 875.92992560595, 639.6290151712043, 640.3052687833706, 645.1516256123183, 645.3353046362255, 645.5223134880155, 12056.276623402624, 12069.758604827943, 12141.635750927486, 12156.51373945041, 12176.452059657337, 10446.582136714522, 10677.1992100592, 10732.892671073345, 10743.086837618734, 11033.889661103367, 9491.857622135674, 9626.518674066268, 9696.70909872705, 9696.854547181798, 9824.0, 3426.9719084274616, 3449.258409441982, 3455.9308813823836, 3472.73954453417, 3481.268933523963, 40460.19079618391, 40463.988400290225, 43382.56617433817, 45414.31878521792, 48975.265371019465, 8841.557922103997, 8850.982137054321, 8892.555372231363, 8905.465672059698, 8933.06680358719, 18108.0, 18146.727799083023, 18155.0, 18179.363722269656, 18203.908980455013, 7035.050268213828, 7513.774586762406, 7516.5114267572435, 7573.053368328959, 7922.405819563556, 4090.570490098556, 4154.169166166779, 4190.874273771792, 4209.957900421018, 4351.673624478177, 3173.984130079335, 3209.8555564999633, 3212.823294718766, 3214.1321843102232, 3235.9352812943607, 534.4388392188187, 540.1735344922275, 544.7548603128608, 551.0, 558.034600141757, 515.4150039704962, 531.8643745844785, 548.4159370270648, 556.3629644057531, 650.5056157320375, 8005.119436861941, 8011.879821802678, 8065.90421800935, 8070.515769053875, 8379.539125348165, 559.712601472717, 589.8260013296087, 644.8900867931004, 670.8255853478131, 695.5965539986795, 3785.716071294609, 3818.7326887118193, 3830.751001184914, 3865.8260378283044, 3870.4369976113016, 59807.70096149491, 60737.69631151816, 60819.0, 60868.0, 60977.39022609717, 52738.73630631822, 52871.735641321546, 53552.73223633857, 53567.19649205265, 53649.0, 46719.29921051187, 47205.76397117993, 47596.7620161897, 47774.76112619484, 48631.5136848627, 8054.879176812469, 8208.425410221465, 8647.437916535406, 8649.567521623767, 8747.91252087471, 35717.0, 36193.27613447663, 36806.26387472182, 37286.62713372832, 37342.813285934484, 46259.537404625524, 46520.53479465162, 49749.75125124351, 49852.25221621751, 51852.0, 10727.678169654922, 10785.838212426663, 10959.958803913616, 11076.944615276872, 11477.770444590895, 11426.885731142582, 11543.134264929995, 11718.765624687629, 11768.235064720766, 12101.636950891487, 10659.94670026645, 10684.57261709523, 10869.945650271698, 10984.890151098707, 11009.834852477059, 10895.455227238448, 11167.553297867995, 11430.828537571777, 11496.597619083292, 11625.825612615987, 10324.43215623148, 10344.586216551254, 10427.0, 10604.045635892806, 10664.306820056672, 9472.763180920532, 9602.615895364108, 9690.12788849007, 9696.62687189246, 10181.796364072825, 8738.475691458532, 8873.645054198018, 9093.636254549743, 9285.72142835716, 9511.191548718436], "unit": "ms", "name": "yui.html", "value": 6155.940771885032}], "name": "dromaeo_css", "value": 7066.222384838509}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 104.0, 111.0, 111.0, 111.0, 122.0, 111.0, 111.0, 110.0, 112.0], "unit": "ms", "name": "ai-astar", "value": 111.7}, {"lowerIsBetter": true, "replicates": [154.0, 125.0, 153.0, 162.0, 127.0, 174.0, 162.0, 131.0, 166.0, 132.0], "unit": "ms", "name": "audio-beat-detection", "value": 148.6}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 162.0, 165.0, 161.0, 163.0, 162.0, 164.0, 172.0, 163.0], "unit": "ms", "name": "audio-dft", "value": 163.3}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 72.0, 76.0, 72.0, 127.0, 72.0, 79.0, 71.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 78.3}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 137.0, 94.0, 93.0, 93.0, 141.0, 94.0, 94.0, 132.0], "unit": "ms", "name": "audio-oscillator", "value": 106.5}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 110.0, 112.0, 112.0, 111.0, 110.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.4}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 146.0, 146.0, 147.0, 147.0, 147.0, 145.0, 147.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.6}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 96.0, 96.0, 100.0, 98.0, 97.0, 96.0, 96.0, 97.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.9}, {"lowerIsBetter": true, "replicates": [63.0, 61.0, 70.0, 61.0, 62.0, 61.0, 61.0, 61.0, 60.0, 60.0], "unit": "ms", "name": "json-parse-financial", "value": 62.0}, {"lowerIsBetter": true, "replicates": [74.0, 71.0, 70.0, 71.0, 71.0, 70.0, 70.0, 70.0, 70.0, 70.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 70.7}, {"lowerIsBetter": true, "replicates": [84.0, 86.0, 85.0, 85.0, 85.0, 93.0, 88.0, 84.0, 84.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.9}, {"lowerIsBetter": true, "replicates": [126.0, 123.0, 119.0, 124.0, 125.0, 135.0, 121.0, 114.0, 129.0, 125.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 124.1}, {"lowerIsBetter": true, "replicates": [140.0, 150.0, 156.0, 157.0, 147.0, 138.0, 150.0, 151.0, 149.0, 150.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 148.8}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 65.0, 66.0, 73.0, 67.0, 66.0, 67.0, 66.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.1}], "name": "kraken", "value": 1520.9}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.88918558077435], "unit": "score", "name": "Richards", "value": 211.52958400000006}, {"lowerIsBetter": false, "replicates": [185.63207722294413], "unit": "score", "name": "DeltaBlue", "value": 356.1776659999999}, {"lowerIsBetter": false, "replicates": [279.3296089385475], "unit": "score", "name": "Encrypt", "value": 952.9279799999998}, {"lowerIsBetter": false, "replicates": [5191.709844559586], "unit": "score", "name": "Decrypt", "value": 51.270392215568904}, {"lowerIsBetter": false, "replicates": [1085.7763300760043], "unit": "score", "name": "RayTrace", "value": 681.5298690000002}, {"lowerIsBetter": false, "replicates": [713.7758743754462], "unit": "score", "name": "Earley", "value": 933.7146630000002}, {"lowerIsBetter": false, "replicates": [12000.0], "unit": "score", "name": "Boyer", "value": 55.53858333333334}, {"lowerIsBetter": false, "replicates": [26025.641025641027], "unit": "score", "name": "RegExp", "value": 35.003364532019674}, {"lowerIsBetter": false, "replicates": [615.3374233128834], "unit": "score", "name": "Splay", "value": 132.43303090727818}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 21303.154837312642}]} 06:07:06 INFO - RETURN: dromaeo_css: 7066.22 06:07:06 INFO - RETURN: kraken: 1520.90 06:07:06 INFO - RETURN: v8_7: 21303.15 06:07:06 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,43]]", "result": "21303.15"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,43]]", "result": "1520.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,43]]", "result": "7066.22"}}} 06:07:06 INFO - Return code: 0 06:07:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 06:07:06 INFO - # TBPL SUCCESS # 06:07:06 INFO - Running post-action listener: _resource_record_post_action 06:07:06 INFO - Running post-run listener: _resource_record_post_run 06:07:07 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 13381632; Write bytes: 410804224; Read time: 5340; Write time: 275312 06:07:07 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 13901824; Read time: 0; Write time: 2068 06:07:07 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 11644928; Write bytes: 396902400; Read time: 5292; Write time: 273244 06:07:07 INFO - Running post-run listener: _upload_blobber_files 06:07:07 INFO - Blob upload gear active. 06:07:07 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 06:07:07 INFO - Copying logs to upload dir... 06:07:07 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1270.529678 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2015-11-26 06:07:07.213943) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-26 06:07:07.218010) ========= 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=5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009992 build_url: 'https://queue.taskcluster.net/v1/task/v9b5j_4HT8KXPfpPiSwlVQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-26 06:07:07.271297) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 06:07:07.271824) ========= 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=5752742a61d0f534093fa7a9000001cb-1448545430.813606-1035456742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004417 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 06:07:07.322671) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-26 06:07:07.323024) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-26 06:07:07.323321) ========= ========= Total master_lag: 0.19 =========