builder: b2g-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-021 starttime: 1451837310.48 results: success (0) buildid: 20160103053120 builduid: 38370049ebe046baa239b79d47da2b1c revision: d8d8ce02431c2fbfd744ee0d01837dbe81f446ff ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:30.478442) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:30.478931) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:30.479364) ========= 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=ccb4611811c763f2fad71465000001de-1451820701.737861-850851793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006332 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:30.508176) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:30.508727) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:30.522507) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:30.522952) ========= 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=ccb4611811c763f2fad71465000001de-1451820701.737861-850851793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004578 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:30.573046) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:30.573410) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:30.573759) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:30.574048) ========= 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=ccb4611811c763f2fad71465000001de-1451820701.737861-850851793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-03 08:08:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.5M=0s 2016-01-03 08:08:31 (43.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.096930 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:30.683658) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:30.683995) ========= 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=ccb4611811c763f2fad71465000001de-1451820701.737861-850851793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021430 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:30.734393) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:30.734762) ========= bash -c 'python archiver_client.py mozharness --repo integration/b2g-inbound --rev d8d8ce02431c2fbfd744ee0d01837dbe81f446ff --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/b2g-inbound --rev d8d8ce02431c2fbfd744ee0d01837dbe81f446ff --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=ccb4611811c763f2fad71465000001de-1451820701.737861-850851793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-03 08:08:31,476 truncating revision to first 12 chars 2016-01-03 08:08:31,476 Setting DEBUG logging. 2016-01-03 08:08:31,476 attempt 1/10 2016-01-03 08:08:31,476 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/d8d8ce02431c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-03 08:08:31,929 unpacking tar archive at: b2g-inbound-d8d8ce02431c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.738134 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:31.485750) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:31.486069) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:31.496341) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:31.496663) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-03 08:08:31.497103) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-01-03 08:08:31.497395) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'B2G-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'B2G-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ccb4611811c763f2fad71465000001de-1451820701.737861-850851793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:08:32 INFO - MultiFileLogger online at 20160103 08:08:32 in /builds/slave/test-pgo 08:08:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound 08:08:32 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 08:08:32 INFO - {'append_to_log': False, 08:08:32 INFO - 'base_work_dir': '/builds/slave/test-pgo', 08:08:32 INFO - 'blob_upload_branch': 'B2G-Inbound', 08:08:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 08:08:32 INFO - 'branch': 'B2G-Inbound', 08:08:32 INFO - 'buildbot_json_path': 'buildprops.json', 08:08:32 INFO - 'config_files': ('talos/linux_config.py',), 08:08:32 INFO - 'default_actions': ('clobber', 08:08:32 INFO - 'read-buildbot-config', 08:08:32 INFO - 'download-and-extract', 08:08:32 INFO - 'populate-webroot', 08:08:32 INFO - 'create-virtualenv', 08:08:32 INFO - 'install', 08:08:32 INFO - 'run-tests'), 08:08:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:08:32 INFO - 'download_minidump_stackwalk': True, 08:08:32 INFO - 'download_symbols': 'ondemand', 08:08:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:08:32 INFO - 'tooltool.py': '/tools/tooltool.py', 08:08:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:08:32 INFO - '/tools/misc-python/virtualenv.py')}, 08:08:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:08:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:08:32 INFO - 'installer_path': 'installer.exe', 08:08:32 INFO - 'log_level': 'info', 08:08:32 INFO - 'log_name': 'talos', 08:08:32 INFO - 'log_to_console': True, 08:08:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:08:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:08:32 INFO - 'opt_config_files': (), 08:08:32 INFO - 'pip_index': False, 08:08:32 INFO - 'sps_profile': False, 08:08:32 INFO - 'sps_profile_interval': 0, 08:08:32 INFO - 'suite': 'dromaeojs', 08:08:32 INFO - 'system_bits': '32', 08:08:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:08:32 INFO - 'title': 'talos-linux64-ix-021', 08:08:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:08:32 INFO - 'use_talos_json': True, 08:08:32 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 08:08:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:08:32 INFO - 'work_dir': 'build'} 08:08:32 INFO - ##### 08:08:32 INFO - ##### Running clobber step. 08:08:32 INFO - ##### 08:08:32 INFO - Running pre-action listener: _resource_record_pre_action 08:08:32 INFO - Running main action method: clobber 08:08:32 INFO - rmtree: /builds/slave/test-pgo/build 08:08:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 08:08:32 INFO - Running post-action listener: _resource_record_post_action 08:08:32 INFO - ##### 08:08:32 INFO - ##### Running read-buildbot-config step. 08:08:32 INFO - ##### 08:08:32 INFO - Running pre-action listener: _resource_record_pre_action 08:08:32 INFO - Running main action method: read_buildbot_config 08:08:32 INFO - Using buildbot properties: 08:08:32 INFO - { 08:08:32 INFO - "project": "", 08:08:32 INFO - "product": "firefox", 08:08:32 INFO - "script_repo_revision": "production", 08:08:32 INFO - "builddir": "b2g-inbound_ubuntu64_hw_test-dromaeojs", 08:08:32 INFO - "repository": "", 08:08:32 INFO - "buildername": "Ubuntu HW 12.04 x64 b2g-inbound pgo talos dromaeojs", 08:08:32 INFO - "buildid": "20160103053120", 08:08:32 INFO - "pgo_build": "True", 08:08:32 INFO - "basedir": "/builds/slave/test-pgo", 08:08:32 INFO - "buildnumber": 176, 08:08:32 INFO - "slavename": "talos-linux64-ix-021", 08:08:32 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:08:32 INFO - "platform": "ubuntu64_hw", 08:08:32 INFO - "scheduler": "tests-b2g-inbound-linux64-pgo-talos", 08:08:32 INFO - "branch": "b2g-inbound", 08:08:32 INFO - "repo_path": "integration/b2g-inbound", 08:08:32 INFO - "revision": "d8d8ce02431c2fbfd744ee0d01837dbe81f446ff", 08:08:32 INFO - "stage_platform": "linux64-pgo", 08:08:32 INFO - "builduid": "38370049ebe046baa239b79d47da2b1c", 08:08:32 INFO - "slavebuilddir": "test" 08:08:32 INFO - } 08:08:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 08:08:32 INFO - Running post-action listener: _resource_record_post_action 08:08:32 INFO - ##### 08:08:32 INFO - ##### Running download-and-extract step. 08:08:32 INFO - ##### 08:08:32 INFO - Running pre-action listener: _resource_record_pre_action 08:08:32 INFO - Running main action method: download_and_extract 08:08:32 INFO - mkdir: /builds/slave/test-pgo/build/tests 08:08:32 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')]} 08:08:32 INFO - https://queue.taskcluster.net/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 08:08:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/test_packages.json 08:08:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/test_packages.json 08:08:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 08:08:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 08:08:33 INFO - Downloaded 1302 bytes. 08:08:33 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 08:08:33 INFO - Using the following test package requirements: 08:08:33 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 08:08:33 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:08:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:08:33 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:08:33 INFO - u'jsshell-linux-x86_64.zip'], 08:08:33 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:08:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:08:33 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 08:08:33 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:08:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:08:33 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:08:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:08:33 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:08:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:08:33 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 08:08:33 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:08:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:08:33 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:08: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')]} 08:08:33 INFO - https://queue.taskcluster.net/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:08:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:08:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:08:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:08:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:08:34 INFO - Downloaded 23197960 bytes. 08:08:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 08:08:34 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:08:34 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 08:08:35 INFO - Return code: 0 08:08:35 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:08:35 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')]} 08:08:35 INFO - https://queue.taskcluster.net/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:08:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:08:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:08:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:08:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:08:40 INFO - Downloaded 23197960 bytes. 08:08:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 08:08:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:08:40 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 08:08:42 INFO - Return code: 0 08:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:42 INFO - https://queue.taskcluster.net/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:08:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 08:08:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 08:08:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 08:08:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:08:43 INFO - Downloaded 11132285 bytes. 08:08:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 08:08:43 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 08:08:43 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 08:08:43 INFO - Return code: 0 08:08:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:43 INFO - https://queue.taskcluster.net/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:08:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 08:08:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 08:08:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 08:08:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:08:45 INFO - Downloaded 54986683 bytes. 08:08:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 08:08:45 INFO - mkdir: /builds/slave/test-pgo/properties 08:08:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 08:08:45 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 08:08:45 INFO - Contents: 08:08:45 INFO - build_url:https://queue.taskcluster.net/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 08:08:45 INFO - Running post-action listener: _resource_record_post_action 08:08:45 INFO - Running post-action listener: set_extra_try_arguments 08:08:45 INFO - ##### 08:08:45 INFO - ##### Running populate-webroot step. 08:08:45 INFO - ##### 08:08:45 INFO - Running pre-action listener: _resource_record_pre_action 08:08:45 INFO - Running main action method: populate_webroot 08:08:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:08:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:08:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:08:45 INFO - '--tppagecycles', 08:08:45 INFO - '7'], 08:08:45 INFO - 'tests': ['tsvgm']}}, 08:08:45 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 08:08:45 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 08:08:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:08:45 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:08:45 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 08:08:45 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:08:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:08:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:45 INFO - 'talos_options': ['--e10s'], 08:08:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:08:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:45 INFO - 'tests': ['damp', 'tps']}, 08:08:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:45 INFO - 'talos_options': ['--e10s'], 08:08:45 INFO - 'tests': ['damp', 'tps']}, 08:08:45 INFO - 'g3': {'tests': ['dromaeo_dom']}, 08:08:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:08:45 INFO - 'other': {'tests': ['a11yr', 08:08:45 INFO - 'ts_paint', 08:08:45 INFO - 'tpaint', 08:08:45 INFO - 'sessionrestore', 08:08:45 INFO - 'sessionrestore_no_auto_restore']}, 08:08:45 INFO - 'other-e10s': {'talos_options': ['--e10s'], 08:08:45 INFO - 'tests': ['a11yr', 08:08:45 INFO - 'ts_paint', 08:08:45 INFO - 'tpaint', 08:08:45 INFO - 'sessionrestore', 08:08:45 INFO - 'sessionrestore_no_auto_restore']}, 08:08:45 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 08:08:45 INFO - 'tests': ['a11yr', 08:08:45 INFO - 'ts_paint', 08:08:45 INFO - 'tpaint', 08:08:45 INFO - 'sessionrestore', 08:08:45 INFO - 'sessionrestore_no_auto_restore']}, 08:08:45 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 08:08:45 INFO - 'tests': ['a11yr', 08:08:45 INFO - 'ts_paint', 08:08:45 INFO - 'tpaint', 08:08:45 INFO - 'sessionrestore', 08:08:45 INFO - 'sessionrestore_no_auto_restore']}, 08:08:45 INFO - 'other_l64': {'tests': ['a11yr', 08:08:45 INFO - 'ts_paint', 08:08:45 INFO - 'tpaint', 08:08:45 INFO - 'sessionrestore', 08:08:45 INFO - 'sessionrestore_no_auto_restore']}, 08:08:45 INFO - 'other_nol64': {'tests': ['a11yr', 08:08:45 INFO - 'ts_paint', 08:08:45 INFO - 'tpaint', 08:08:45 INFO - 'sessionrestore', 08:08:45 INFO - 'sessionrestore_no_auto_restore']}, 08:08:45 INFO - 'svgr': {'tests': ['tsvgx', 08:08:45 INFO - 'tsvgr_opacity', 08:08:45 INFO - 'tart', 08:08:45 INFO - 'tscrollx', 08:08:45 INFO - 'cart']}, 08:08:45 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 08:08:45 INFO - 'tests': ['tsvgx', 08:08:45 INFO - 'tsvgr_opacity', 08:08:45 INFO - 'tart', 08:08:45 INFO - 'tscrollx', 08:08:45 INFO - 'cart']}, 08:08:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:45 INFO - 'tests': ['tp5o']}, 08:08:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:45 INFO - 'talos_options': ['--e10s'], 08:08:45 INFO - 'tests': ['tp5o']}, 08:08:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:45 INFO - 'talos_options': ['--xperf_path', 08:08:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:08:45 INFO - 'tests': ['tp5n']}, 08:08:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:45 INFO - 'talos_options': ['--e10s', 08:08:45 INFO - '--xperf_path', 08:08:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:08:45 INFO - 'tests': ['tp5n']}}, 08:08:45 INFO - 'talos.zip': {'path': '', 08:08:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:08:45 INFO - Running post-action listener: _resource_record_post_action 08:08:45 INFO - ##### 08:08:45 INFO - ##### Running create-virtualenv step. 08:08:45 INFO - ##### 08:08:45 INFO - Running pre-action listener: _resource_record_pre_action 08:08:45 INFO - Running main action method: create_virtualenv 08:08:45 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 08:08:45 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 08:08:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 08:08:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:08:45 INFO - Using real prefix '/usr' 08:08:45 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 08:08:45 INFO - Installing distribute.............................................................................................................................................................................................done. 08:08:47 INFO - Installing pip.................done. 08:08:47 INFO - Return code: 0 08:08:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 08:08:47 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')]} 08:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:47 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')]} 08:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:47 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 0x7fc360c2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2921630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b2840>, '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 0x291f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291fb60>, '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': 'ccb4611811c763f2fad71465000001de-1451820701.737861-850851793', '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 08:08:47 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 08:08:47 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 08:08:47 INFO - Using env: {'DISPLAY': ':0', 08:08:47 INFO - 'HOME': '/home/cltbld', 08:08:47 INFO - 'LANG': 'en_US.UTF-8', 08:08:47 INFO - 'LANGUAGE': 'en_US:en', 08:08:47 INFO - 'LOGNAME': 'cltbld', 08:08:47 INFO - 'MAIL': '/var/mail/cltbld', 08:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:47 INFO - 'MOZ_NO_REMOTE': '1', 08:08:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:47 INFO - 'NO_EM_RESTART': '1', 08:08:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:08:47 INFO - 'PWD': '/builds/slave/test-pgo', 08:08:47 INFO - 'SHELL': '/bin/bash', 08:08:47 INFO - 'SHLVL': '1', 08:08:47 INFO - 'TERM': 'linux', 08:08:47 INFO - 'TMOUT': '86400', 08:08:47 INFO - 'USER': 'cltbld', 08:08:47 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1451820701.737861-850851793', 08:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:47 INFO - '_': '/tools/buildbot/bin/python'} 08:08:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:48 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 08:08:48 INFO - Cleaning up... 08:08:48 INFO - Return code: 0 08:08:48 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 08:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:48 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 0x7fc360c2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2921630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b2840>, '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 0x291f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291fb60>, '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': 'ccb4611811c763f2fad71465000001de-1451820701.737861-850851793', '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 08:08:48 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 08:08:48 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 08:08:48 INFO - Using env: {'DISPLAY': ':0', 08:08:48 INFO - 'HOME': '/home/cltbld', 08:08:48 INFO - 'LANG': 'en_US.UTF-8', 08:08:48 INFO - 'LANGUAGE': 'en_US:en', 08:08:48 INFO - 'LOGNAME': 'cltbld', 08:08:48 INFO - 'MAIL': '/var/mail/cltbld', 08:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:48 INFO - 'MOZ_NO_REMOTE': '1', 08:08:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:48 INFO - 'NO_EM_RESTART': '1', 08:08:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:08:48 INFO - 'PWD': '/builds/slave/test-pgo', 08:08:48 INFO - 'SHELL': '/bin/bash', 08:08:48 INFO - 'SHLVL': '1', 08:08:48 INFO - 'TERM': 'linux', 08:08:48 INFO - 'TMOUT': '86400', 08:08:48 INFO - 'USER': 'cltbld', 08:08:48 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1451820701.737861-850851793', 08:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:48 INFO - '_': '/tools/buildbot/bin/python'} 08:08:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:48 INFO - Downloading/unpacking psutil>=0.7.1 08:08:48 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 08:08:48 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 08:08:48 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 08:08:48 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 08:08:48 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 08:08:48 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 08:08:49 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 08:08:49 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 08:08:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 08:08:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:08:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:08:50 INFO - Installing collected packages: psutil 08:08:50 INFO - Running setup.py install for psutil 08:08:50 INFO - building 'psutil._psutil_linux' extension 08:08:50 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 08:08:50 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 08:08:50 INFO - building 'psutil._psutil_posix' extension 08:08:50 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 08:08:50 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 08:08:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:08:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:08:50 INFO - Successfully installed psutil 08:08:50 INFO - Cleaning up... 08:08:50 INFO - Return code: 0 08:08:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 08:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:50 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 0x7fc360c2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2921630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b2840>, '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 0x291f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291fb60>, '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': 'ccb4611811c763f2fad71465000001de-1451820701.737861-850851793', '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 08:08:50 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 08:08:50 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 08:08:50 INFO - Using env: {'DISPLAY': ':0', 08:08:50 INFO - 'HOME': '/home/cltbld', 08:08:50 INFO - 'LANG': 'en_US.UTF-8', 08:08:50 INFO - 'LANGUAGE': 'en_US:en', 08:08:50 INFO - 'LOGNAME': 'cltbld', 08:08:50 INFO - 'MAIL': '/var/mail/cltbld', 08:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:50 INFO - 'MOZ_NO_REMOTE': '1', 08:08:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:50 INFO - 'NO_EM_RESTART': '1', 08:08:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:08:50 INFO - 'PWD': '/builds/slave/test-pgo', 08:08:50 INFO - 'SHELL': '/bin/bash', 08:08:50 INFO - 'SHLVL': '1', 08:08:50 INFO - 'TERM': 'linux', 08:08:50 INFO - 'TMOUT': '86400', 08:08:50 INFO - 'USER': 'cltbld', 08:08:50 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1451820701.737861-850851793', 08:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:50 INFO - '_': '/tools/buildbot/bin/python'} 08:08:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:08:50 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 08:08:50 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 08:08:50 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 08:08:50 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 08:08:50 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 08:08:50 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 08:08:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:08:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 08:08:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:08:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:08:52 INFO - Installing collected packages: mozsystemmonitor 08:08:52 INFO - Running setup.py install for mozsystemmonitor 08:08:52 INFO - Successfully installed mozsystemmonitor 08:08:52 INFO - Cleaning up... 08:08:52 INFO - Return code: 0 08:08:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 08:08:52 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')]} 08:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:52 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')]} 08:08:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:52 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 0x7fc360c2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2921630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b2840>, '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 0x291f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291fb60>, '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': 'ccb4611811c763f2fad71465000001de-1451820701.737861-850851793', '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 08:08:52 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 08:08:52 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 08:08:52 INFO - Using env: {'DISPLAY': ':0', 08:08:52 INFO - 'HOME': '/home/cltbld', 08:08:52 INFO - 'LANG': 'en_US.UTF-8', 08:08:52 INFO - 'LANGUAGE': 'en_US:en', 08:08:52 INFO - 'LOGNAME': 'cltbld', 08:08:52 INFO - 'MAIL': '/var/mail/cltbld', 08:08:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:52 INFO - 'MOZ_NO_REMOTE': '1', 08:08:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:52 INFO - 'NO_EM_RESTART': '1', 08:08:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:08:52 INFO - 'PWD': '/builds/slave/test-pgo', 08:08:52 INFO - 'SHELL': '/bin/bash', 08:08:52 INFO - 'SHLVL': '1', 08:08:52 INFO - 'TERM': 'linux', 08:08:52 INFO - 'TMOUT': '86400', 08:08:52 INFO - 'USER': 'cltbld', 08:08:52 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1451820701.737861-850851793', 08:08:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:52 INFO - '_': '/tools/buildbot/bin/python'} 08:08:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:52 INFO - Downloading/unpacking blobuploader==1.2.4 08:08:52 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 08:08:52 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 08:08:52 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 08:08:52 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 08:08:52 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 08:08:52 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 08:08:54 INFO - Downloading blobuploader-1.2.4.tar.gz 08:08:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 08:08:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:08:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:08:54 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 08:08:54 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 08:08:54 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 08:08:54 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 08:08:54 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 08:08:54 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 08:08:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 08:08:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 08:08:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:55 INFO - Downloading docopt-0.6.1.tar.gz 08:08:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 08:08:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 08:08:55 INFO - Installing collected packages: blobuploader, requests, docopt 08:08:55 INFO - Running setup.py install for blobuploader 08:08:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:08:55 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 08:08:55 INFO - Running setup.py install for requests 08:08:55 INFO - Running setup.py install for docopt 08:08:55 INFO - Successfully installed blobuploader requests docopt 08:08:55 INFO - Cleaning up... 08:08:55 INFO - Return code: 0 08:08:55 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:55 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 0x7fc360c2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2921630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b2840>, '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 0x291f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291fb60>, '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': 'ccb4611811c763f2fad71465000001de-1451820701.737861-850851793', '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 08:08:55 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 08:08:55 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 08:08:55 INFO - Using env: {'DISPLAY': ':0', 08:08:55 INFO - 'HOME': '/home/cltbld', 08:08:55 INFO - 'LANG': 'en_US.UTF-8', 08:08:55 INFO - 'LANGUAGE': 'en_US:en', 08:08:55 INFO - 'LOGNAME': 'cltbld', 08:08:55 INFO - 'MAIL': '/var/mail/cltbld', 08:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:55 INFO - 'MOZ_NO_REMOTE': '1', 08:08:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:55 INFO - 'NO_EM_RESTART': '1', 08:08:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:08:55 INFO - 'PWD': '/builds/slave/test-pgo', 08:08:55 INFO - 'SHELL': '/bin/bash', 08:08:55 INFO - 'SHLVL': '1', 08:08:55 INFO - 'TERM': 'linux', 08:08:55 INFO - 'TMOUT': '86400', 08:08:55 INFO - 'USER': 'cltbld', 08:08:55 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1451820701.737861-850851793', 08:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:55 INFO - '_': '/tools/buildbot/bin/python'} 08:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:08:56 INFO - Running setup.py (path:/tmp/pip-oF_hD2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:08:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:08:56 INFO - Running setup.py (path:/tmp/pip-4s8oFE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:08:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:08:56 INFO - Running setup.py (path:/tmp/pip-YXbaJQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:08:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:08:56 INFO - Running setup.py (path:/tmp/pip-4S3UUi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:08:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:08:56 INFO - Running setup.py (path:/tmp/pip-8ZTFaJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:08:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:08:56 INFO - Running setup.py (path:/tmp/pip-QhgKNM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:08:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:08:56 INFO - Running setup.py (path:/tmp/pip-cgGVIY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:08:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:08:56 INFO - Running setup.py (path:/tmp/pip-eE0GKl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:08:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:08:56 INFO - Running setup.py (path:/tmp/pip-jMYzRu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:08:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:08:56 INFO - Running setup.py (path:/tmp/pip-OYd2M3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:08:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:08:56 INFO - Running setup.py (path:/tmp/pip-9mHs4N-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:08:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:08:56 INFO - Running setup.py (path:/tmp/pip-AfovEH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:08:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:08:56 INFO - Running setup.py (path:/tmp/pip-f9cbHW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:08:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:08:57 INFO - Running setup.py (path:/tmp/pip-9RKrsJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:08:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:08:57 INFO - Running setup.py (path:/tmp/pip-uia9sO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:08:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:08:57 INFO - Running setup.py (path:/tmp/pip-o6BJ3G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:08:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:08:57 INFO - Running setup.py (path:/tmp/pip-cF7lAQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:08:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:08:57 INFO - Running setup.py install for manifestparser 08:08:57 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 08:08:57 INFO - Running setup.py install for mozcrash 08:08:57 INFO - Running setup.py install for mozdebug 08:08:57 INFO - Running setup.py install for mozdevice 08:08:57 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 08:08:57 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 08:08:57 INFO - Running setup.py install for mozfile 08:08:57 INFO - Running setup.py install for mozhttpd 08:08:57 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 08:08:57 INFO - Running setup.py install for mozinfo 08:08:58 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 08:08:58 INFO - Running setup.py install for mozInstall 08:08:58 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 08:08:58 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 08:08:58 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 08:08:58 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 08:08:58 INFO - Running setup.py install for mozleak 08:08:58 INFO - Running setup.py install for mozlog 08:08:58 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 08:08:58 INFO - Running setup.py install for moznetwork 08:08:58 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 08:08:58 INFO - Running setup.py install for mozprocess 08:08:58 INFO - Running setup.py install for mozprofile 08:08:58 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 08:08:58 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 08:08:58 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 08:08:58 INFO - Running setup.py install for mozrunner 08:08:58 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 08:08:58 INFO - Running setup.py install for mozscreenshot 08:08:58 INFO - Running setup.py install for moztest 08:08:58 INFO - Running setup.py install for mozversion 08:08:59 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 08:08:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:08:59 INFO - Cleaning up... 08:08:59 INFO - Return code: 0 08:08:59 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:59 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7fc360c2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2921630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b2840>, '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 0x291f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291fb60>, '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': 'ccb4611811c763f2fad71465000001de-1451820701.737861-850851793', '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 08:08:59 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 08:08:59 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 08:08:59 INFO - Using env: {'DISPLAY': ':0', 08:08:59 INFO - 'HOME': '/home/cltbld', 08:08:59 INFO - 'LANG': 'en_US.UTF-8', 08:08:59 INFO - 'LANGUAGE': 'en_US:en', 08:08:59 INFO - 'LOGNAME': 'cltbld', 08:08:59 INFO - 'MAIL': '/var/mail/cltbld', 08:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:59 INFO - 'MOZ_NO_REMOTE': '1', 08:08:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:59 INFO - 'NO_EM_RESTART': '1', 08:08:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:08:59 INFO - 'PWD': '/builds/slave/test-pgo', 08:08:59 INFO - 'SHELL': '/bin/bash', 08:08:59 INFO - 'SHLVL': '1', 08:08:59 INFO - 'TERM': 'linux', 08:08:59 INFO - 'TMOUT': '86400', 08:08:59 INFO - 'USER': 'cltbld', 08:08:59 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1451820701.737861-850851793', 08:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:59 INFO - '_': '/tools/buildbot/bin/python'} 08:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:08:59 INFO - Running setup.py (path:/tmp/pip-G3YtRB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:08:59 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)) 08:08:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:08:59 INFO - Running setup.py (path:/tmp/pip-CUe3DJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:08:59 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)) 08:08:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:08:59 INFO - Running setup.py (path:/tmp/pip-0fO8hO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:08:59 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)) 08:08:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:08:59 INFO - Running setup.py (path:/tmp/pip-iL_qI2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:08:59 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)) 08:08:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:08:59 INFO - Running setup.py (path:/tmp/pip-9vyV4d-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:08:59 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)) 08:08:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:08:59 INFO - Running setup.py (path:/tmp/pip-iPc3Xa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:08:59 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)) 08:08:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:08:59 INFO - Running setup.py (path:/tmp/pip-oE1y3I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:08:59 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)) 08:08:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:08:59 INFO - Running setup.py (path:/tmp/pip-eJWMXr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:08:59 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)) 08:08:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:08:59 INFO - Running setup.py (path:/tmp/pip-KFmnTl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:08:59 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)) 08:08:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:08:59 INFO - Running setup.py (path:/tmp/pip-TIQVJA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:09:00 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)) 08:09:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:09:00 INFO - Running setup.py (path:/tmp/pip-8FDBsp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:09:00 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)) 08:09:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:09:00 INFO - Running setup.py (path:/tmp/pip-gGRTrg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:09:00 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)) 08:09:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:09:00 INFO - Running setup.py (path:/tmp/pip-egwYJF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 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)) 08:09:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:09:00 INFO - Running setup.py (path:/tmp/pip-Jq3AZK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:09:00 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)) 08:09:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:09:00 INFO - Running setup.py (path:/tmp/pip-J0FLX1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:09:00 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)) 08:09:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:09:00 INFO - Running setup.py (path:/tmp/pip-24kgSV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:09:00 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)) 08:09:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:09:00 INFO - Running setup.py (path:/tmp/pip-LfnKpo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:09:00 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)) 08:09:00 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)) 08:09:00 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)) 08:09:00 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)) 08:09:00 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)) 08:09:00 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)) 08:09:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 08:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:02 INFO - Downloading blessings-1.5.1.tar.gz 08:09:02 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 08:09:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 08:09:02 INFO - Installing collected packages: blessings 08:09:02 INFO - Running setup.py install for blessings 08:09:02 INFO - Successfully installed blessings 08:09:02 INFO - Cleaning up... 08:09:02 INFO - Return code: 0 08:09:02 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 08:09:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:09:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:09:02 INFO - Reading from file tmpfile_stdout 08:09:02 INFO - Current package versions: 08:09:02 INFO - argparse == 1.2.1 08:09:02 INFO - blessings == 1.5.1 08:09:02 INFO - blobuploader == 1.2.4 08:09:02 INFO - docopt == 0.6.1 08:09:02 INFO - manifestparser == 1.1 08:09:02 INFO - mozInstall == 1.12 08:09:02 INFO - mozcrash == 0.16 08:09:02 INFO - mozdebug == 0.1 08:09:02 INFO - mozdevice == 0.47 08:09:02 INFO - mozfile == 1.2 08:09:02 INFO - mozhttpd == 0.7 08:09:02 INFO - mozinfo == 0.9 08:09:02 INFO - mozleak == 0.1 08:09:02 INFO - mozlog == 3.1 08:09:02 INFO - moznetwork == 0.27 08:09:02 INFO - mozprocess == 0.22 08:09:02 INFO - mozprofile == 0.28 08:09:02 INFO - mozrunner == 6.11 08:09:02 INFO - mozscreenshot == 0.1 08:09:02 INFO - mozsystemmonitor == 0.0 08:09:02 INFO - moztest == 0.7 08:09:02 INFO - mozversion == 1.4 08:09:02 INFO - psutil == 3.1.1 08:09:02 INFO - requests == 1.2.3 08:09:02 INFO - wsgiref == 0.1.2 08:09:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:09: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')]} 08:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7fc360c2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2921630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b2840>, '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 0x291f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291fb60>, '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': 'ccb4611811c763f2fad71465000001de-1451820701.737861-850851793', '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 08:09:02 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 08:09:02 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 08:09:02 INFO - Using env: {'DISPLAY': ':0', 08:09:02 INFO - 'HOME': '/home/cltbld', 08:09:02 INFO - 'LANG': 'en_US.UTF-8', 08:09:02 INFO - 'LANGUAGE': 'en_US:en', 08:09:02 INFO - 'LOGNAME': 'cltbld', 08:09:02 INFO - 'MAIL': '/var/mail/cltbld', 08:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:02 INFO - 'MOZ_NO_REMOTE': '1', 08:09:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:09:02 INFO - 'NO_EM_RESTART': '1', 08:09:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:09:02 INFO - 'PWD': '/builds/slave/test-pgo', 08:09:02 INFO - 'SHELL': '/bin/bash', 08:09:02 INFO - 'SHLVL': '1', 08:09:02 INFO - 'TERM': 'linux', 08:09:02 INFO - 'TMOUT': '86400', 08:09:02 INFO - 'USER': 'cltbld', 08:09:02 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1451820701.737861-850851793', 08:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:02 INFO - '_': '/tools/buildbot/bin/python'} 08:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.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 1)) 08:09:02 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 2)) 08:09:02 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 3)) 08:09:02 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 4)) 08:09:02 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 5)) 08:09:02 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 6)) 08:09:02 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 7)) 08:09:02 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 8)) 08:09:02 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 9)) 08:09:02 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.1->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 08:09:02 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 4)) 08:09:02 INFO - Cleaning up... 08:09:02 INFO - Return code: 0 08:09:02 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 08:09: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')]} 08:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc360c2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2921630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b2840>, '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 0x291f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291fb60>, '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': 'ccb4611811c763f2fad71465000001de-1451820701.737861-850851793', '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 08:09:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 08:09:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:09:02 INFO - Using env: {'DISPLAY': ':0', 08:09:02 INFO - 'HOME': '/home/cltbld', 08:09:02 INFO - 'LANG': 'en_US.UTF-8', 08:09:02 INFO - 'LANGUAGE': 'en_US:en', 08:09:02 INFO - 'LOGNAME': 'cltbld', 08:09:02 INFO - 'MAIL': '/var/mail/cltbld', 08:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:02 INFO - 'MOZ_NO_REMOTE': '1', 08:09:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:09:02 INFO - 'NO_EM_RESTART': '1', 08:09:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:09:02 INFO - 'PWD': '/builds/slave/test-pgo', 08:09:02 INFO - 'SHELL': '/bin/bash', 08:09:02 INFO - 'SHLVL': '1', 08:09:02 INFO - 'TERM': 'linux', 08:09:02 INFO - 'TMOUT': '86400', 08:09:02 INFO - 'USER': 'cltbld', 08:09:02 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1451820701.737861-850851793', 08:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:02 INFO - '_': '/tools/buildbot/bin/python'} 08:09:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:03 INFO - Downloading/unpacking jsonschema 08:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:09:04 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 08:09:04 INFO - Downloading/unpacking functools32 (from jsonschema) 08:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:04 INFO - Downloading functools32-3.2.3-2.tar.gz 08:09:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 08:09:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 08:09:05 INFO - warning: no files found matching '*.txt' 08:09:05 INFO - no previously-included directories found matching 'build' 08:09:05 INFO - no previously-included directories found matching 'dist' 08:09:05 INFO - no previously-included directories found matching '.git*' 08:09:05 INFO - Installing collected packages: jsonschema, functools32 08:09:05 INFO - Running setup.py install for functools32 08:09:05 INFO - warning: no files found matching '*.txt' 08:09:05 INFO - no previously-included directories found matching 'build' 08:09:05 INFO - no previously-included directories found matching 'dist' 08:09:05 INFO - no previously-included directories found matching '.git*' 08:09:05 INFO - Successfully installed jsonschema functools32 08:09:05 INFO - Cleaning up... 08:09:05 INFO - Return code: 0 08:09:05 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 08:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc360c2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2921630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b2840>, '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 0x291f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291fb60>, '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': 'ccb4611811c763f2fad71465000001de-1451820701.737861-850851793', '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 08:09:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 08:09:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 08:09:05 INFO - Using env: {'DISPLAY': ':0', 08:09:05 INFO - 'HOME': '/home/cltbld', 08:09:05 INFO - 'LANG': 'en_US.UTF-8', 08:09:05 INFO - 'LANGUAGE': 'en_US:en', 08:09:05 INFO - 'LOGNAME': 'cltbld', 08:09:05 INFO - 'MAIL': '/var/mail/cltbld', 08:09:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:05 INFO - 'MOZ_NO_REMOTE': '1', 08:09:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:09:05 INFO - 'NO_EM_RESTART': '1', 08:09:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:09:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:09:05 INFO - 'PWD': '/builds/slave/test-pgo', 08:09:05 INFO - 'SHELL': '/bin/bash', 08:09:05 INFO - 'SHLVL': '1', 08:09:05 INFO - 'TERM': 'linux', 08:09:05 INFO - 'TMOUT': '86400', 08:09:05 INFO - 'USER': 'cltbld', 08:09:05 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1451820701.737861-850851793', 08:09:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:05 INFO - '_': '/tools/buildbot/bin/python'} 08:09:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:05 INFO - Downloading/unpacking flake8 08:09:05 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 08:09:05 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 08:09:05 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 08:09:05 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 08:09:05 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 08:09:05 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 08:09:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:09:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 08:09:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:09:07 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 08:09:07 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 08:09:07 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 08:09:07 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 08:09:07 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 08:09:07 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 08:09:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:09:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 08:09:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:09:07 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 08:09:07 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 08:09:07 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 08:09:07 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 08:09:07 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 08:09:07 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 08:09:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 08:09:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:09:07 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 08:09:07 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 08:09:07 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 08:09:07 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 08:09:07 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 08:09:07 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 08:09:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 08:09:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 08:09:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:09:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:09:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:09:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:09:07 INFO - no previously-included directories found matching 'docs/_build' 08:09:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:09:07 INFO - Running setup.py install for pep8 08:09:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:09:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:09:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:09:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:09:07 INFO - no previously-included directories found matching 'docs/_build' 08:09:07 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 08:09:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:09:07 INFO - Cleaning up... 08:09:08 INFO - Return code: 0 08:09:08 INFO - Running post-action listener: _resource_record_post_action 08:09:08 INFO - Running post-action listener: _start_resource_monitoring 08:09:08 INFO - Starting resource monitoring. 08:09:08 INFO - ##### 08:09:08 INFO - ##### Running install step. 08:09:08 INFO - ##### 08:09:08 INFO - Running pre-action listener: _resource_record_pre_action 08:09:08 INFO - Running main action method: install 08:09:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:09:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:09:08 INFO - Reading from file tmpfile_stdout 08:09:08 INFO - Detecting whether we're running mozinstall >=1.0... 08:09:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 08:09:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 08:09:08 INFO - Reading from file tmpfile_stdout 08:09:08 INFO - Output received: 08:09:08 INFO - Usage: mozinstall [options] installer 08:09:08 INFO - Options: 08:09:08 INFO - -h, --help show this help message and exit 08:09:08 INFO - -d DEST, --destination=DEST 08:09:08 INFO - Directory to install application into. [default: 08:09:08 INFO - "/builds/slave/test-pgo"] 08:09:08 INFO - --app=APP Application being installed. [default: firefox] 08:09:08 INFO - mkdir: /builds/slave/test-pgo/build/application 08:09:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 08:09:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 08:09:25 INFO - Reading from file tmpfile_stdout 08:09:25 INFO - Output received: 08:09:25 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 08:09:25 INFO - Running post-action listener: _resource_record_post_action 08:09:25 INFO - ##### 08:09:25 INFO - ##### Running run-tests step. 08:09:25 INFO - ##### 08:09:25 INFO - Running pre-action listener: _resource_record_pre_action 08:09:25 INFO - Running main action method: run_tests 08:09:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 08:09:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 08:09:25 INFO - Python 2.7.3 08:09:25 INFO - Return code: 0 08:09:25 INFO - grabbing minidump binary from tooltool 08:09:25 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')]} 08:09:25 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 0x29b2840>, '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 0x291f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291fb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 08:09:25 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 08:09:25 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 08:09:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:09:25 INFO - Return code: 0 08:09:25 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 08:09:25 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 08:09:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 08:09:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 08:09:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 08:09:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 08:09:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 08:09:26 INFO - Return code: 0 08:09:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'B2G-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test-pgo/build 08:09:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName B2G-Inbound --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log 08:09:26 INFO - Using env: {'DISPLAY': ':0', 08:09:26 INFO - 'HOME': '/home/cltbld', 08:09:26 INFO - 'LANG': 'en_US.UTF-8', 08:09:26 INFO - 'LANGUAGE': 'en_US:en', 08:09:26 INFO - 'LOGNAME': 'cltbld', 08:09:26 INFO - 'MAIL': '/var/mail/cltbld', 08:09:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:09:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 08:09:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:26 INFO - 'MOZ_NO_REMOTE': '1', 08:09:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:09:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:09:26 INFO - 'NO_EM_RESTART': '1', 08:09:26 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:09:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:09:26 INFO - 'PWD': '/builds/slave/test-pgo', 08:09:26 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 08:09:26 INFO - 'SHELL': '/bin/bash', 08:09:26 INFO - 'SHLVL': '1', 08:09:26 INFO - 'TERM': 'linux', 08:09:26 INFO - 'TMOUT': '86400', 08:09:26 INFO - 'USER': 'cltbld', 08:09:26 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1451820701.737861-850851793', 08:09:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:26 INFO - '_': '/tools/buildbot/bin/python'} 08:09:26 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'B2G-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:09:26 INFO - mozversion application_buildid: 20160103053120 08:09:26 INFO - mozversion application_changeset: d8d8ce02431c2fbfd744ee0d01837dbe81f446ff 08:09:26 INFO - mozversion application_display_name: Nightly 08:09:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:09:26 INFO - mozversion application_name: Firefox 08:09:26 INFO - mozversion application_remotingname: firefox 08:09:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/b2g-inbound 08:09:26 INFO - mozversion application_vendor: Mozilla 08:09:26 INFO - mozversion application_version: 46.0a1 08:09:26 INFO - mozversion platform_buildid: 20160103053120 08:09:26 INFO - mozversion platform_changeset: d8d8ce02431c2fbfd744ee0d01837dbe81f446ff 08:09:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/b2g-inbound 08:09:26 INFO - mozversion platform_version: 46.0a1 08:09:26 INFO - using testdate: 1451837366 08:09:26 INFO - actual date: 1451837366 08:09:26 INFO - starting webserver on 'localhost:36434' 08:09:26 INFO - SUITE-START | Running 3 tests 08:09:26 INFO - TEST-START | dromaeo_css 08:09:26 INFO - operating with platform_type : linux_ 08:09:26 INFO - Initialising browser for dromaeo_css test... 08:09:26 INFO - TEST-INFO | started process 5928 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzbUu7x/profile http://localhost:36434/getInfo.html) 08:09:31 INFO - PROCESS | 5928 | __metrics Screen width/height:1600/1200 08:09:31 INFO - PROCESS | 5928 | colorDepth:24 08:09:31 INFO - PROCESS | 5928 | Browser inner width/height: 1024/697 08:09:31 INFO - PROCESS | 5928 | __metrics 08:09:31 INFO - PROCESS | 5928 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 08:09:31 INFO - PROCESS | 5928 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:09:33 INFO - TEST-INFO | 5928: exit 0 08:09:33 INFO - Browser initialized. 08:09:33 INFO - Running cycle 1/1 for dromaeo_css test... 08:09:33 INFO - TEST-INFO | started process 6023 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzbUu7x/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:09:45 INFO - PROCESS | 6023 | RSS: Main: 149856256 08:09:45 INFO - PROCESS | 6023 | 08:10:36 INFO - PROCESS | 6023 | 1451837436686 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] 08:12:45 INFO - PROCESS | 6023 | Cycle 1(1): loaded http://localhost:36434/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36434/tests/dromaeo/cssquery-ext.html) 08:12:46 INFO - PROCESS | 6023 | RSS: Main: 288690176 08:12:46 INFO - PROCESS | 6023 | 08:15:49 INFO - PROCESS | 6023 | Cycle 1(1): loaded http://localhost:36434/tests/dromaeo/cssquery-ext.html (next: http://localhost:36434/tests/dromaeo/cssquery-jquery.html) 08:15:50 INFO - PROCESS | 6023 | RSS: Main: 235454464 08:15:50 INFO - PROCESS | 6023 | 08:18:52 INFO - PROCESS | 6023 | Cycle 1(1): loaded http://localhost:36434/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36434/tests/dromaeo/cssquery-mootools.html) 08:18:52 INFO - PROCESS | 6023 | RSS: Main: 229490688 08:18:52 INFO - PROCESS | 6023 | 08:21:54 INFO - PROCESS | 6023 | Cycle 1(1): loaded http://localhost:36434/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36434/tests/dromaeo/cssquery-prototype.html) 08:21:55 INFO - PROCESS | 6023 | RSS: Main: 214794240 08:21:55 INFO - PROCESS | 6023 | 08:24:57 INFO - PROCESS | 6023 | Cycle 1(1): loaded http://localhost:36434/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36434/tests/dromaeo/cssquery-yui.html) 08:24:58 INFO - PROCESS | 6023 | RSS: Main: 221171712 08:24:58 INFO - PROCESS | 6023 | 08:28:00 INFO - PROCESS | 6023 | Cycle 1(1): loaded http://localhost:36434/tests/dromaeo/cssquery-yui.html (next: http://localhost:36434/tests/dromaeo/cssquery-dojo.html) 08:28:01 INFO - PROCESS | 6023 | RSS: Main: 275120128 08:28:01 INFO - PROCESS | 6023 | 08:28:01 INFO - PROCESS | 6023 | __start_tp_report 08:28:01 INFO - PROCESS | 6023 | _x_x_mozilla_page_load 08:28:01 INFO - PROCESS | 6023 | _x_x_mozilla_page_load_details 08:28:01 INFO - PROCESS | 6023 | |i|pagename|runs| 08:28:01 INFO - PROCESS | 6023 | |0;dojo.html;2985.1343110497955;3073.907782766518;3130.107919243016;3130.9843450782755;3147.6537580866107;5167.715775632339;5307.814226502073;5315.441878602749;5336.30628018358;5368.973155134224;5268.604854635899;5294.788208471657;5310.522053015228;5331.786728530854;5380.21986811912;4785.330053792463;4801.495842936493;4830.73430961297;4874.46380898101;4885.31605575218;4766.952330476703;4899.265110233458;4914.827981020665;4940.925886111711;4973.925391119118;4759.90480190396;4762.166620841356;4852.975735121302;4857.2714092886;4868.367112275417;4839.7096174229655;4845.079434907357;4852.8544143675745;4879.2925025871045;4907.975460122712;4742.905141897178;4817.084753896784;4829.6860704054125;4862.100511405366;4869.269609558559;5080.288759573663;5153.9742301288625;5161.096808058613;5176.689398636094;5199.090159222121;5638;5640.520555752766;5661.631993920382;5662.2639056922335;5688.11834165705;5577.442255774431;5696.1740547620775;5703.144528320744;5705.800296989627;5734.283214598176;5233.685978841282;5339.092354299701;5361.544268737123;5366.034113859504;5411.458854114597;5716.428357164334;5761.740721667451;5764.884702305931;5767.192593036978;5768.942310576925;5454.945450545444;5543.556515478752;5552.7223638817895;5553.777848885999;5570.498655120978;5616.269884914935;5664.0937450007805;5669.433056694297;5676.631018983753;5719.19931209631;5539.806106786243;5562.137868630369;5576.498115169654;5598.10430331145;5603.5237004854225;5459.262999495097;5542.39033706292;5560.555155587544;5566.860828479321;5602.971985140048;4388.780560971939;4450.287953927356;4455.532169122261;4493.528179541101;4495.370648109268;3885.902852428712;3910.4720862683744;3912.1197730510976;3917.7061720371084;3938.4879965604287;5256.894862102813;5272.077386457355;5302.814401496006;5336.519713225828;5380.381256155514;5671.262735844314;5717.885642287131;5745.109508026263;5746.482816546531;5779.88440231193;5289;5300.390455097636;5308.893822123613;5311.017461769585;5314.813981510706;4657.720536767804;4765.761711914391;4798.304245884327;4837;4887.095887260868;5923.437273459101;5923.526117910556;5930.703464826827;5932.584719069624;5965.13505541694;5674.4892959633835;5678.403767604342;5680.403557626555;5682.488576028178;5692.601517893873;5717.142428635738;5773.509251713652;5834.562407819346;5868.09044598088;5919.4080591940465;5623.606347555796;5631.746571404215;5634.52106570946;5636.154576813511;5678.716064196857;5647.830565083054;5704.3725190228215;5709;5712.685802280915;5717.514011309086;5373.8387848363045;5503.944960550343;5553.194786755817;5599.132134519156;5640.182173584725;5565.693886836263;5567.582431317586;5568.16477528374;5572.442755724557;5575.358833734173;5481.5340696041285;5484;5486.643368181056;5487.176923461513;5533.916991245215;5410.404855465817;5508;5521.061419558604;5523.171524271231;5568.220449137043;5398.5951053671915;5443.755031023693;5480.260164877691;5492.286002819608;5538.307711536058 08:28:01 INFO - PROCESS | 6023 | |1;ext.html;527.4619887714531;535.3575709149022;537.7015756255279;560.2716468590833;562.5162360370084;27379.726202737966;27690.584641230373;34300.14249643757;34960;35034.12414689634;38433.42349864754;38796;38866.611333886656;38893.41659875104;38952;7284.562926224444;8320.043195032558;8476.321894248445;8481.703140390087;8485.618147183392;7384.852302953937;7486.737964171255;9925.602975880993;10059.044390782863;10067.093961543496;9889.406635601887;9978.20174386051;9987.450690212036;9992.050755178283;9998.650047248348;9809.264305177141;9843.212542996615;9887.357321774134;9911.851322230137;9930.652427165087;9680.27397945157;9787.46168960707;9793.657221997268;9798.363106398061;9814.312998090136;35309.823450882584;35803.31475139375;39239.41140882889;39399.21201575981;39496.60503394958;3203.583534140547;3234.3944434253535;3424.7945123292434;3430.279641275335;3439.4496880499;29637.555436668466;29950.70049299501;30447;30606;31250.218744531343;3738.2523495301048;3746.0822098586023;3778.149916268845;3812.9618703812885;3871.1096447816876;260177.9999999981;260813;261411;262002;262080;50066.74966625144;50476;50552;55511.44488555063;56482.7175864118;48594.27108593374;48734.51265487371;48810.26784598234;48990;49023.75488122608;14242.501512447221;14325.068870523382;14336;14361.281935903164;14403.207823569805;11055.281406708538;11160.497777600027;11175.273607215668;11251.212415130862;11265.267757595893;17411.825881741275;17449.912750436168;18369.540761480803;18399.632007359778;18409.447716568524;6887.345702158287;7050.294970503011;7123.786286411312;7197.316254955771;7234.782956511313;947.2327414794039;955.7467271173144;968.2689569375218;1027.547878933267;1053.4574694032578;30038.399232015236;30565.541516877263;30772.53841192384;30888.382232355227;30920.69079309223;971.0435221307007;971.2715463402449;1007.2194049611463;1073.8281874899978;1083.9458027098613;12943.870561294454;12976;12988.870111298766;13002.739945201232;13060.34698265083;306023;314650;314765;314962;316220;42385.36421953673;49416;49729;49965.500344996086;50063.49936500661;1247.077162899466;1341.7249463859885;1348.716769478401;1349.9865001350058;1376.4494202319152;24494.265172044867;24639.75360246446;24906.875465622557;24939.625905611792;25087;34995.65004349924;35551.64448355483;40835;41529.1694166121;45445.54554454546;190735;191125;191863;192420;192432;27383.726162738116;27946.44107117887;27949.16152515428;27970.580441293805;27998.44003119967;37299.81350093232;38068;39046.609533904295;39069.80465097656;39237.80381098076;32097.839510802296;34970.47544286787;35312;35962.92111236667;36518.634813652585;34893.8255308732;35403;35526.28947420985;35542.822285888404;35828;34591.30817383588;34612.65387346094;34861.65138348685;36319.455208172425;36491.817540912125;29030;30656.233594160323;30961.071167865;31246.21884452907;31488.842555787076;23967.52064958726;24044.63933041041;24131.276061718178;24327.635085474085;24416.75583244145 08:28:01 INFO - PROCESS | 6023 | |2;jquery.html;357.3888650407803;358.88515674984;360.4269211952996;360.922401683638;366.33327344233504;13506.864931350683;13891.305434728274;16143.192840357979;16177.919110404446;16513.339466421345;15832.129232892186;16275.918620406881;17050.06224657643;17059.317627294895;17081.57296067599;5139.408967968676;5282.260483532308;5297.311349524556;5305.283786688786;5308.893822123555;4008.6392224699916;4119.629233368996;5236.371635403757;5606.55147588192;5848.035074212749;5728.885422291552;5731.570132240078;5752.712364381785;5755.769769209248;5768.423157684219;5582.078956972095;5658.349289831681;5687.203791469177;5693.743781529841;5695.401982791809;5018.573421259161;5020.748962551895;5026.0199261143725;5030.89938201234;5037.244413337992;20498.487537811674;20837.37487875366;21082.894585526974;21362.89318553413;21367.67948480774;2297.689811875392;2317.7218733752056;2318.719429973185;2330.196082351586;2332.521833024226;13008.934955325258;13481.59555213345;13617.251051192181;13670.52153174644;13699.178049317074;1596.6726821001666;1691.129068529708;1711.4523352527071;1821;1868;59422.40577594212;59816;59996;60860.08709869311;62359;9903.554340054714;10223.079922806983;10224.437655928996;10312.845307320396;10325.48372581368;13272.535461258809;13356.599302020955;13378.197308161542;13430.597082087554;13453;7465.925340746631;7542.736004239825;7571.772846814604;7616.771496855104;7632.732854350071;7236.204017558064;7263.564186148848;7267.63661816907;7557.584332861636;7726.072871255373;6036.547258955596;6047.78832740852;6059.6364218147055;6082;6082.847928801815;1687.8902871313326;1698.5668654493024;1705.9914700426418;1744.7208446648476;1756.47305808257;423.6737711961834;427.3333599584658;429.4524481286385;457.82144963464276;897.2732087009543;10315;10469.9476502617;10582.365058096539;16068.598285042968;16990.830091699172;813.3005615170948;869.1873098652779;889.1952782731638;908.6683360573476;1002.5989604158395;9475.90524094764;9986.251031172545;10167.440790756431;10344.948275258575;10426.113780328606;521787;533099;539328;544821;545485;74587.62706186435;76911;78144.60927695326;79102.6044869772;80255;1095.6548687163677;1110.4836251143256;1110.6612483192703;1120.081533142815;1140.1676775953601;15227;15437.15095669749;15612.609684757972;15655.452059177875;15734.842651573337;32181;32471.18822029468;32906;34936.825315873255;35268.29463410666;45503.772481137385;45838.08323833438;46602.300965484865;47878.28182577195;51117.97764044624;9246.214071803703;9520.571574279313;9652.951735241279;9653;9662.033796620282;10904.509297081491;15914.60213494672;16177.4337898173;16683.332666693197;16735.33058677688;12074.75850483003;13686.863131368558;13840.169589824643;14375.928120359331;14401.63995900111;13811.79282310746;14199.574012779633;14218.644533886321;14323.928380358031;14607;10572.947135264274;10614.946925265323;13166.210027398387;13437.395317210554;14154.221517816619;8963.462192268486;9080.682176123804;9337.159655631025;9398.483083430478;9464.526773661428;9524.476153811607;9626.759331016781;12866.35668216604;13639.386227619583;13992.300384980914 08:28:01 INFO - PROCESS | 6023 | |3;mootools.html;1297.779377505824;1452.150491962202;1460.043671395236;1615.450746746106;1786.794518630357;6803.625800580966;7407;7461.2911773381575;7544.735934242296;7548.320651141396;7405.777826665195;7578.848423031536;7582.620868956557;7589.772306830804;7592.810179745509;1959.480737604536;1999.660057790179;2193.4297082758517;2198.6921830943643;2231.94420139497;2271.761465046171;2276.8747718875456;2280.7605201453857;2282.0985710644286;2283.851549649276;2182.9126834926583;2251.8423710340285;2253.2451628704403;2254.5941730488507;2254.86470811751;2180.2151225558787;2203.1627981367137;2205.71325727656;2222.744384395799;2250.0212407602744;2237.071615279657;2244.932652020442;2272.7954484096344;2279.4985103277254;2282.281081459335;7554.77335679925;7616;7617.46677732559;7631.2368763123795;7632.236776322379;4592.265237562007;4678.719276843401;4679.0174063446375;4683.625309975194;4686.390769200017;3013.623297087864;3082.475979083561;3108.704673056056;3114.345987342661;3148.039847846406;983.8032393521324;1008.7730260691359;1043.0820877627714;1047.3715770537692;1090.0516550600948;10188.337758045704;10297;10307.381557106597;10312.381257124522;10330.27688061836;3975.185087057146;4065.735727177724;4127.050778320943;4154.667626589866;4160.729552579133;8422.578871056545;8435.789105272417;8440.282575981111;8459.111793261742;8576.914230857736;4631.722096674211;4635.23518619424;4643.512431194676;4645.883852903705;4650.372199753057;4334.674899382559;4335.046289816235;4338.370936214231;4343.087951530151;4344.956550434518;3071.447139514909;3174;3210.1814037420777;3393.7285017198565;3459.117924929177;1038.5067093130854;1048.3866937841449;1053.6470282455464;1059.247933966892;1065.248999455382;1081.8864019277862;1103.784761971424;1141.3266172957995;1346.9964876167319;1348.0630961481677;6864.553804002724;6902.068220790129;7121.572705637678;7422.6288685565505;7429.257074292527;1112.4660163121584;1114.2590177531852;1117.0895719988184;1130.886911308879;1144.7019394971376;5750.6262092963825;5821.359650438449;5827.7668893244645;5832.533397328203;5832.679202643811;8850.822983540293;9036.096390360912;9289.860652090225;9292.303077269096;9306.627734890528;9177.724668259963;9203.447793132433;9237.953810230905;9243.491607961627;9243.861342079874;7090.326418990187;7195.856082878313;7215.855682886313;7221.819454513679;7235.204127546071;7005.369516743313;7016.5790052597;7038.8944165838575;7045.01369808217;7050.048243487064;8012.719554815553;8153.429259951747;8222.794430139294;8359.331253499584;8368.497890126611;8430.873536897074;8473.78815529592;8525.488470691778;8623.267022303175;8623.82752344937;6689.063531105747;6907.620080895398;6908.758193463204;6926.9307306926285;6934.618595977269;6638.369354911276;6723.193216814014;6958.547694399847;7173.390261827594;7199.820004499929;6593.0769692244085;6671.766488172891;6734.629595372302;6777.356151165632;6856.41720453767;6667.333266673294;6800.319968003358;6800.455963522807;6827.556208846409;6925.134358205224;6721.428678562182;6742.6628668567355;6746;6746.291639377991;6762.188537375547;6140.907886381798;6171.444569988723;6172.845678858064;6236.064590311489;6254.468370188585;6241.937580624317;6264.655443950626;6274.372562743689;6281.152044473937;6283.74865005413 08:28:01 INFO - PROCESS | 6023 | |4;prototype.html;333.76969890775365;336.1562478179768;341.48947323750997;345.5404312264688;349.41124205713373;5777.248957635512;5869.501092407145;5873.500752436043;5879.441453061958;5944.078667806486;5670.6030577859565;5848.035074212749;5849.853753656161;5863;5870.677112758796;4556.6582506312;4557.407537020199;4559.407277053979;4561.429821272341;4569.977150114245;4348.717333373336;4425.623821975136;4467.285234362503;4553.863384098482;4558.97720511397;4500.549945005506;4502.909941801178;4504.909901801962;4510.188166130095;4543.7955292011775;4522.140793249305;4527.886802829923;4548.31775233716;4562.338460923164;4571.428571428572;4005.218982298453;4006.599340065999;4017.216642754664;4023.97988010061;4030.2946984277633;5470.9452905471235;5576.191452239403;5592.86017849557;5651.745671444794;5708.20085188074;5294.97352513235;5301.072312345363;5306.70813105279;5308.973455132699;5313.734313284359;5918.526517878559;6248.718807653667;6249.437550620421;6251.593646412968;6256.15541901842;4279.1013887083745;4307.784610769449;4344.826206951718;4352.586504282088;4362.781860906942;6349.1428657131155;6470.288268290531;6475.805725828232;6494.6103233806125;6496.350364963513;4476.216662084187;4523.050159466483;4524.9547504524535;4535.591796738244;4542.500324964251;4495.43807024122;4574.405327307429;4581.037982023746;4582.839600613963;4604.8388306409115;5205.6616319939085;5211;5213.452587478336;5213.582913366921;5216.217567364925;6280.905786413207;6281.3404592518045;6286.119943207955;6287.339829317947;6287.402696743802;1610.0822531157128;1633.7222672145763;1635.30499537696;1636.6399392133712;1640;1102.823548232295;1111.1777284809184;1113.287496002559;1113.9944300278446;1137.056243318044;5917.757270973144;5924.644521328734;5926.318473375531;5935.910961335583;5936.257967754031;6396.776112836028;6399.648019358887;6407.103005579223;6408.423241908251;6417;5913.793017244376;5915.585908986416;5921.496672782861;5930.258717660293;5932.644041357532;3413.7781044232047;3507.316073365669;3509.1578021275222;3510.8946731598094;3513.5783705954937;6787.151606049244;6801.18385793708;6809.761658341934;6812.386885180357;6813.693383797741;6646.53474256807;6667.033280174348;6671.899921501182;6682.799516014527;6684.83287917806;6618.503612229102;6795.694193761194;6796.558223715541;6800.489963252776;6807.591544507345;6214.5028397728975;6420.133282006869;6479.902801457888;6487.3836985486305;6491.188601424822;6268.373162683695;6376.617402955838;6389.808305750835;6398.328175541687;6446.387593178641;6750.35871592198;6764.526483146133;6766.594004359754;6767.966160169168;6776.898346524905;4324.718893271927;4395.824167033283;4425.712328698624;4427.734335939854;4429.357743127293;6475.125858009108;6477.481801455967;6478.287388387368;6484.902726459201;6486.124373209556;6269.655168965568;6275.309715931336;6282.2147231596055;6288.371162883675;6316.368363163647;6182.165407670087;6221.564490485623;6300.873982520232;6301.873962520815;6302.369763023661;6101.298350689727;6105.114758359924;6107.236595425572;6112.449879510687;6114.960456722279;6296.244450665949;6310.368963103653;6313.652749098844;6317.494600431863;6322.49420046386;6069.211002569814;6135.969320153371;6154.323024467394;6172.506199504119;6177.84555386119 08:28:01 INFO - PROCESS | 6023 | |5;yui.html;828.5981299069954;856.2336708645759;861.2636196052376;867.0679020053442;881.5283823154614;11700.707482312935;11839.70400739982;11839.940800296008;11874.28754274743;11875.465604047817;12734.490620375174;12837.229766214034;12845.614631561044;12866.614001579945;12877;687.7558466744309;695.6417445015821;802.9879551806728;812.959352032399;827.3174630929477;828.7265202483167;831.9833603327929;839.236294971576;839.8278352937664;840.7141571865565;858.0818524179131;864.4640322999749;866.3891956170916;869.7477731457869;870.7735988642935;712.6899798587631;722.4401089155893;852.8848605438249;874.4753148111146;875.3522393428881;642.6369101457661;644.2430144580146;647.4334956912701;647.8509942713155;647.8574713563008;12057.93971030148;12130.332831694253;12144;12145.939270303592;12168.08739344544;11677.88322116785;11720.296782193096;12412.875871241353;12562.623121306375;13074.266752622407;10240.539175737109;10255.79488410235;10387.27289089764;10438.530266137966;10473.162147028219;3568.4290513517712;3604.6215147409407;3612.1150318171954;3652.6347365263528;3660.670539651444;44997.77501112474;45011.54988450139;45348;45373.319400209024;45419.77290113561;9929.553170107363;10041;10165.44090075053;10220;10281.640142594973;18562;18584.163712632962;18595.070246487623;18595.58257426553;18623.53441163982;6980.581165130108;7174.390176835028;7296.562206267641;7419.703211871572;7586.431017673696;3869.845206191721;3930.718480532541;3958.524977002778;4070.1656160487028;4317.978410107929;2733.7539621434166;2767.2943399433016;2768.6539182602173;2773.362126710868;2787.1499192746405;520.5055197562331;526.3000209721062;530.8885134121878;532.8827657915252;534.1800336483557;607.3926073926074;631.8072987738784;643.1831573901119;647.6826355086068;657.6921166360321;8080.555569443737;8257.091719910804;8363.247307742333;8423.199796019368;8458.365622578209;540.4757385336296;560.4003716023815;607.3440684061203;659.9901001484982;675.2707076357522;3900.8829735107424;3930.253251882133;4015.7791321477016;4029.194161167778;4034.919301613951;44464.55535444669;45211;45934.540654593024;47932.5206747928;49025.754871225414;46242.76878615585;46556.5344346559;46723.29915051277;47091.5290847094;47139.528604713516;41973.37039944403;43703.344449833276;43922;43968.78015609965;44504.55495445069;7710.730124445618;8001.959990200012;8127.187281271826;8155.5514446706;8316.334693224406;33944;34254.65745342515;35122.12194695153;35128.82435587806;35785.64214357823;41509.79245103755;41674;41829.58170418257;42317.36523952205;42322;10858.67423977282;10928;11234.157438192331;11287.379194144401;11507.539698411969;11158.88841111611;11368.602098926498;11436.885631143583;12692.492300307884;12699.174553653984;10566.41884696349;10837.837432438362;10840.349579025284;11124.276922000045;11376.488058037243;10333.638322658671;10445.791084178121;10564.260501764802;10618.522166502371;10681.839772403575;10838.132949363875;11083.556657733923;11117.722056948642;11329.886701132884;11486.885131148581;9034.819303614022;9591.712248632552;9624.75938101553;9627.759306017406;9807.950960245153;8240.381971352292;8325.708600199207;8698.826023479369;8916.687915922912;9302.302327325342 08:28:01 INFO - PROCESS | 6023 | __end_tp_report 08:28:01 INFO - PROCESS | 6023 | __start_cc_report 08:28:01 INFO - PROCESS | 6023 | _x_x_mozilla_cycle_collect,1058 08:28:01 INFO - PROCESS | 6023 | __end_cc_report 08:28:01 INFO - PROCESS | 6023 | __startTimestamp1451838481024__endTimestamp 08:28:01 INFO - PROCESS | 6023 | 08:28:01 INFO - PROCESS | 6023 | ------- Summary: start ------- 08:28:01 INFO - PROCESS | 6023 | Number of tests: 6 08:28:01 INFO - PROCESS | 6023 | 08:28:01 INFO - PROCESS | 6023 | [#0] dojo.html Cycles:165 Average:5264.66 Median:5481.53 stddev:583.52 (10.6%) stddev-sans-first:557.23 08:28:01 INFO - PROCESS | 6023 | Values: 2985.1 3073.9 3130.1 3131.0 3147.7 5167.7 5307.8 5315.4 5336.3 5369.0 5268.6 5294.8 5310.5 5331.8 5380.2 4785.3 4801.5 4830.7 4874.5 4885.3 4767.0 4899.3 4914.8 4940.9 4973.9 4759.9 4762.2 4853.0 4857.3 4868.4 4839.7 4845.1 4852.9 4879.3 4908.0 4742.9 4817.1 4829.7 4862.1 4869.3 5080.3 5154.0 5161.1 5176.7 5199.1 5638.0 5640.5 5661.6 5662.3 5688.1 5577.4 5696.2 5703.1 5705.8 5734.3 5233.7 5339.1 5361.5 5366.0 5411.5 5716.4 5761.7 5764.9 5767.2 5768.9 5454.9 5543.6 5552.7 5553.8 5570.5 5616.3 5664.1 5669.4 5676.6 5719.2 5539.8 5562.1 5576.5 5598.1 5603.5 5459.3 5542.4 5560.6 5566.9 5603.0 4388.8 4450.3 4455.5 4493.5 4495.4 3885.9 3910.5 3912.1 3917.7 3938.5 5256.9 5272.1 5302.8 5336.5 5380.4 5671.3 5717.9 5745.1 5746.5 5779.9 5289.0 5300.4 5308.9 5311.0 5314.8 4657.7 4765.8 4798.3 4837.0 4887.1 5923.4 5923.5 5930.7 5932.6 5965.1 5674.5 5678.4 5680.4 5682.5 5692.6 5717.1 5773.5 5834.6 5868.1 5919.4 5623.6 5631.7 5634.5 5636.2 5678.7 5647.8 5704.4 5709.0 5712.7 5717.5 5373.8 5503.9 5553.2 5599.1 5640.2 5565.7 5567.6 5568.2 5572.4 5575.4 5481.5 5484.0 5486.6 5487.2 5533.9 5410.4 5508.0 5521.1 5523.2 5568.2 5398.6 5443.8 5480.3 5492.3 5538.3 08:28:01 INFO - PROCESS | 6023 | 08:28:01 INFO - PROCESS | 6023 | [#1] ext.html Cycles:180 Average:41627.72 Median:34965.24 stddev:67938.92 (194.3%) stddev-sans-first:68059.42 08:28:01 INFO - PROCESS | 6023 | Values: 527.5 535.4 537.7 560.3 562.5 27379.7 27690.6 34300.1 34960.0 35034.1 38433.4 38796.0 38866.6 38893.4 38952.0 7284.6 8320.0 8476.3 8481.7 8485.6 7384.9 7486.7 9925.6 10059.0 10067.1 9889.4 9978.2 9987.5 9992.1 9998.7 9809.3 9843.2 9887.4 9911.9 9930.7 9680.3 9787.5 9793.7 9798.4 9814.3 35309.8 35803.3 39239.4 39399.2 39496.6 3203.6 3234.4 3424.8 3430.3 3439.4 29637.6 29950.7 30447.0 30606.0 31250.2 3738.3 3746.1 3778.1 3813.0 3871.1 260178.0 260813.0 261411.0 262002.0 262080.0 50066.7 50476.0 50552.0 55511.4 56482.7 48594.3 48734.5 48810.3 48990.0 49023.8 14242.5 14325.1 14336.0 14361.3 14403.2 11055.3 11160.5 11175.3 11251.2 11265.3 17411.8 17449.9 18369.5 18399.6 18409.4 6887.3 7050.3 7123.8 7197.3 7234.8 947.2 955.7 968.3 1027.5 1053.5 30038.4 30565.5 30772.5 30888.4 30920.7 971.0 971.3 1007.2 1073.8 1083.9 12943.9 12976.0 12988.9 13002.7 13060.3 306023.0 314650.0 314765.0 314962.0 316220.0 42385.4 49416.0 49729.0 49965.5 50063.5 1247.1 1341.7 1348.7 1350.0 1376.4 24494.3 24639.8 24906.9 24939.6 25087.0 34995.7 35551.6 40835.0 41529.2 45445.5 190735.0 191125.0 191863.0 192420.0 192432.0 27383.7 27946.4 27949.2 27970.6 27998.4 37299.8 38068.0 39046.6 39069.8 39237.8 32097.8 34970.5 35312.0 35962.9 36518.6 34893.8 35403.0 35526.3 35542.8 35828.0 34591.3 34612.7 34861.7 36319.5 36491.8 29030.0 30656.2 30961.1 31246.2 31488.8 23967.5 24044.6 24131.3 24327.6 24416.8 08:28:01 INFO - PROCESS | 6023 | 08:28:01 INFO - PROCESS | 6023 | [#2] jquery.html Cycles:180 Average:28536.94 Median:17812.84 stddev:87719.60 (492.5%) stddev-sans-first:87940.16 08:28:01 INFO - PROCESS | 6023 | Values: 357.4 358.9 360.4 360.9 366.3 13506.9 13891.3 16143.2 16177.9 16513.3 15832.1 16275.9 17050.1 17059.3 17081.6 5139.4 5282.3 5297.3 5305.3 5308.9 4008.6 4119.6 5236.4 5606.6 5848.0 5728.9 5731.6 5752.7 5755.8 5768.4 5582.1 5658.3 5687.2 5693.7 5695.4 5018.6 5020.7 5026.0 5030.9 5037.2 20498.5 20837.4 21082.9 21362.9 21367.7 2297.7 2317.7 2318.7 2330.2 2332.5 13008.9 13481.6 13617.3 13670.5 13699.2 1596.7 1691.1 1711.5 1821.0 1868.0 59422.4 59816.0 59996.0 60860.1 62359.0 9903.6 10223.1 10224.4 10312.8 10325.5 13272.5 13356.6 13378.2 13430.6 13453.0 7465.9 7542.7 7571.8 7616.8 7632.7 7236.2 7263.6 7267.6 7557.6 7726.1 6036.5 6047.8 6059.6 6082.0 6082.8 1687.9 1698.6 1706.0 1744.7 1756.5 423.7 427.3 429.5 457.8 897.3 10315.0 10469.9 10582.4 16068.6 16990.8 813.3 869.2 889.2 908.7 1002.6 9475.9 9986.3 10167.4 10344.9 10426.1 521787.0 533099.0 539328.0 544821.0 545485.0 74587.6 76911.0 78144.6 79102.6 80255.0 1095.7 1110.5 1110.7 1120.1 1140.2 15227.0 15437.2 15612.6 15655.5 15734.8 32181.0 32471.2 32906.0 34936.8 35268.3 45503.8 45838.1 46602.3 47878.3 51118.0 9246.2 9520.6 9653.0 9653.0 9662.0 10904.5 15914.6 16177.4 16683.3 16735.3 12074.8 13686.9 13840.2 14375.9 14401.6 13811.8 14199.6 14218.6 14323.9 14607.0 10572.9 10614.9 13166.2 13437.4 14154.2 8963.5 9080.7 9337.2 9398.5 9464.5 9524.5 9626.8 12866.4 13639.4 13992.3 08:28:01 INFO - PROCESS | 6023 | 08:28:01 INFO - PROCESS | 6023 | [#3] mootools.html Cycles:180 Average:5313.42 Median:6172.15 stddev:2730.97 (44.2%) stddev-sans-first:2721.95 08:28:01 INFO - PROCESS | 6023 | Values: 1297.8 1452.2 1460.0 1615.5 1786.8 6803.6 7407.0 7461.3 7544.7 7548.3 7405.8 7578.8 7582.6 7589.8 7592.8 1959.5 1999.7 2193.4 2198.7 2231.9 2271.8 2276.9 2280.8 2282.1 2283.9 2182.9 2251.8 2253.2 2254.6 2254.9 2180.2 2203.2 2205.7 2222.7 2250.0 2237.1 2244.9 2272.8 2279.5 2282.3 7554.8 7616.0 7617.5 7631.2 7632.2 4592.3 4678.7 4679.0 4683.6 4686.4 3013.6 3082.5 3108.7 3114.3 3148.0 983.8 1008.8 1043.1 1047.4 1090.1 10188.3 10297.0 10307.4 10312.4 10330.3 3975.2 4065.7 4127.1 4154.7 4160.7 8422.6 8435.8 8440.3 8459.1 8576.9 4631.7 4635.2 4643.5 4645.9 4650.4 4334.7 4335.0 4338.4 4343.1 4345.0 3071.4 3174.0 3210.2 3393.7 3459.1 1038.5 1048.4 1053.6 1059.2 1065.2 1081.9 1103.8 1141.3 1347.0 1348.1 6864.6 6902.1 7121.6 7422.6 7429.3 1112.5 1114.3 1117.1 1130.9 1144.7 5750.6 5821.4 5827.8 5832.5 5832.7 8850.8 9036.1 9289.9 9292.3 9306.6 9177.7 9203.4 9238.0 9243.5 9243.9 7090.3 7195.9 7215.9 7221.8 7235.2 7005.4 7016.6 7038.9 7045.0 7050.0 8012.7 8153.4 8222.8 8359.3 8368.5 8430.9 8473.8 8525.5 8623.3 8623.8 6689.1 6907.6 6908.8 6926.9 6934.6 6638.4 6723.2 6958.5 7173.4 7199.8 6593.1 6671.8 6734.6 6777.4 6856.4 6667.3 6800.3 6800.5 6827.6 6925.1 6721.4 6742.7 6746.0 6746.3 6762.2 6140.9 6171.4 6172.8 6236.1 6254.5 6241.9 6264.7 6274.4 6281.2 6283.7 08:28:01 INFO - PROCESS | 6023 | 08:28:01 INFO - PROCESS | 6023 | [#4] prototype.html Cycles:180 Average:5246.78 Median:5916.67 stddev:1568.49 (26.5%) stddev-sans-first:1528.93 08:28:01 INFO - PROCESS | 6023 | Values: 333.8 336.2 341.5 345.5 349.4 5777.2 5869.5 5873.5 5879.4 5944.1 5670.6 5848.0 5849.9 5863.0 5870.7 4556.7 4557.4 4559.4 4561.4 4570.0 4348.7 4425.6 4467.3 4553.9 4559.0 4500.5 4502.9 4504.9 4510.2 4543.8 4522.1 4527.9 4548.3 4562.3 4571.4 4005.2 4006.6 4017.2 4024.0 4030.3 5470.9 5576.2 5592.9 5651.7 5708.2 5295.0 5301.1 5306.7 5309.0 5313.7 5918.5 6248.7 6249.4 6251.6 6256.2 4279.1 4307.8 4344.8 4352.6 4362.8 6349.1 6470.3 6475.8 6494.6 6496.4 4476.2 4523.1 4525.0 4535.6 4542.5 4495.4 4574.4 4581.0 4582.8 4604.8 5205.7 5211.0 5213.5 5213.6 5216.2 6280.9 6281.3 6286.1 6287.3 6287.4 1610.1 1633.7 1635.3 1636.6 1640.0 1102.8 1111.2 1113.3 1114.0 1137.1 5917.8 5924.6 5926.3 5935.9 5936.3 6396.8 6399.6 6407.1 6408.4 6417.0 5913.8 5915.6 5921.5 5930.3 5932.6 3413.8 3507.3 3509.2 3510.9 3513.6 6787.2 6801.2 6809.8 6812.4 6813.7 6646.5 6667.0 6671.9 6682.8 6684.8 6618.5 6795.7 6796.6 6800.5 6807.6 6214.5 6420.1 6479.9 6487.4 6491.2 6268.4 6376.6 6389.8 6398.3 6446.4 6750.4 6764.5 6766.6 6768.0 6776.9 4324.7 4395.8 4425.7 4427.7 4429.4 6475.1 6477.5 6478.3 6484.9 6486.1 6269.7 6275.3 6282.2 6288.4 6316.4 6182.2 6221.6 6300.9 6301.9 6302.4 6101.3 6105.1 6107.2 6112.4 6115.0 6296.2 6310.4 6313.7 6317.5 6322.5 6069.2 6136.0 6154.3 6172.5 6177.8 08:28:01 INFO - PROCESS | 6023 | 08:28:01 INFO - PROCESS | 6023 | [#5] yui.html Cycles:180 Average:12932.85 Median:43812.67 stddev:14420.81 (32.9%) stddev-sans-first:14432.62 08:28:01 INFO - PROCESS | 6023 | Values: 828.6 856.2 861.3 867.1 881.5 11700.7 11839.7 11839.9 11874.3 11875.5 12734.5 12837.2 12845.6 12866.6 12877.0 687.8 695.6 803.0 813.0 827.3 828.7 832.0 839.2 839.8 840.7 858.1 864.5 866.4 869.7 870.8 712.7 722.4 852.9 874.5 875.4 642.6 644.2 647.4 647.9 647.9 12057.9 12130.3 12144.0 12145.9 12168.1 11677.9 11720.3 12412.9 12562.6 13074.3 10240.5 10255.8 10387.3 10438.5 10473.2 3568.4 3604.6 3612.1 3652.6 3660.7 44997.8 45011.5 45348.0 45373.3 45419.8 9929.6 10041.0 10165.4 10220.0 10281.6 18562.0 18584.2 18595.1 18595.6 18623.5 6980.6 7174.4 7296.6 7419.7 7586.4 3869.8 3930.7 3958.5 4070.2 4318.0 2733.8 2767.3 2768.7 2773.4 2787.1 520.5 526.3 530.9 532.9 534.2 607.4 631.8 643.2 647.7 657.7 8080.6 8257.1 8363.2 8423.2 8458.4 540.5 560.4 607.3 660.0 675.3 3900.9 3930.3 4015.8 4029.2 4034.9 44464.6 45211.0 45934.5 47932.5 49025.8 46242.8 46556.5 46723.3 47091.5 47139.5 41973.4 43703.3 43922.0 43968.8 44504.6 7710.7 8002.0 8127.2 8155.6 8316.3 33944.0 34254.7 35122.1 35128.8 35785.6 41509.8 41674.0 41829.6 42317.4 42322.0 10858.7 10928.0 11234.2 11287.4 11507.5 11158.9 11368.6 11436.9 12692.5 12699.2 10566.4 10837.8 10840.3 11124.3 11376.5 10333.6 10445.8 10564.3 10618.5 10681.8 10838.1 11083.6 11117.7 11329.9 11486.9 9034.8 9591.7 9624.8 9627.8 9808.0 8240.4 8325.7 8698.8 8916.7 9302.3 08:28:01 INFO - PROCESS | 6023 | -------- Summary: end -------- 08:28:01 INFO - PROCESS | 6023 | 08:28:01 INFO - TEST-INFO | 6023: exit 0 08:28:01 INFO - TEST-OK | dromaeo_css | took 1115231ms 08:28:01 INFO - TEST-START | kraken 08:28:01 INFO - operating with platform_type : linux_ 08:28:01 INFO - Initialising browser for kraken test... 08:28:01 INFO - TEST-INFO | started process 6215 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4ZkiyJ/profile http://localhost:36434/getInfo.html) 08:28:06 INFO - PROCESS | 6215 | __metrics Screen width/height:1600/1200 08:28:06 INFO - PROCESS | 6215 | colorDepth:24 08:28:06 INFO - PROCESS | 6215 | Browser inner width/height: 1024/697 08:28:06 INFO - PROCESS | 6215 | __metrics 08:28:06 INFO - PROCESS | 6215 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 08:28:06 INFO - PROCESS | 6215 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:28:08 INFO - TEST-INFO | 6215: exit 0 08:28:08 INFO - Browser initialized. 08:28:08 INFO - Running cycle 1/1 for kraken test... 08:28:08 INFO - TEST-INFO | started process 6301 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4ZkiyJ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:28:20 INFO - PROCESS | 6301 | RSS: Main: 150282240 08:28:20 INFO - PROCESS | 6301 | 08:28:22 INFO - PROCESS | 6301 | Cycle 1(1): loaded http://localhost:36434/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36434/tests/kraken/driver.html?testName=audio-beat-detection) 08:28:23 INFO - PROCESS | 6301 | RSS: Main: 183730176 08:28:23 INFO - PROCESS | 6301 | 08:28:25 INFO - PROCESS | 6301 | Cycle 1(1): loaded http://localhost:36434/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36434/tests/kraken/driver.html?testName=audio-dft) 08:28:26 INFO - PROCESS | 6301 | RSS: Main: 173047808 08:28:26 INFO - PROCESS | 6301 | 08:28:29 INFO - PROCESS | 6301 | Cycle 1(1): loaded http://localhost:36434/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36434/tests/kraken/driver.html?testName=audio-fft) 08:28:29 INFO - PROCESS | 6301 | RSS: Main: 160268288 08:28:29 INFO - PROCESS | 6301 | 08:28:31 INFO - PROCESS | 6301 | Cycle 1(1): loaded http://localhost:36434/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36434/tests/kraken/driver.html?testName=audio-oscillator) 08:28:32 INFO - PROCESS | 6301 | RSS: Main: 162791424 08:28:32 INFO - PROCESS | 6301 | 08:28:34 INFO - PROCESS | 6301 | Cycle 1(1): loaded http://localhost:36434/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36434/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:28:35 INFO - PROCESS | 6301 | RSS: Main: 164941824 08:28:35 INFO - PROCESS | 6301 | 08:28:38 INFO - PROCESS | 6301 | Cycle 1(1): loaded http://localhost:36434/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36434/tests/kraken/driver.html?testName=imaging-darkroom) 08:28:38 INFO - PROCESS | 6301 | RSS: Main: 163827712 08:28:38 INFO - PROCESS | 6301 | 08:28:42 INFO - PROCESS | 6301 | Cycle 1(1): loaded http://localhost:36434/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36434/tests/kraken/driver.html?testName=imaging-desaturate) 08:28:42 INFO - PROCESS | 6301 | RSS: Main: 164876288 08:28:42 INFO - PROCESS | 6301 | 08:28:45 INFO - PROCESS | 6301 | Cycle 1(1): loaded http://localhost:36434/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36434/tests/kraken/driver.html?testName=json-parse-financial) 08:28:45 INFO - PROCESS | 6301 | RSS: Main: 166350848 08:28:45 INFO - PROCESS | 6301 | 08:28:47 INFO - PROCESS | 6301 | Cycle 1(1): loaded http://localhost:36434/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36434/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:28:48 INFO - PROCESS | 6301 | RSS: Main: 177217536 08:28:48 INFO - PROCESS | 6301 | 08:28:49 INFO - PROCESS | 6301 | Cycle 1(1): loaded http://localhost:36434/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36434/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:28:50 INFO - PROCESS | 6301 | RSS: Main: 175312896 08:28:50 INFO - PROCESS | 6301 | 08:28:52 INFO - PROCESS | 6301 | Cycle 1(1): loaded http://localhost:36434/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36434/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:28:53 INFO - PROCESS | 6301 | RSS: Main: 186421248 08:28:53 INFO - PROCESS | 6301 | 08:28:55 INFO - PROCESS | 6301 | Cycle 1(1): loaded http://localhost:36434/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36434/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:28:56 INFO - PROCESS | 6301 | RSS: Main: 189259776 08:28:56 INFO - PROCESS | 6301 | 08:28:58 INFO - PROCESS | 6301 | Cycle 1(1): loaded http://localhost:36434/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36434/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:28:59 INFO - PROCESS | 6301 | RSS: Main: 189943808 08:28:59 INFO - PROCESS | 6301 | 08:29:01 INFO - PROCESS | 6301 | Cycle 1(1): loaded http://localhost:36434/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36434/tests/kraken/driver.html?testName=ai-astar) 08:29:01 INFO - PROCESS | 6301 | RSS: Main: 247062528 08:29:01 INFO - PROCESS | 6301 | 08:29:01 INFO - PROCESS | 6301 | __start_tp_report 08:29:01 INFO - PROCESS | 6301 | _x_x_mozilla_page_load 08:29:01 INFO - PROCESS | 6301 | _x_x_mozilla_page_load_details 08:29:01 INFO - PROCESS | 6301 | |i|pagename|runs| 08:29:01 INFO - PROCESS | 6301 | |0;ai-astar;112;113;107;113;112;112;101;111;111;111 08:29:01 INFO - PROCESS | 6301 | |1;audio-beat-detection;154;126;149;164;125;149;151;128;165;130 08:29:01 INFO - PROCESS | 6301 | |2;audio-dft;163;161;160;160;161;160;161;160;161;161 08:29:01 INFO - PROCESS | 6301 | |3;audio-fft;74;72;84;92;71;75;75;97;96;73 08:29:01 INFO - PROCESS | 6301 | |4;audio-oscillator;96;97;140;96;96;95;143;96;96;135 08:29:01 INFO - PROCESS | 6301 | |5;imaging-gaussian-blur;109;108;109;109;109;109;109;109;110;110 08:29:01 INFO - PROCESS | 6301 | |6;imaging-darkroom;146;145;145;146;146;145;145;145;146;145 08:29:01 INFO - PROCESS | 6301 | |7;imaging-desaturate;96;95;96;95;95;96;95;96;96;95 08:29:01 INFO - PROCESS | 6301 | |8;json-parse-financial;57;60;59;58;60;59;61;69;56;56 08:29:01 INFO - PROCESS | 6301 | |9;json-stringify-tinderbox;67;67;68;68;68;68;67;67;66;68 08:29:01 INFO - PROCESS | 6301 | |10;stanford-crypto-aes;86;87;87;94;86;86;88;89;88;86 08:29:01 INFO - PROCESS | 6301 | |11;stanford-crypto-ccm;120;133;116;132;118;119;128;119;115;118 08:29:01 INFO - PROCESS | 6301 | |12;stanford-crypto-pbkdf2;155;144;151;162;154;141;154;151;149;144 08:29:01 INFO - PROCESS | 6301 | |13;stanford-crypto-sha256-iterative;70;71;68;67;67;67;68;67;69;67 08:29:01 INFO - PROCESS | 6301 | __end_tp_report 08:29:01 INFO - PROCESS | 6301 | __start_cc_report 08:29:01 INFO - PROCESS | 6301 | _x_x_mozilla_cycle_collect,947 08:29:01 INFO - PROCESS | 6301 | __end_cc_report 08:29:01 INFO - PROCESS | 6301 | __startTimestamp1451838541344__endTimestamp 08:29:01 INFO - PROCESS | 6301 | 08:29:01 INFO - PROCESS | 6301 | ------- Summary: start ------- 08:29:01 INFO - PROCESS | 6301 | Number of tests: 14 08:29:01 INFO - PROCESS | 6301 | 08:29:01 INFO - PROCESS | 6301 | [#0] ai-astar Cycles:10 Average:110.30 Median:112.00 stddev:3.68 (3.3%) stddev-sans-first:3.86 08:29:01 INFO - PROCESS | 6301 | Values: 112.0 113.0 107.0 113.0 112.0 112.0 101.0 111.0 111.0 111.0 08:29:01 INFO - PROCESS | 6301 | 08:29:01 INFO - PROCESS | 6301 | [#1] audio-beat-detection Cycles:10 Average:144.10 Median:150.00 stddev:15.55 (10.4%) stddev-sans-first:16.08 08:29:01 INFO - PROCESS | 6301 | Values: 154.0 126.0 149.0 164.0 125.0 149.0 151.0 128.0 165.0 130.0 08:29:01 INFO - PROCESS | 6301 | 08:29:01 INFO - PROCESS | 6301 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.92 (0.6%) stddev-sans-first:0.53 08:29:01 INFO - PROCESS | 6301 | Values: 163.0 161.0 160.0 160.0 161.0 160.0 161.0 160.0 161.0 161.0 08:29:01 INFO - PROCESS | 6301 | 08:29:01 INFO - PROCESS | 6301 | [#3] audio-fft Cycles:10 Average:80.90 Median:79.50 stddev:10.42 (13.1%) stddev-sans-first:10.75 08:29:01 INFO - PROCESS | 6301 | Values: 74.0 72.0 84.0 92.0 71.0 75.0 75.0 97.0 96.0 73.0 08:29:01 INFO - PROCESS | 6301 | 08:29:01 INFO - PROCESS | 6301 | [#4] audio-oscillator Cycles:10 Average:109.00 Median:96.00 stddev:21.02 (21.9%) stddev-sans-first:21.77 08:29:01 INFO - PROCESS | 6301 | Values: 96.0 97.0 140.0 96.0 96.0 95.0 143.0 96.0 96.0 135.0 08:29:01 INFO - PROCESS | 6301 | 08:29:01 INFO - PROCESS | 6301 | [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 08:29:01 INFO - PROCESS | 6301 | Values: 109.0 108.0 109.0 109.0 109.0 109.0 109.0 109.0 110.0 110.0 08:29:01 INFO - PROCESS | 6301 | 08:29:01 INFO - PROCESS | 6301 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 08:29:01 INFO - PROCESS | 6301 | Values: 146.0 145.0 145.0 146.0 146.0 145.0 145.0 145.0 146.0 145.0 08:29:01 INFO - PROCESS | 6301 | 08:29:01 INFO - PROCESS | 6301 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 08:29:01 INFO - PROCESS | 6301 | Values: 96.0 95.0 96.0 95.0 95.0 96.0 95.0 96.0 96.0 95.0 08:29:01 INFO - PROCESS | 6301 | 08:29:01 INFO - PROCESS | 6301 | [#8] json-parse-financial Cycles:10 Average:59.50 Median:59.50 stddev:3.75 (6.3%) stddev-sans-first:3.87 08:29:01 INFO - PROCESS | 6301 | Values: 57.0 60.0 59.0 58.0 60.0 59.0 61.0 69.0 56.0 56.0 08:29:01 INFO - PROCESS | 6301 | 08:29:01 INFO - PROCESS | 6301 | [#9] json-stringify-tinderbox Cycles:10 Average:67.40 Median:68.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 08:29:01 INFO - PROCESS | 6301 | Values: 67.0 67.0 68.0 68.0 68.0 68.0 67.0 67.0 66.0 68.0 08:29:01 INFO - PROCESS | 6301 | 08:29:01 INFO - PROCESS | 6301 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:87.50 stddev:2.45 (2.8%) stddev-sans-first:2.52 08:29:01 INFO - PROCESS | 6301 | Values: 86.0 87.0 87.0 94.0 86.0 86.0 88.0 89.0 88.0 86.0 08:29:01 INFO - PROCESS | 6301 | 08:29:01 INFO - PROCESS | 6301 | [#11] stanford-crypto-ccm Cycles:10 Average:121.80 Median:119.50 stddev:6.63 (5.5%) stddev-sans-first:7.00 08:29:01 INFO - PROCESS | 6301 | Values: 120.0 133.0 116.0 132.0 118.0 119.0 128.0 119.0 115.0 118.0 08:29:01 INFO - PROCESS | 6301 | 08:29:01 INFO - PROCESS | 6301 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.50 Median:152.50 stddev:6.28 (4.1%) stddev-sans-first:6.44 08:29:01 INFO - PROCESS | 6301 | Values: 155.0 144.0 151.0 162.0 154.0 141.0 154.0 151.0 149.0 144.0 08:29:01 INFO - PROCESS | 6301 | 08:29:01 INFO - PROCESS | 6301 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:1.45 (2.1%) stddev-sans-first:1.36 08:29:01 INFO - PROCESS | 6301 | Values: 70.0 71.0 68.0 67.0 67.0 67.0 68.0 67.0 69.0 67.0 08:29:01 INFO - PROCESS | 6301 | -------- Summary: end -------- 08:29:01 INFO - PROCESS | 6301 | 08:29:02 INFO - TEST-INFO | 6301: exit 0 08:29:02 INFO - TEST-OK | kraken | took 60513ms 08:29:02 INFO - TEST-START | v8_7 08:29:02 INFO - operating with platform_type : linux_ 08:29:02 INFO - Initialising browser for v8_7 test... 08:29:02 INFO - TEST-INFO | started process 6553 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgrO2ka/profile http://localhost:36434/getInfo.html) 08:29:07 INFO - PROCESS | 6553 | __metrics Screen width/height:1600/1200 08:29:07 INFO - PROCESS | 6553 | colorDepth:24 08:29:07 INFO - PROCESS | 6553 | Browser inner width/height: 1024/697 08:29:07 INFO - PROCESS | 6553 | __metrics 08:29:07 INFO - PROCESS | 6553 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 08:29:07 INFO - PROCESS | 6553 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:29:09 INFO - TEST-INFO | 6553: exit 0 08:29:09 INFO - Browser initialized. 08:29:09 INFO - Running cycle 1/1 for v8_7 test... 08:29:09 INFO - TEST-INFO | started process 6639 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgrO2ka/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:29:20 INFO - PROCESS | 6639 | RSS: Main: 149737472 08:29:20 INFO - PROCESS | 6639 | 08:29:42 INFO - PROCESS | 6639 | Cycle 1(1): loaded http://localhost:36434/tests/v8_7/run.html (next: http://localhost:36434/tests/v8_7/run.html) 08:29:42 INFO - PROCESS | 6639 | RSS: Main: 549974016 08:29:42 INFO - PROCESS | 6639 | 08:29:42 INFO - PROCESS | 6639 | __start_tp_report 08:29:42 INFO - PROCESS | 6639 | _x_x_mozilla_page_load 08:29:42 INFO - PROCESS | 6639 | _x_x_mozilla_page_load_details 08:29:42 INFO - PROCESS | 6639 | |i|pagename|runs| 08:29:42 INFO - PROCESS | 6639 | |0;Richards;168.4352366515075 08:29:42 INFO - PROCESS | 6639 | |1;DeltaBlue;190.47619047619048 08:29:42 INFO - PROCESS | 6639 | |2;Encrypt;282.80542986425337 08:29:42 INFO - PROCESS | 6639 | |3;Decrypt;5246.073298429319 08:29:42 INFO - PROCESS | 6639 | |4;RayTrace;1978.2608695652175 08:29:42 INFO - PROCESS | 6639 | |5;Earley;799.3605115907275 08:29:42 INFO - PROCESS | 6639 | |6;Boyer;11940.47619047619 08:29:42 INFO - PROCESS | 6639 | |7;RegExp;26973.684210526317 08:29:42 INFO - PROCESS | 6639 | |8;Splay;592.7015891701001 08:29:42 INFO - PROCESS | 6639 | |9;NavierStokes;4830.917874396136 08:29:42 INFO - PROCESS | 6639 | __end_tp_report 08:29:42 INFO - PROCESS | 6639 | __startTimestamp1451838582486__endTimestamp 08:29:42 INFO - PROCESS | 6639 | 08:29:42 INFO - PROCESS | 6639 | ------- Summary: start ------- 08:29:42 INFO - PROCESS | 6639 | Number of tests: 10 08:29:42 INFO - PROCESS | 6639 | 08:29:42 INFO - PROCESS | 6639 | [#0] Richards Cycles:1 Average:168.44 Median:168.44 stddev:0.00 (0.0%) 08:29:42 INFO - PROCESS | 6639 | Values: 168.4 08:29:42 INFO - PROCESS | 6639 | 08:29:42 INFO - PROCESS | 6639 | [#1] DeltaBlue Cycles:1 Average:190.48 Median:190.48 stddev:0.00 (0.0%) 08:29:42 INFO - PROCESS | 6639 | Values: 190.5 08:29:42 INFO - PROCESS | 6639 | 08:29:42 INFO - PROCESS | 6639 | [#2] Encrypt Cycles:1 Average:282.81 Median:282.81 stddev:0.00 (0.0%) 08:29:42 INFO - PROCESS | 6639 | Values: 282.8 08:29:42 INFO - PROCESS | 6639 | 08:29:42 INFO - PROCESS | 6639 | [#3] Decrypt Cycles:1 Average:5246.07 Median:5246.07 stddev:0.00 (0.0%) 08:29:42 INFO - PROCESS | 6639 | Values: 5246.1 08:29:42 INFO - PROCESS | 6639 | 08:29:42 INFO - PROCESS | 6639 | [#4] RayTrace Cycles:1 Average:1978.26 Median:1978.26 stddev:0.00 (0.0%) 08:29:42 INFO - PROCESS | 6639 | Values: 1978.3 08:29:42 INFO - PROCESS | 6639 | 08:29:42 INFO - PROCESS | 6639 | [#5] Earley Cycles:1 Average:799.36 Median:799.36 stddev:0.00 (0.0%) 08:29:42 INFO - PROCESS | 6639 | Values: 799.4 08:29:42 INFO - PROCESS | 6639 | 08:29:42 INFO - PROCESS | 6639 | [#6] Boyer Cycles:1 Average:11940.48 Median:11940.48 stddev:0.00 (0.0%) 08:29:42 INFO - PROCESS | 6639 | Values: 11940.5 08:29:42 INFO - PROCESS | 6639 | 08:29:42 INFO - PROCESS | 6639 | [#7] RegExp Cycles:1 Average:26973.68 Median:26973.68 stddev:0.00 (0.0%) 08:29:42 INFO - PROCESS | 6639 | Values: 26973.7 08:29:42 INFO - PROCESS | 6639 | 08:29:42 INFO - PROCESS | 6639 | [#8] Splay Cycles:1 Average:592.70 Median:592.70 stddev:0.00 (0.0%) 08:29:42 INFO - PROCESS | 6639 | Values: 592.7 08:29:42 INFO - PROCESS | 6639 | 08:29:42 INFO - PROCESS | 6639 | [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 08:29:42 INFO - PROCESS | 6639 | Values: 4830.9 08:29:42 INFO - PROCESS | 6639 | -------- Summary: end -------- 08:29:42 INFO - PROCESS | 6639 | 08:29:43 INFO - TEST-INFO | 6639: exit 0 08:29:43 INFO - TEST-OK | v8_7 | took 41111ms 08:29:43 INFO - SUITE-END | took 1216s 08:29:43 INFO - Completed test suite (00:20:16) 08:29:43 INFO - Working with test: dromaeo_css 08:29:43 INFO - Generating results file: dromaeo_css 08:29:43 INFO - Working with test: kraken 08:29:43 INFO - Generating results file: kraken 08:29:43 INFO - javascript benchmark 08:29:43 INFO - Working with test: v8_7 08:29:43 INFO - Generating results file: v8_7 08:29:43 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:29:43 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,201,35]] 08:29:43 INFO - process_Request line: dromaeo_css 6811.45 graph.html#tests=[[72,201,35]] 08:29:43 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:29:43 INFO - process_Request line: kraken 1510.10 graph.html#tests=[[232,201,35]] 08:29:43 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:29:43 INFO - process_Request line: v8_7 19736.88 graph.html#tests=[[230,201,35]] 08:29:43 INFO - RETURN: dromaeo_css: 6811.45 08:29:43 INFO - RETURN: kraken: 1510.10 08:29:43 INFO - RETURN: v8_7: 19736.88 08:29:43 INFO - javascript benchmark 08:29:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2985.1343110497955, 3073.907782766518, 3130.107919243016, 3130.9843450782755, 3147.6537580866107, 5167.715775632339, 5307.814226502073, 5315.441878602749, 5336.30628018358, 5368.973155134224, 5268.604854635899, 5294.788208471657, 5310.522053015228, 5331.786728530854, 5380.21986811912, 4785.330053792463, 4801.495842936493, 4830.73430961297, 4874.46380898101, 4885.31605575218, 4766.952330476703, 4899.265110233458, 4914.827981020665, 4940.925886111711, 4973.925391119118, 4759.90480190396, 4762.166620841356, 4852.975735121302, 4857.2714092886, 4868.367112275417, 4839.7096174229655, 4845.079434907357, 4852.8544143675745, 4879.2925025871045, 4907.975460122712, 4742.905141897178, 4817.084753896784, 4829.6860704054125, 4862.100511405366, 4869.269609558559, 5080.288759573663, 5153.9742301288625, 5161.096808058613, 5176.689398636094, 5199.090159222121, 5638.0, 5640.520555752766, 5661.631993920382, 5662.2639056922335, 5688.11834165705, 5577.442255774431, 5696.1740547620775, 5703.144528320744, 5705.800296989627, 5734.283214598176, 5233.685978841282, 5339.092354299701, 5361.544268737123, 5366.034113859504, 5411.458854114597, 5716.428357164334, 5761.740721667451, 5764.884702305931, 5767.192593036978, 5768.942310576925, 5454.945450545444, 5543.556515478752, 5552.7223638817895, 5553.777848885999, 5570.498655120978, 5616.269884914935, 5664.0937450007805, 5669.433056694297, 5676.631018983753, 5719.19931209631, 5539.806106786243, 5562.137868630369, 5576.498115169654, 5598.10430331145, 5603.5237004854225, 5459.262999495097, 5542.39033706292, 5560.555155587544, 5566.860828479321, 5602.971985140048, 4388.780560971939, 4450.287953927356, 4455.532169122261, 4493.528179541101, 4495.370648109268, 3885.902852428712, 3910.4720862683744, 3912.1197730510976, 3917.7061720371084, 3938.4879965604287, 5256.894862102813, 5272.077386457355, 5302.814401496006, 5336.519713225828, 5380.381256155514, 5671.262735844314, 5717.885642287131, 5745.109508026263, 5746.482816546531, 5779.88440231193, 5289.0, 5300.390455097636, 5308.893822123613, 5311.017461769585, 5314.813981510706, 4657.720536767804, 4765.761711914391, 4798.304245884327, 4837.0, 4887.095887260868, 5923.437273459101, 5923.526117910556, 5930.703464826827, 5932.584719069624, 5965.13505541694, 5674.4892959633835, 5678.403767604342, 5680.403557626555, 5682.488576028178, 5692.601517893873, 5717.142428635738, 5773.509251713652, 5834.562407819346, 5868.09044598088, 5919.4080591940465, 5623.606347555796, 5631.746571404215, 5634.52106570946, 5636.154576813511, 5678.716064196857, 5647.830565083054, 5704.3725190228215, 5709.0, 5712.685802280915, 5717.514011309086, 5373.8387848363045, 5503.944960550343, 5553.194786755817, 5599.132134519156, 5640.182173584725, 5565.693886836263, 5567.582431317586, 5568.16477528374, 5572.442755724557, 5575.358833734173, 5481.5340696041285, 5484.0, 5486.643368181056, 5487.176923461513, 5533.916991245215, 5410.404855465817, 5508.0, 5521.061419558604, 5523.171524271231, 5568.220449137043, 5398.5951053671915, 5443.755031023693, 5480.260164877691, 5492.286002819608, 5538.307711536058], "unit": "score", "name": "dojo.html", "value": 5226.30669300486}, {"lowerIsBetter": false, "replicates": [527.4619887714531, 535.3575709149022, 537.7015756255279, 560.2716468590833, 562.5162360370084, 27379.726202737966, 27690.584641230373, 34300.14249643757, 34960.0, 35034.12414689634, 38433.42349864754, 38796.0, 38866.611333886656, 38893.41659875104, 38952.0, 7284.562926224444, 8320.043195032558, 8476.321894248445, 8481.703140390087, 8485.618147183392, 7384.852302953937, 7486.737964171255, 9925.602975880993, 10059.044390782863, 10067.093961543496, 9889.406635601887, 9978.20174386051, 9987.450690212036, 9992.050755178283, 9998.650047248348, 9809.264305177141, 9843.212542996615, 9887.357321774134, 9911.851322230137, 9930.652427165087, 9680.27397945157, 9787.46168960707, 9793.657221997268, 9798.363106398061, 9814.312998090136, 35309.823450882584, 35803.31475139375, 39239.41140882889, 39399.21201575981, 39496.60503394958, 3203.583534140547, 3234.3944434253535, 3424.7945123292434, 3430.279641275335, 3439.4496880499, 29637.555436668466, 29950.70049299501, 30447.0, 30606.0, 31250.218744531343, 3738.2523495301048, 3746.0822098586023, 3778.149916268845, 3812.9618703812885, 3871.1096447816876, 260177.9999999981, 260813.0, 261411.0, 262002.0, 262080.0, 50066.74966625144, 50476.0, 50552.0, 55511.44488555063, 56482.7175864118, 48594.27108593374, 48734.51265487371, 48810.26784598234, 48990.0, 49023.75488122608, 14242.501512447221, 14325.068870523382, 14336.0, 14361.281935903164, 14403.207823569805, 11055.281406708538, 11160.497777600027, 11175.273607215668, 11251.212415130862, 11265.267757595893, 17411.825881741275, 17449.912750436168, 18369.540761480803, 18399.632007359778, 18409.447716568524, 6887.345702158287, 7050.294970503011, 7123.786286411312, 7197.316254955771, 7234.782956511313, 947.2327414794039, 955.7467271173144, 968.2689569375218, 1027.547878933267, 1053.4574694032578, 30038.399232015236, 30565.541516877263, 30772.53841192384, 30888.382232355227, 30920.69079309223, 971.0435221307007, 971.2715463402449, 1007.2194049611463, 1073.8281874899978, 1083.9458027098613, 12943.870561294454, 12976.0, 12988.870111298766, 13002.739945201232, 13060.34698265083, 306023.0, 314650.0, 314765.0, 314962.0, 316220.0, 42385.36421953673, 49416.0, 49729.0, 49965.500344996086, 50063.49936500661, 1247.077162899466, 1341.7249463859885, 1348.716769478401, 1349.9865001350058, 1376.4494202319152, 24494.265172044867, 24639.75360246446, 24906.875465622557, 24939.625905611792, 25087.0, 34995.65004349924, 35551.64448355483, 40835.0, 41529.1694166121, 45445.54554454546, 190735.0, 191125.0, 191863.0, 192420.0, 192432.0, 27383.726162738116, 27946.44107117887, 27949.16152515428, 27970.580441293805, 27998.44003119967, 37299.81350093232, 38068.0, 39046.609533904295, 39069.80465097656, 39237.80381098076, 32097.839510802296, 34970.47544286787, 35312.0, 35962.92111236667, 36518.634813652585, 34893.8255308732, 35403.0, 35526.28947420985, 35542.822285888404, 35828.0, 34591.30817383588, 34612.65387346094, 34861.65138348685, 36319.455208172425, 36491.817540912125, 29030.0, 30656.233594160323, 30961.071167865, 31246.21884452907, 31488.842555787076, 23967.52064958726, 24044.63933041041, 24131.276061718178, 24327.635085474085, 24416.75583244145], "unit": "score", "name": "ext.html", "value": 17458.502184200504}, {"lowerIsBetter": false, "replicates": [357.3888650407803, 358.88515674984, 360.4269211952996, 360.922401683638, 366.33327344233504, 13506.864931350683, 13891.305434728274, 16143.192840357979, 16177.919110404446, 16513.339466421345, 15832.129232892186, 16275.918620406881, 17050.06224657643, 17059.317627294895, 17081.57296067599, 5139.408967968676, 5282.260483532308, 5297.311349524556, 5305.283786688786, 5308.893822123555, 4008.6392224699916, 4119.629233368996, 5236.371635403757, 5606.55147588192, 5848.035074212749, 5728.885422291552, 5731.570132240078, 5752.712364381785, 5755.769769209248, 5768.423157684219, 5582.078956972095, 5658.349289831681, 5687.203791469177, 5693.743781529841, 5695.401982791809, 5018.573421259161, 5020.748962551895, 5026.0199261143725, 5030.89938201234, 5037.244413337992, 20498.487537811674, 20837.37487875366, 21082.894585526974, 21362.89318553413, 21367.67948480774, 2297.689811875392, 2317.7218733752056, 2318.719429973185, 2330.196082351586, 2332.521833024226, 13008.934955325258, 13481.59555213345, 13617.251051192181, 13670.52153174644, 13699.178049317074, 1596.6726821001666, 1691.129068529708, 1711.4523352527071, 1821.0, 1868.0, 59422.40577594212, 59816.0, 59996.0, 60860.08709869311, 62359.0, 9903.554340054714, 10223.079922806983, 10224.437655928996, 10312.845307320396, 10325.48372581368, 13272.535461258809, 13356.599302020955, 13378.197308161542, 13430.597082087554, 13453.0, 7465.925340746631, 7542.736004239825, 7571.772846814604, 7616.771496855104, 7632.732854350071, 7236.204017558064, 7263.564186148848, 7267.63661816907, 7557.584332861636, 7726.072871255373, 6036.547258955596, 6047.78832740852, 6059.6364218147055, 6082.0, 6082.847928801815, 1687.8902871313326, 1698.5668654493024, 1705.9914700426418, 1744.7208446648476, 1756.47305808257, 423.6737711961834, 427.3333599584658, 429.4524481286385, 457.82144963464276, 897.2732087009543, 10315.0, 10469.9476502617, 10582.365058096539, 16068.598285042968, 16990.830091699172, 813.3005615170948, 869.1873098652779, 889.1952782731638, 908.6683360573476, 1002.5989604158395, 9475.90524094764, 9986.251031172545, 10167.440790756431, 10344.948275258575, 10426.113780328606, 521787.0, 533099.0, 539328.0, 544821.0, 545485.0, 74587.62706186435, 76911.0, 78144.60927695326, 79102.6044869772, 80255.0, 1095.6548687163677, 1110.4836251143256, 1110.6612483192703, 1120.081533142815, 1140.1676775953601, 15227.0, 15437.15095669749, 15612.609684757972, 15655.452059177875, 15734.842651573337, 32181.0, 32471.18822029468, 32906.0, 34936.825315873255, 35268.29463410666, 45503.772481137385, 45838.08323833438, 46602.300965484865, 47878.28182577195, 51117.97764044624, 9246.214071803703, 9520.571574279313, 9652.951735241279, 9653.0, 9662.033796620282, 10904.509297081491, 15914.60213494672, 16177.4337898173, 16683.332666693197, 16735.33058677688, 12074.75850483003, 13686.863131368558, 13840.169589824643, 14375.928120359331, 14401.63995900111, 13811.79282310746, 14199.574012779633, 14218.644533886321, 14323.928380358031, 14607.0, 10572.947135264274, 10614.946925265323, 13166.210027398387, 13437.395317210554, 14154.221517816619, 8963.462192268486, 9080.682176123804, 9337.159655631025, 9398.483083430478, 9464.526773661428, 9524.476153811607, 9626.759331016781, 12866.35668216604, 13639.386227619583, 13992.300384980914], "unit": "score", "name": "jquery.html", "value": 8673.94281854206}, {"lowerIsBetter": false, "replicates": [1297.779377505824, 1452.150491962202, 1460.043671395236, 1615.450746746106, 1786.794518630357, 6803.625800580966, 7407.0, 7461.2911773381575, 7544.735934242296, 7548.320651141396, 7405.777826665195, 7578.848423031536, 7582.620868956557, 7589.772306830804, 7592.810179745509, 1959.480737604536, 1999.660057790179, 2193.4297082758517, 2198.6921830943643, 2231.94420139497, 2271.761465046171, 2276.8747718875456, 2280.7605201453857, 2282.0985710644286, 2283.851549649276, 2182.9126834926583, 2251.8423710340285, 2253.2451628704403, 2254.5941730488507, 2254.86470811751, 2180.2151225558787, 2203.1627981367137, 2205.71325727656, 2222.744384395799, 2250.0212407602744, 2237.071615279657, 2244.932652020442, 2272.7954484096344, 2279.4985103277254, 2282.281081459335, 7554.77335679925, 7616.0, 7617.46677732559, 7631.2368763123795, 7632.236776322379, 4592.265237562007, 4678.719276843401, 4679.0174063446375, 4683.625309975194, 4686.390769200017, 3013.623297087864, 3082.475979083561, 3108.704673056056, 3114.345987342661, 3148.039847846406, 983.8032393521324, 1008.7730260691359, 1043.0820877627714, 1047.3715770537692, 1090.0516550600948, 10188.337758045704, 10297.0, 10307.381557106597, 10312.381257124522, 10330.27688061836, 3975.185087057146, 4065.735727177724, 4127.050778320943, 4154.667626589866, 4160.729552579133, 8422.578871056545, 8435.789105272417, 8440.282575981111, 8459.111793261742, 8576.914230857736, 4631.722096674211, 4635.23518619424, 4643.512431194676, 4645.883852903705, 4650.372199753057, 4334.674899382559, 4335.046289816235, 4338.370936214231, 4343.087951530151, 4344.956550434518, 3071.447139514909, 3174.0, 3210.1814037420777, 3393.7285017198565, 3459.117924929177, 1038.5067093130854, 1048.3866937841449, 1053.6470282455464, 1059.247933966892, 1065.248999455382, 1081.8864019277862, 1103.784761971424, 1141.3266172957995, 1346.9964876167319, 1348.0630961481677, 6864.553804002724, 6902.068220790129, 7121.572705637678, 7422.6288685565505, 7429.257074292527, 1112.4660163121584, 1114.2590177531852, 1117.0895719988184, 1130.886911308879, 1144.7019394971376, 5750.6262092963825, 5821.359650438449, 5827.7668893244645, 5832.533397328203, 5832.679202643811, 8850.822983540293, 9036.096390360912, 9289.860652090225, 9292.303077269096, 9306.627734890528, 9177.724668259963, 9203.447793132433, 9237.953810230905, 9243.491607961627, 9243.861342079874, 7090.326418990187, 7195.856082878313, 7215.855682886313, 7221.819454513679, 7235.204127546071, 7005.369516743313, 7016.5790052597, 7038.8944165838575, 7045.01369808217, 7050.048243487064, 8012.719554815553, 8153.429259951747, 8222.794430139294, 8359.331253499584, 8368.497890126611, 8430.873536897074, 8473.78815529592, 8525.488470691778, 8623.267022303175, 8623.82752344937, 6689.063531105747, 6907.620080895398, 6908.758193463204, 6926.9307306926285, 6934.618595977269, 6638.369354911276, 6723.193216814014, 6958.547694399847, 7173.390261827594, 7199.820004499929, 6593.0769692244085, 6671.766488172891, 6734.629595372302, 6777.356151165632, 6856.41720453767, 6667.333266673294, 6800.319968003358, 6800.455963522807, 6827.556208846409, 6925.134358205224, 6721.428678562182, 6742.6628668567355, 6746.0, 6746.291639377991, 6762.188537375547, 6140.907886381798, 6171.444569988723, 6172.845678858064, 6236.064590311489, 6254.468370188585, 6241.937580624317, 6264.655443950626, 6274.372562743689, 6281.152044473937, 6283.74865005413], "unit": "score", "name": "mootools.html", "value": 4379.939721478473}, {"lowerIsBetter": false, "replicates": [333.76969890775365, 336.1562478179768, 341.48947323750997, 345.5404312264688, 349.41124205713373, 5777.248957635512, 5869.501092407145, 5873.500752436043, 5879.441453061958, 5944.078667806486, 5670.6030577859565, 5848.035074212749, 5849.853753656161, 5863.0, 5870.677112758796, 4556.6582506312, 4557.407537020199, 4559.407277053979, 4561.429821272341, 4569.977150114245, 4348.717333373336, 4425.623821975136, 4467.285234362503, 4553.863384098482, 4558.97720511397, 4500.549945005506, 4502.909941801178, 4504.909901801962, 4510.188166130095, 4543.7955292011775, 4522.140793249305, 4527.886802829923, 4548.31775233716, 4562.338460923164, 4571.428571428572, 4005.218982298453, 4006.599340065999, 4017.216642754664, 4023.97988010061, 4030.2946984277633, 5470.9452905471235, 5576.191452239403, 5592.86017849557, 5651.745671444794, 5708.20085188074, 5294.97352513235, 5301.072312345363, 5306.70813105279, 5308.973455132699, 5313.734313284359, 5918.526517878559, 6248.718807653667, 6249.437550620421, 6251.593646412968, 6256.15541901842, 4279.1013887083745, 4307.784610769449, 4344.826206951718, 4352.586504282088, 4362.781860906942, 6349.1428657131155, 6470.288268290531, 6475.805725828232, 6494.6103233806125, 6496.350364963513, 4476.216662084187, 4523.050159466483, 4524.9547504524535, 4535.591796738244, 4542.500324964251, 4495.43807024122, 4574.405327307429, 4581.037982023746, 4582.839600613963, 4604.8388306409115, 5205.6616319939085, 5211.0, 5213.452587478336, 5213.582913366921, 5216.217567364925, 6280.905786413207, 6281.3404592518045, 6286.119943207955, 6287.339829317947, 6287.402696743802, 1610.0822531157128, 1633.7222672145763, 1635.30499537696, 1636.6399392133712, 1640.0, 1102.823548232295, 1111.1777284809184, 1113.287496002559, 1113.9944300278446, 1137.056243318044, 5917.757270973144, 5924.644521328734, 5926.318473375531, 5935.910961335583, 5936.257967754031, 6396.776112836028, 6399.648019358887, 6407.103005579223, 6408.423241908251, 6417.0, 5913.793017244376, 5915.585908986416, 5921.496672782861, 5930.258717660293, 5932.644041357532, 3413.7781044232047, 3507.316073365669, 3509.1578021275222, 3510.8946731598094, 3513.5783705954937, 6787.151606049244, 6801.18385793708, 6809.761658341934, 6812.386885180357, 6813.693383797741, 6646.53474256807, 6667.033280174348, 6671.899921501182, 6682.799516014527, 6684.83287917806, 6618.503612229102, 6795.694193761194, 6796.558223715541, 6800.489963252776, 6807.591544507345, 6214.5028397728975, 6420.133282006869, 6479.902801457888, 6487.3836985486305, 6491.188601424822, 6268.373162683695, 6376.617402955838, 6389.808305750835, 6398.328175541687, 6446.387593178641, 6750.35871592198, 6764.526483146133, 6766.594004359754, 6767.966160169168, 6776.898346524905, 4324.718893271927, 4395.824167033283, 4425.712328698624, 4427.734335939854, 4429.357743127293, 6475.125858009108, 6477.481801455967, 6478.287388387368, 6484.902726459201, 6486.124373209556, 6269.655168965568, 6275.309715931336, 6282.2147231596055, 6288.371162883675, 6316.368363163647, 6182.165407670087, 6221.564490485623, 6300.873982520232, 6301.873962520815, 6302.369763023661, 6101.298350689727, 6105.114758359924, 6107.236595425572, 6112.449879510687, 6114.960456722279, 6296.244450665949, 6310.368963103653, 6313.652749098844, 6317.494600431863, 6322.49420046386, 6069.211002569814, 6135.969320153371, 6154.323024467394, 6172.506199504119, 6177.84555386119], "unit": "score", "name": "prototype.html", "value": 4744.591180930316}, {"lowerIsBetter": false, "replicates": [828.5981299069954, 856.2336708645759, 861.2636196052376, 867.0679020053442, 881.5283823154614, 11700.707482312935, 11839.70400739982, 11839.940800296008, 11874.28754274743, 11875.465604047817, 12734.490620375174, 12837.229766214034, 12845.614631561044, 12866.614001579945, 12877.0, 687.7558466744309, 695.6417445015821, 802.9879551806728, 812.959352032399, 827.3174630929477, 828.7265202483167, 831.9833603327929, 839.236294971576, 839.8278352937664, 840.7141571865565, 858.0818524179131, 864.4640322999749, 866.3891956170916, 869.7477731457869, 870.7735988642935, 712.6899798587631, 722.4401089155893, 852.8848605438249, 874.4753148111146, 875.3522393428881, 642.6369101457661, 644.2430144580146, 647.4334956912701, 647.8509942713155, 647.8574713563008, 12057.93971030148, 12130.332831694253, 12144.0, 12145.939270303592, 12168.08739344544, 11677.88322116785, 11720.296782193096, 12412.875871241353, 12562.623121306375, 13074.266752622407, 10240.539175737109, 10255.79488410235, 10387.27289089764, 10438.530266137966, 10473.162147028219, 3568.4290513517712, 3604.6215147409407, 3612.1150318171954, 3652.6347365263528, 3660.670539651444, 44997.77501112474, 45011.54988450139, 45348.0, 45373.319400209024, 45419.77290113561, 9929.553170107363, 10041.0, 10165.44090075053, 10220.0, 10281.640142594973, 18562.0, 18584.163712632962, 18595.070246487623, 18595.58257426553, 18623.53441163982, 6980.581165130108, 7174.390176835028, 7296.562206267641, 7419.703211871572, 7586.431017673696, 3869.845206191721, 3930.718480532541, 3958.524977002778, 4070.1656160487028, 4317.978410107929, 2733.7539621434166, 2767.2943399433016, 2768.6539182602173, 2773.362126710868, 2787.1499192746405, 520.5055197562331, 526.3000209721062, 530.8885134121878, 532.8827657915252, 534.1800336483557, 607.3926073926074, 631.8072987738784, 643.1831573901119, 647.6826355086068, 657.6921166360321, 8080.555569443737, 8257.091719910804, 8363.247307742333, 8423.199796019368, 8458.365622578209, 540.4757385336296, 560.4003716023815, 607.3440684061203, 659.9901001484982, 675.2707076357522, 3900.8829735107424, 3930.253251882133, 4015.7791321477016, 4029.194161167778, 4034.919301613951, 44464.55535444669, 45211.0, 45934.540654593024, 47932.5206747928, 49025.754871225414, 46242.76878615585, 46556.5344346559, 46723.29915051277, 47091.5290847094, 47139.528604713516, 41973.37039944403, 43703.344449833276, 43922.0, 43968.78015609965, 44504.55495445069, 7710.730124445618, 8001.959990200012, 8127.187281271826, 8155.5514446706, 8316.334693224406, 33944.0, 34254.65745342515, 35122.12194695153, 35128.82435587806, 35785.64214357823, 41509.79245103755, 41674.0, 41829.58170418257, 42317.36523952205, 42322.0, 10858.67423977282, 10928.0, 11234.157438192331, 11287.379194144401, 11507.539698411969, 11158.88841111611, 11368.602098926498, 11436.885631143583, 12692.492300307884, 12699.174553653984, 10566.41884696349, 10837.837432438362, 10840.349579025284, 11124.276922000045, 11376.488058037243, 10333.638322658671, 10445.791084178121, 10564.260501764802, 10618.522166502371, 10681.839772403575, 10838.132949363875, 11083.556657733923, 11117.722056948642, 11329.886701132884, 11486.885131148581, 9034.819303614022, 9591.712248632552, 9624.75938101553, 9627.759306017406, 9807.950960245153, 8240.381971352292, 8325.708600199207, 8698.826023479369, 8916.687915922912, 9302.302327325342], "unit": "score", "name": "yui.html", "value": 6071.756154531983}], "name": "dromaeo_css", "value": 6811.449829887664}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 113.0, 107.0, 113.0, 112.0, 112.0, 101.0, 111.0, 111.0, 111.0], "unit": "score", "name": "ai-astar", "value": 110.3}, {"lowerIsBetter": true, "replicates": [154.0, 126.0, 149.0, 164.0, 125.0, 149.0, 151.0, 128.0, 165.0, 130.0], "unit": "score", "name": "audio-beat-detection", "value": 144.1}, {"lowerIsBetter": true, "replicates": [163.0, 161.0, 160.0, 160.0, 161.0, 160.0, 161.0, 160.0, 161.0, 161.0], "unit": "score", "name": "audio-dft", "value": 160.8}, {"lowerIsBetter": true, "replicates": [74.0, 72.0, 84.0, 92.0, 71.0, 75.0, 75.0, 97.0, 96.0, 73.0], "unit": "score", "name": "audio-fft", "value": 80.9}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 140.0, 96.0, 96.0, 95.0, 143.0, 96.0, 96.0, 135.0], "unit": "score", "name": "audio-oscillator", "value": 109.0}, {"lowerIsBetter": true, "replicates": [109.0, 108.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0], "unit": "score", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [57.0, 60.0, 59.0, 58.0, 60.0, 59.0, 61.0, 69.0, 56.0, 56.0], "unit": "score", "name": "json-parse-financial", "value": 59.5}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 68.0, 68.0, 68.0, 68.0, 67.0, 67.0, 66.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.4}, {"lowerIsBetter": true, "replicates": [86.0, 87.0, 87.0, 94.0, 86.0, 86.0, 88.0, 89.0, 88.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 87.7}, {"lowerIsBetter": true, "replicates": [120.0, 133.0, 116.0, 132.0, 118.0, 119.0, 128.0, 119.0, 115.0, 118.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 121.8}, {"lowerIsBetter": true, "replicates": [155.0, 144.0, 151.0, 162.0, 154.0, 141.0, 154.0, 151.0, 149.0, 144.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 150.5}, {"lowerIsBetter": true, "replicates": [70.0, 71.0, 68.0, 67.0, 67.0, 67.0, 68.0, 67.0, 69.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.1}], "name": "kraken", "value": 1510.1}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.4352366515075], "unit": "score", "name": "Richards", "value": 209.587974}, {"lowerIsBetter": false, "replicates": [190.47619047619048], "unit": "score", "name": "DeltaBlue", "value": 347.1194999999999}, {"lowerIsBetter": false, "replicates": [282.80542986425337], "unit": "score", "name": "Encrypt", "value": 941.2160160000003}, {"lowerIsBetter": false, "replicates": [5246.073298429319], "unit": "score", "name": "Decrypt", "value": 50.739092814371276}, {"lowerIsBetter": false, "replicates": [1978.2608695652175], "unit": "score", "name": "RayTrace", "value": 374.06037362637363}, {"lowerIsBetter": false, "replicates": [799.3605115907275], "unit": "score", "name": "Earley", "value": 833.7452129999997}, {"lowerIsBetter": false, "replicates": [11940.47619047619], "unit": "score", "name": "Boyer", "value": 55.815445663010976}, {"lowerIsBetter": false, "replicates": [26973.684210526317], "unit": "score", "name": "RegExp", "value": 33.77310243902437}, {"lowerIsBetter": false, "replicates": [592.7015891701001], "unit": "score", "name": "Splay", "value": 137.49077358490564}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 19736.875844588038}]} 08:29:43 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,201,35]]", "result": "19736.88"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,201,35]]", "result": "1510.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,201,35]]", "result": "6811.45"}}} 08:29:43 INFO - Return code: 0 08:29:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 08:29:43 INFO - # TBPL SUCCESS # 08:29:43 INFO - Running post-action listener: _resource_record_post_action 08:29:43 INFO - Running post-run listener: _resource_record_post_run 08:29:44 INFO - Total resource usage - Wall time: 1235s; CPU: 13.0%; Read bytes: 4096; Write bytes: 561307648; Read time: 4; Write time: 1753780 08:29:44 INFO - install - Wall time: 17s; CPU: 18.0%; Read bytes: 0; Write bytes: 246300672; Read time: 0; Write time: 827444 08:29:45 INFO - run-tests - Wall time: 1219s; CPU: 13.0%; Read bytes: 4096; Write bytes: 313360384; Read time: 4; Write time: 926268 08:29:45 INFO - Running post-run listener: _upload_blobber_files 08:29:45 INFO - Blob upload gear active. 08:29:45 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 08:29:45 INFO - Files from /builds/slave/test-pgo/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:29:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'B2G-Inbound', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 08:29:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b B2G-Inbound -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 08:29:45 INFO - (blobuploader) - INFO - Open directory for files ... 08:29:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 08:29:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:29:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:46 INFO - (blobuploader) - INFO - Done attempting. 08:29:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:29:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:29:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:47 INFO - (blobuploader) - INFO - Done attempting. 08:29:47 INFO - (blobuploader) - INFO - Iteration through files over. 08:29:47 INFO - Return code: 0 08:29:47 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 08:29:47 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 08:29:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/da9a85f665d20eb4ec56b0b4e3bdd3163db740750726180c952ab816e0d26c3598280d955107459168a78608aa58bcc581875010c2ee78cd3061520bf774c814", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/4347f420dfa78ddb1a569f78ec760b7be344e59d46293f5d061019b94f78a3f98f87dd409c252e7150c6de62e040bfa192e934e243ce961d495aab01ad89a85f"} 08:29:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 08:29:47 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 08:29:47 INFO - Contents: 08:29:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/da9a85f665d20eb4ec56b0b4e3bdd3163db740750726180c952ab816e0d26c3598280d955107459168a78608aa58bcc581875010c2ee78cd3061520bf774c814", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/4347f420dfa78ddb1a569f78ec760b7be344e59d46293f5d061019b94f78a3f98f87dd409c252e7150c6de62e040bfa192e934e243ce961d495aab01ad89a85f"} 08:29:47 INFO - Copying logs to upload dir... 08:29:47 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1274.926761 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-01-03 08:29:46.458073) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-03 08:29:46.462249) ========= 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=ccb4611811c763f2fad71465000001de-1451820701.737861-850851793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/da9a85f665d20eb4ec56b0b4e3bdd3163db740750726180c952ab816e0d26c3598280d955107459168a78608aa58bcc581875010c2ee78cd3061520bf774c814", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/4347f420dfa78ddb1a569f78ec760b7be344e59d46293f5d061019b94f78a3f98f87dd409c252e7150c6de62e040bfa192e934e243ce961d495aab01ad89a85f"} build_url:https://queue.taskcluster.net/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011367 build_url: 'https://queue.taskcluster.net/v1/task/cuiJKH4dS_WgpVCrMBos5A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/da9a85f665d20eb4ec56b0b4e3bdd3163db740750726180c952ab816e0d26c3598280d955107459168a78608aa58bcc581875010c2ee78cd3061520bf774c814", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/4347f420dfa78ddb1a569f78ec760b7be344e59d46293f5d061019b94f78a3f98f87dd409c252e7150c6de62e040bfa192e934e243ce961d495aab01ad89a85f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-03 08:29:46.492062) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 08:29:46.492729) ========= 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=ccb4611811c763f2fad71465000001de-1451820701.737861-850851793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 08:29:46.576589) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-03 08:29:46.576915) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-03 08:29:46.577228) ========= ========= Total master_lag: 0.25 =========