builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-064 starttime: 1452778289.01 results: success (0) buildid: 20160114030246 builduid: 17ab2e707d59461cbabc0b1a911d506d revision: 6fa2ab99f52feb1b6ead5581b8f5d398546a55a5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.012662) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.013147) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.013454) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006301 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.036604) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.036890) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.050266) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.050653) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004572 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.099711) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.100032) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.100591) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.100973) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-14 05:31:30-- 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% 35.3M=0s 2016-01-14 05:31:30 (35.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.162756 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.278395) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.278688) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022388 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.330154) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.330462) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6fa2ab99f52feb1b6ead5581b8f5d398546a55a5 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 6fa2ab99f52feb1b6ead5581b8f5d398546a55a5 --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=a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-14 05:31:30,432 truncating revision to first 12 chars 2016-01-14 05:31:30,432 Setting DEBUG logging. 2016-01-14 05:31:30,432 attempt 1/10 2016-01-14 05:31:30,432 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6fa2ab99f52f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-14 05:31:30,710 unpacking tar archive at: mozilla-central-6fa2ab99f52f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.564533 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.907788) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.908104) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.916030) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.916515) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-14 05:31:29.917229) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-01-14 05:31:29.917751) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:31:31 INFO - MultiFileLogger online at 20160114 05:31:31 in /builds/slave/test-pgo 05:31:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:31:31 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:31:31 INFO - {'append_to_log': False, 05:31:31 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:31:31 INFO - 'blob_upload_branch': 'Firefox', 05:31:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:31:31 INFO - 'branch': 'Firefox', 05:31:31 INFO - 'buildbot_json_path': 'buildprops.json', 05:31:31 INFO - 'config_files': ('talos/linux_config.py',), 05:31:31 INFO - 'default_actions': ('clobber', 05:31:31 INFO - 'read-buildbot-config', 05:31:31 INFO - 'download-and-extract', 05:31:31 INFO - 'populate-webroot', 05:31:31 INFO - 'create-virtualenv', 05:31:31 INFO - 'install', 05:31:31 INFO - 'run-tests'), 05:31:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:31:31 INFO - 'download_minidump_stackwalk': True, 05:31:31 INFO - 'download_symbols': 'ondemand', 05:31:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:31:31 INFO - 'tooltool.py': '/tools/tooltool.py', 05:31:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:31:31 INFO - '/tools/misc-python/virtualenv.py')}, 05:31:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:31:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:31:31 INFO - 'installer_path': 'installer.exe', 05:31:31 INFO - 'log_level': 'info', 05:31:31 INFO - 'log_name': 'talos', 05:31:31 INFO - 'log_to_console': True, 05:31:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:31:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:31:31 INFO - 'opt_config_files': (), 05:31:31 INFO - 'pip_index': False, 05:31:31 INFO - 'sps_profile': False, 05:31:31 INFO - 'sps_profile_interval': 0, 05:31:31 INFO - 'suite': 'dromaeojs', 05:31:31 INFO - 'system_bits': '32', 05:31:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:31:31 INFO - 'title': 'talos-linux64-ix-064', 05:31:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:31:31 INFO - 'use_talos_json': True, 05:31:31 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:31:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:31:31 INFO - 'work_dir': 'build'} 05:31:31 INFO - ##### 05:31:31 INFO - ##### Running clobber step. 05:31:31 INFO - ##### 05:31:31 INFO - Running pre-action listener: _resource_record_pre_action 05:31:31 INFO - Running main action method: clobber 05:31:31 INFO - rmtree: /builds/slave/test-pgo/build 05:31:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:31:32 INFO - Running post-action listener: _resource_record_post_action 05:31:32 INFO - ##### 05:31:32 INFO - ##### Running read-buildbot-config step. 05:31:32 INFO - ##### 05:31:32 INFO - Running pre-action listener: _resource_record_pre_action 05:31:32 INFO - Running main action method: read_buildbot_config 05:31:32 INFO - Using buildbot properties: 05:31:32 INFO - { 05:31:32 INFO - "project": "", 05:31:32 INFO - "product": "firefox", 05:31:32 INFO - "script_repo_revision": "production", 05:31:32 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:31:32 INFO - "repository": "", 05:31:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:31:32 INFO - "buildid": "20160114030246", 05:31:32 INFO - "stage_platform": "linux64-pgo", 05:31:32 INFO - "basedir": "/builds/slave/test-pgo", 05:31:32 INFO - "nightly_build": "True", 05:31:32 INFO - "buildnumber": 302, 05:31:32 INFO - "slavename": "talos-linux64-ix-064", 05:31:32 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:31:32 INFO - "platform": "ubuntu64_hw", 05:31:32 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:31:32 INFO - "branch": "mozilla-central", 05:31:32 INFO - "repo_path": "mozilla-central", 05:31:32 INFO - "revision": "6fa2ab99f52feb1b6ead5581b8f5d398546a55a5", 05:31:32 INFO - "pgo_build": "True", 05:31:32 INFO - "builduid": "17ab2e707d59461cbabc0b1a911d506d", 05:31:32 INFO - "slavebuilddir": "test" 05:31:32 INFO - } 05:31:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 05:31:32 INFO - Running post-action listener: _resource_record_post_action 05:31:32 INFO - ##### 05:31:32 INFO - ##### Running download-and-extract step. 05:31:32 INFO - ##### 05:31:32 INFO - Running pre-action listener: _resource_record_pre_action 05:31:32 INFO - Running main action method: download_and_extract 05:31:32 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:31: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')]} 05:31:32 INFO - https://queue.taskcluster.net/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:31:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/test_packages.json 05:31:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/test_packages.json 05:31:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:31:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:31:33 INFO - Downloaded 1448 bytes. 05:31:33 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:31:33 INFO - Using the following test package requirements: 05:31:33 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:31:33 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:31:33 INFO - u'gtest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:31:33 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:33 INFO - u'jsshell-linux-x86_64.zip'], 05:31:33 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:31:33 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:31:33 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:31:33 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:31:33 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:31:33 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:31:33 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:31:33 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:31:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:33 INFO - https://queue.taskcluster.net/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:31:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:31:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:31:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/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 05:31:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/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 05:31:37 INFO - Downloaded 24030700 bytes. 05:31:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 05:31:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 05:31:39 INFO - Return code: 0 05:31:39 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 05:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:39 INFO - https://queue.taskcluster.net/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:31:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:31:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:31:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/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 05:31:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/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 05:31:49 INFO - Downloaded 24030700 bytes. 05:31:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 05:31:49 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 05:31:50 INFO - Return code: 0 05:31: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')]} 05:31:50 INFO - https://queue.taskcluster.net/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:31:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:31:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:31:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/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 05:31:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/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 05:31:52 INFO - Downloaded 11132144 bytes. 05:31:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 05:31:52 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test-pgo/build/tests 05:31:53 INFO - Return code: 0 05:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:53 INFO - https://queue.taskcluster.net/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:31:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:31:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:31:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/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 05:31:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/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 05:31:56 INFO - Downloaded 55883159 bytes. 05:31:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:31:56 INFO - mkdir: /builds/slave/test-pgo/properties 05:31:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:31:56 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:31:56 INFO - Contents: 05:31:56 INFO - build_url:https://queue.taskcluster.net/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:31:56 INFO - Running post-action listener: _resource_record_post_action 05:31:56 INFO - Running post-action listener: set_extra_try_arguments 05:31:56 INFO - ##### 05:31:56 INFO - ##### Running populate-webroot step. 05:31:56 INFO - ##### 05:31:56 INFO - Running pre-action listener: _resource_record_pre_action 05:31:56 INFO - Running main action method: populate_webroot 05:31:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:31:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:31:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:31:56 INFO - '--tppagecycles', 05:31:56 INFO - '7'], 05:31:56 INFO - 'tests': ['tsvgm']}}, 05:31:56 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:31:56 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:31:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:31:56 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:31:56 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:31:56 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:31:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:56 INFO - 'talos_options': ['--e10s'], 05:31:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:56 INFO - 'tests': ['damp', 'tps']}, 05:31:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:56 INFO - 'talos_options': ['--e10s'], 05:31:56 INFO - 'tests': ['damp', 'tps']}, 05:31:56 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:31:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:31:56 INFO - 'other': {'tests': ['a11yr', 05:31:56 INFO - 'ts_paint', 05:31:56 INFO - 'tpaint', 05:31:56 INFO - 'sessionrestore', 05:31:56 INFO - 'sessionrestore_no_auto_restore']}, 05:31:56 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:31:56 INFO - 'tests': ['a11yr', 05:31:56 INFO - 'ts_paint', 05:31:56 INFO - 'tpaint', 05:31:56 INFO - 'sessionrestore', 05:31:56 INFO - 'sessionrestore_no_auto_restore']}, 05:31:56 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:31:56 INFO - 'tests': ['a11yr', 05:31:56 INFO - 'ts_paint', 05:31:56 INFO - 'tpaint', 05:31:56 INFO - 'sessionrestore', 05:31:56 INFO - 'sessionrestore_no_auto_restore']}, 05:31:56 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:31:56 INFO - 'tests': ['a11yr', 05:31:56 INFO - 'ts_paint', 05:31:56 INFO - 'tpaint', 05:31:56 INFO - 'sessionrestore', 05:31:56 INFO - 'sessionrestore_no_auto_restore']}, 05:31:56 INFO - 'other_l64': {'tests': ['a11yr', 05:31:56 INFO - 'ts_paint', 05:31:56 INFO - 'tpaint', 05:31:56 INFO - 'sessionrestore', 05:31:56 INFO - 'sessionrestore_no_auto_restore']}, 05:31:56 INFO - 'other_nol64': {'tests': ['a11yr', 05:31:56 INFO - 'ts_paint', 05:31:56 INFO - 'tpaint', 05:31:56 INFO - 'sessionrestore', 05:31:56 INFO - 'sessionrestore_no_auto_restore']}, 05:31:56 INFO - 'svgr': {'tests': ['tsvgx', 05:31:56 INFO - 'tsvgr_opacity', 05:31:56 INFO - 'tart', 05:31:56 INFO - 'tscrollx', 05:31:56 INFO - 'cart']}, 05:31:56 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:31:56 INFO - 'tests': ['tsvgx', 05:31:56 INFO - 'tsvgr_opacity', 05:31:56 INFO - 'tart', 05:31:56 INFO - 'tscrollx', 05:31:56 INFO - 'cart']}, 05:31:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:56 INFO - 'tests': ['tp5o']}, 05:31:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:56 INFO - 'talos_options': ['--e10s'], 05:31:56 INFO - 'tests': ['tp5o']}, 05:31:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:56 INFO - 'talos_options': ['--xperf_path', 05:31:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:56 INFO - 'tests': ['tp5n']}, 05:31:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:56 INFO - 'talos_options': ['--e10s', 05:31:56 INFO - '--xperf_path', 05:31:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:56 INFO - 'tests': ['tp5n']}}, 05:31:56 INFO - 'talos.zip': {'path': '', 05:31:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:31:56 INFO - Running post-action listener: _resource_record_post_action 05:31:56 INFO - ##### 05:31:56 INFO - ##### Running create-virtualenv step. 05:31:56 INFO - ##### 05:31:56 INFO - Running pre-action listener: _resource_record_pre_action 05:31:56 INFO - Running main action method: create_virtualenv 05:31:56 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:31:56 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 05:31:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:31:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:31:56 INFO - Using real prefix '/usr' 05:31:56 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:31:57 INFO - Installing distribute.............................................................................................................................................................................................done. 05:31:59 INFO - Installing pip.................done. 05:31:59 INFO - Return code: 0 05:31:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:31: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')]} 05:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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')]} 05:31:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:59 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaba638f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de0360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6f540>, '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 0x1e70440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e708f0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:31:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 05:31:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:31:59 INFO - Using env: {'DISPLAY': ':0', 05:31:59 INFO - 'HOME': '/home/cltbld', 05:31:59 INFO - 'LANG': 'en_US.UTF-8', 05:31:59 INFO - 'LANGUAGE': 'en_US:en', 05:31:59 INFO - 'LOGNAME': 'cltbld', 05:31:59 INFO - 'MAIL': '/var/mail/cltbld', 05:31:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:59 INFO - 'MOZ_NO_REMOTE': '1', 05:31:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:59 INFO - 'NO_EM_RESTART': '1', 05:31:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:31:59 INFO - 'PWD': '/builds/slave/test-pgo', 05:31:59 INFO - 'SHELL': '/bin/bash', 05:31:59 INFO - 'SHLVL': '1', 05:31:59 INFO - 'TERM': 'linux', 05:31:59 INFO - 'TMOUT': '86400', 05:31:59 INFO - 'USER': 'cltbld', 05:31:59 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815', 05:31:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:59 INFO - '_': '/tools/buildbot/bin/python'} 05:31:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:31:59 INFO - Cleaning up... 05:31:59 INFO - Return code: 0 05:31:59 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:31: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')]} 05:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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')]} 05:31:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:59 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fbaba638f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de0360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6f540>, '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 0x1e70440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e708f0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:31:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 05:31:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:31:59 INFO - Using env: {'DISPLAY': ':0', 05:31:59 INFO - 'HOME': '/home/cltbld', 05:31:59 INFO - 'LANG': 'en_US.UTF-8', 05:31:59 INFO - 'LANGUAGE': 'en_US:en', 05:31:59 INFO - 'LOGNAME': 'cltbld', 05:31:59 INFO - 'MAIL': '/var/mail/cltbld', 05:31:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:59 INFO - 'MOZ_NO_REMOTE': '1', 05:31:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:59 INFO - 'NO_EM_RESTART': '1', 05:31:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:31:59 INFO - 'PWD': '/builds/slave/test-pgo', 05:31:59 INFO - 'SHELL': '/bin/bash', 05:31:59 INFO - 'SHLVL': '1', 05:31:59 INFO - 'TERM': 'linux', 05:31:59 INFO - 'TMOUT': '86400', 05:31:59 INFO - 'USER': 'cltbld', 05:31:59 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815', 05:31:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:59 INFO - '_': '/tools/buildbot/bin/python'} 05:31:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:59 INFO - Downloading/unpacking psutil>=0.7.1 05:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:01 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:32:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:32:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:32:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:32:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:32:01 INFO - Installing collected packages: psutil 05:32:01 INFO - Running setup.py install for psutil 05:32:01 INFO - building 'psutil._psutil_linux' extension 05:32:01 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:32:01 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:32:01 INFO - building 'psutil._psutil_posix' extension 05:32:01 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:32:01 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:32:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:32:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:32:01 INFO - Successfully installed psutil 05:32:01 INFO - Cleaning up... 05:32:01 INFO - Return code: 0 05:32:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:32:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:01 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fbaba638f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de0360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6f540>, '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 0x1e70440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e708f0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:32:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 05:32:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:32:01 INFO - Using env: {'DISPLAY': ':0', 05:32:01 INFO - 'HOME': '/home/cltbld', 05:32:01 INFO - 'LANG': 'en_US.UTF-8', 05:32:01 INFO - 'LANGUAGE': 'en_US:en', 05:32:01 INFO - 'LOGNAME': 'cltbld', 05:32:01 INFO - 'MAIL': '/var/mail/cltbld', 05:32:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:01 INFO - 'MOZ_NO_REMOTE': '1', 05:32:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:01 INFO - 'NO_EM_RESTART': '1', 05:32:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:01 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:01 INFO - 'SHELL': '/bin/bash', 05:32:01 INFO - 'SHLVL': '1', 05:32:01 INFO - 'TERM': 'linux', 05:32:01 INFO - 'TMOUT': '86400', 05:32:01 INFO - 'USER': 'cltbld', 05:32:01 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815', 05:32:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:01 INFO - '_': '/tools/buildbot/bin/python'} 05:32:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:02 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:03 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:32:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:32:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:32:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:32:03 INFO - Installing collected packages: mozsystemmonitor 05:32:03 INFO - Running setup.py install for mozsystemmonitor 05:32:04 INFO - Successfully installed mozsystemmonitor 05:32:04 INFO - Cleaning up... 05:32:04 INFO - Return code: 0 05:32:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:04 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 0x7fbaba638f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de0360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6f540>, '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 0x1e70440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e708f0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:32:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 05:32:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:32:04 INFO - Using env: {'DISPLAY': ':0', 05:32:04 INFO - 'HOME': '/home/cltbld', 05:32:04 INFO - 'LANG': 'en_US.UTF-8', 05:32:04 INFO - 'LANGUAGE': 'en_US:en', 05:32:04 INFO - 'LOGNAME': 'cltbld', 05:32:04 INFO - 'MAIL': '/var/mail/cltbld', 05:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:04 INFO - 'MOZ_NO_REMOTE': '1', 05:32:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:04 INFO - 'NO_EM_RESTART': '1', 05:32:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:04 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:04 INFO - 'SHELL': '/bin/bash', 05:32:04 INFO - 'SHLVL': '1', 05:32:04 INFO - 'TERM': 'linux', 05:32:04 INFO - 'TMOUT': '86400', 05:32:04 INFO - 'USER': 'cltbld', 05:32:04 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815', 05:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:04 INFO - '_': '/tools/buildbot/bin/python'} 05:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:04 INFO - Downloading/unpacking blobuploader==1.2.4 05:32: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 05:32: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 05:32: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 05:32: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 05:32: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 05:32: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 05:32:06 INFO - Downloading blobuploader-1.2.4.tar.gz 05:32:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:32:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:32:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:32:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:32:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:06 INFO - Downloading docopt-0.6.1.tar.gz 05:32:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:32:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:32:06 INFO - Installing collected packages: blobuploader, requests, docopt 05:32:06 INFO - Running setup.py install for blobuploader 05:32:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:32:06 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:32:06 INFO - Running setup.py install for requests 05:32:07 INFO - Running setup.py install for docopt 05:32:07 INFO - Successfully installed blobuploader requests docopt 05:32:07 INFO - Cleaning up... 05:32:07 INFO - Return code: 0 05:32:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:07 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 0x7fbaba638f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de0360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6f540>, '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 0x1e70440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e708f0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:32:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 05:32:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:32:07 INFO - Using env: {'DISPLAY': ':0', 05:32:07 INFO - 'HOME': '/home/cltbld', 05:32:07 INFO - 'LANG': 'en_US.UTF-8', 05:32:07 INFO - 'LANGUAGE': 'en_US:en', 05:32:07 INFO - 'LOGNAME': 'cltbld', 05:32:07 INFO - 'MAIL': '/var/mail/cltbld', 05:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:07 INFO - 'MOZ_NO_REMOTE': '1', 05:32:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:07 INFO - 'NO_EM_RESTART': '1', 05:32:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:07 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:07 INFO - 'SHELL': '/bin/bash', 05:32:07 INFO - 'SHLVL': '1', 05:32:07 INFO - 'TERM': 'linux', 05:32:07 INFO - 'TMOUT': '86400', 05:32:07 INFO - 'USER': 'cltbld', 05:32:07 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815', 05:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:07 INFO - '_': '/tools/buildbot/bin/python'} 05:32:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:32:07 INFO - Running setup.py (path:/tmp/pip-LdVZOU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:32:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:32:07 INFO - Running setup.py (path:/tmp/pip-bca5rn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:32:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:32:07 INFO - Running setup.py (path:/tmp/pip-boCaMH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:32:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:32:07 INFO - Running setup.py (path:/tmp/pip-lCrIng-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:32:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:32:07 INFO - Running setup.py (path:/tmp/pip-WvJJFU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:32:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:32:07 INFO - Running setup.py (path:/tmp/pip-l8gvLW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:32:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:32:07 INFO - Running setup.py (path:/tmp/pip-PCvQtn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:32:08 INFO - Running setup.py (path:/tmp/pip-kauivI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:32:08 INFO - Running setup.py (path:/tmp/pip-quyVb9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:32:08 INFO - Running setup.py (path:/tmp/pip-GM5jfC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:32:08 INFO - Running setup.py (path:/tmp/pip-26PJ4Y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:32:08 INFO - Running setup.py (path:/tmp/pip-WYvPE4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:32:08 INFO - Running setup.py (path:/tmp/pip-OACA28-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:32:08 INFO - Running setup.py (path:/tmp/pip-aHyiLa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:32:08 INFO - Running setup.py (path:/tmp/pip-1OzX3_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:32:08 INFO - Running setup.py (path:/tmp/pip-x91QEu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:32:08 INFO - Running setup.py (path:/tmp/pip-R_C7lK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:32:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:32:08 INFO - Running setup.py install for manifestparser 05:32:08 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:32:08 INFO - Running setup.py install for mozcrash 05:32:08 INFO - Running setup.py install for mozdebug 05:32:09 INFO - Running setup.py install for mozdevice 05:32:09 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:32:09 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:32:09 INFO - Running setup.py install for mozfile 05:32:09 INFO - Running setup.py install for mozhttpd 05:32:09 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:32:09 INFO - Running setup.py install for mozinfo 05:32:09 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:32:09 INFO - Running setup.py install for mozInstall 05:32:09 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:32:09 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:32:09 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:32:09 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:32:09 INFO - Running setup.py install for mozleak 05:32:09 INFO - Running setup.py install for mozlog 05:32:09 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:32:09 INFO - Running setup.py install for moznetwork 05:32:09 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:32:09 INFO - Running setup.py install for mozprocess 05:32:10 INFO - Running setup.py install for mozprofile 05:32:10 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:32:10 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:32:10 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:32:10 INFO - Running setup.py install for mozrunner 05:32:10 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:32:10 INFO - Running setup.py install for mozscreenshot 05:32:10 INFO - Running setup.py install for moztest 05:32:10 INFO - Running setup.py install for mozversion 05:32:10 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:32:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:32:10 INFO - Cleaning up... 05:32:10 INFO - Return code: 0 05:32:10 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:10 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7fbaba638f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de0360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6f540>, '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 0x1e70440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e708f0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:32:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 05:32:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:32:10 INFO - Using env: {'DISPLAY': ':0', 05:32:10 INFO - 'HOME': '/home/cltbld', 05:32:10 INFO - 'LANG': 'en_US.UTF-8', 05:32:10 INFO - 'LANGUAGE': 'en_US:en', 05:32:10 INFO - 'LOGNAME': 'cltbld', 05:32:10 INFO - 'MAIL': '/var/mail/cltbld', 05:32:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:10 INFO - 'MOZ_NO_REMOTE': '1', 05:32:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:10 INFO - 'NO_EM_RESTART': '1', 05:32:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:10 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:10 INFO - 'SHELL': '/bin/bash', 05:32:10 INFO - 'SHLVL': '1', 05:32:10 INFO - 'TERM': 'linux', 05:32:10 INFO - 'TMOUT': '86400', 05:32:10 INFO - 'USER': 'cltbld', 05:32:10 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815', 05:32:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:10 INFO - '_': '/tools/buildbot/bin/python'} 05:32:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:32:10 INFO - Running setup.py (path:/tmp/pip-WZnRTP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 05:32:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:32:10 INFO - Running setup.py (path:/tmp/pip-sSATk6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:32:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:32:11 INFO - Running setup.py (path:/tmp/pip-yZp9fY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 05:32:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:32:11 INFO - Running setup.py (path:/tmp/pip-jd7L1I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:32:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:32:11 INFO - Running setup.py (path:/tmp/pip-n2lYZN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 05:32:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:32:11 INFO - Running setup.py (path:/tmp/pip-AgOK6d-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 05:32:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:32:11 INFO - Running setup.py (path:/tmp/pip-dDSFV7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 05:32:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:32:11 INFO - Running setup.py (path:/tmp/pip-lae3Tl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 05:32:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:32:11 INFO - Running setup.py (path:/tmp/pip-1zral1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 05:32:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:32:11 INFO - Running setup.py (path:/tmp/pip-9i3rzl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:32:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:32:11 INFO - Running setup.py (path:/tmp/pip-ekqhRX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 05:32:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:32:11 INFO - Running setup.py (path:/tmp/pip-KITG09-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 05:32:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:32:11 INFO - Running setup.py (path:/tmp/pip-HJUlyV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:32:11 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)) 05:32:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:32:11 INFO - Running setup.py (path:/tmp/pip-bfIfHn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 05:32:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:32:11 INFO - Running setup.py (path:/tmp/pip-hobIy0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 05:32:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:32:12 INFO - Running setup.py (path:/tmp/pip-ynAjMC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 05:32:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:32:12 INFO - Running setup.py (path:/tmp/pip-i461Kp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 05:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 05:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:32:12 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.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:32:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:13 INFO - Downloading blessings-1.5.1.tar.gz 05:32:13 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 05:32:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:32:13 INFO - Installing collected packages: blessings 05:32:13 INFO - Running setup.py install for blessings 05:32:14 INFO - Successfully installed blessings 05:32:14 INFO - Cleaning up... 05:32:14 INFO - Return code: 0 05:32:14 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:32:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:32:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:32:14 INFO - Reading from file tmpfile_stdout 05:32:14 INFO - Current package versions: 05:32:14 INFO - argparse == 1.2.1 05:32:14 INFO - blessings == 1.5.1 05:32:14 INFO - blobuploader == 1.2.4 05:32:14 INFO - docopt == 0.6.1 05:32:14 INFO - manifestparser == 1.1 05:32:14 INFO - mozInstall == 1.12 05:32:14 INFO - mozcrash == 0.16 05:32:14 INFO - mozdebug == 0.1 05:32:14 INFO - mozdevice == 0.48 05:32:14 INFO - mozfile == 1.2 05:32:14 INFO - mozhttpd == 0.7 05:32:14 INFO - mozinfo == 0.9 05:32:14 INFO - mozleak == 0.1 05:32:14 INFO - mozlog == 3.1 05:32:14 INFO - moznetwork == 0.27 05:32:14 INFO - mozprocess == 0.22 05:32:14 INFO - mozprofile == 0.28 05:32:14 INFO - mozrunner == 6.11 05:32:14 INFO - mozscreenshot == 0.1 05:32:14 INFO - mozsystemmonitor == 0.0 05:32:14 INFO - moztest == 0.7 05:32:14 INFO - mozversion == 1.4 05:32:14 INFO - psutil == 3.1.1 05:32:14 INFO - requests == 1.2.3 05:32:14 INFO - wsgiref == 0.1.2 05:32:14 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:14 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 0x7fbaba638f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de0360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6f540>, '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 0x1e70440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e708f0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:32:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 05:32:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:32:14 INFO - Using env: {'DISPLAY': ':0', 05:32:14 INFO - 'HOME': '/home/cltbld', 05:32:14 INFO - 'LANG': 'en_US.UTF-8', 05:32:14 INFO - 'LANGUAGE': 'en_US:en', 05:32:14 INFO - 'LOGNAME': 'cltbld', 05:32:14 INFO - 'MAIL': '/var/mail/cltbld', 05:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:14 INFO - 'MOZ_NO_REMOTE': '1', 05:32:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:14 INFO - 'NO_EM_RESTART': '1', 05:32:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:14 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:14 INFO - 'SHELL': '/bin/bash', 05:32:14 INFO - 'SHLVL': '1', 05:32:14 INFO - 'TERM': 'linux', 05:32:14 INFO - 'TMOUT': '86400', 05:32:14 INFO - 'USER': 'cltbld', 05:32:14 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815', 05:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:14 INFO - '_': '/tools/buildbot/bin/python'} 05:32:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:14 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)) 05:32:14 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)) 05:32:14 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)) 05:32:14 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)) 05:32:14 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)) 05:32:14 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)) 05:32:14 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)) 05:32:14 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)) 05:32:14 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)) 05:32:14 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)) 05:32:14 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)) 05:32:14 INFO - Cleaning up... 05:32:14 INFO - Return code: 0 05:32:14 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:14 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 0x7fbaba638f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de0360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6f540>, '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 0x1e70440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e708f0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:32:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 05:32:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:32:14 INFO - Using env: {'DISPLAY': ':0', 05:32:14 INFO - 'HOME': '/home/cltbld', 05:32:14 INFO - 'LANG': 'en_US.UTF-8', 05:32:14 INFO - 'LANGUAGE': 'en_US:en', 05:32:14 INFO - 'LOGNAME': 'cltbld', 05:32:14 INFO - 'MAIL': '/var/mail/cltbld', 05:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:14 INFO - 'MOZ_NO_REMOTE': '1', 05:32:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:14 INFO - 'NO_EM_RESTART': '1', 05:32:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:14 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:14 INFO - 'SHELL': '/bin/bash', 05:32:14 INFO - 'SHLVL': '1', 05:32:14 INFO - 'TERM': 'linux', 05:32:14 INFO - 'TMOUT': '86400', 05:32:14 INFO - 'USER': 'cltbld', 05:32:14 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815', 05:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:14 INFO - '_': '/tools/buildbot/bin/python'} 05:32:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:14 INFO - Downloading/unpacking jsonschema 05:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:32:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 05:32:16 INFO - Downloading/unpacking functools32 (from jsonschema) 05:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:16 INFO - Downloading functools32-3.2.3-2.tar.gz 05:32:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:32:16 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:32:16 INFO - warning: no files found matching '*.txt' 05:32:16 INFO - no previously-included directories found matching 'build' 05:32:16 INFO - no previously-included directories found matching 'dist' 05:32:16 INFO - no previously-included directories found matching '.git*' 05:32:16 INFO - Installing collected packages: jsonschema, functools32 05:32:16 INFO - Running setup.py install for functools32 05:32:16 INFO - warning: no files found matching '*.txt' 05:32:16 INFO - no previously-included directories found matching 'build' 05:32:16 INFO - no previously-included directories found matching 'dist' 05:32:16 INFO - no previously-included directories found matching '.git*' 05:32:16 INFO - Successfully installed jsonschema functools32 05:32:16 INFO - Cleaning up... 05:32:16 INFO - Return code: 0 05:32:16 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 05:32:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:16 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 0x7fbaba638f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de0360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6f540>, '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 0x1e70440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e708f0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:32:16 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 05:32:16 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 05:32:16 INFO - Using env: {'DISPLAY': ':0', 05:32:16 INFO - 'HOME': '/home/cltbld', 05:32:16 INFO - 'LANG': 'en_US.UTF-8', 05:32:16 INFO - 'LANGUAGE': 'en_US:en', 05:32:16 INFO - 'LOGNAME': 'cltbld', 05:32:16 INFO - 'MAIL': '/var/mail/cltbld', 05:32:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:16 INFO - 'MOZ_NO_REMOTE': '1', 05:32:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:16 INFO - 'NO_EM_RESTART': '1', 05:32:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:16 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:16 INFO - 'SHELL': '/bin/bash', 05:32:16 INFO - 'SHLVL': '1', 05:32:16 INFO - 'TERM': 'linux', 05:32:16 INFO - 'TMOUT': '86400', 05:32:16 INFO - 'USER': 'cltbld', 05:32:16 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815', 05:32:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:16 INFO - '_': '/tools/buildbot/bin/python'} 05:32:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:17 INFO - Downloading/unpacking flake8 05:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:18 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:32:18 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 05:32:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:19 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:32:19 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 05:32:19 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:19 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 05:32:19 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 05:32:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 05:32:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:32:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:32:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:32:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:32:19 INFO - no previously-included directories found matching 'docs/_build' 05:32:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:32:19 INFO - Running setup.py install for pep8 05:32:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:32:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:32:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:32:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:32:19 INFO - no previously-included directories found matching 'docs/_build' 05:32:19 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 05:32:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:32:19 INFO - Cleaning up... 05:32:19 INFO - Return code: 0 05:32:19 INFO - Running post-action listener: _resource_record_post_action 05:32:19 INFO - Running post-action listener: _start_resource_monitoring 05:32:19 INFO - Starting resource monitoring. 05:32:19 INFO - ##### 05:32:19 INFO - ##### Running install step. 05:32:19 INFO - ##### 05:32:19 INFO - Running pre-action listener: _resource_record_pre_action 05:32:19 INFO - Running main action method: install 05:32:19 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:32:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:32:19 INFO - Reading from file tmpfile_stdout 05:32:19 INFO - Detecting whether we're running mozinstall >=1.0... 05:32:19 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:32:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:32:20 INFO - Reading from file tmpfile_stdout 05:32:20 INFO - Output received: 05:32:20 INFO - Usage: mozinstall [options] installer 05:32:20 INFO - Options: 05:32:20 INFO - -h, --help show this help message and exit 05:32:20 INFO - -d DEST, --destination=DEST 05:32:20 INFO - Directory to install application into. [default: 05:32:20 INFO - "/builds/slave/test-pgo"] 05:32:20 INFO - --app=APP Application being installed. [default: firefox] 05:32:20 INFO - mkdir: /builds/slave/test-pgo/build/application 05:32:20 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'] 05:32:20 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 05:32:36 INFO - Reading from file tmpfile_stdout 05:32:36 INFO - Output received: 05:32:36 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:32:36 INFO - Running post-action listener: _resource_record_post_action 05:32:36 INFO - ##### 05:32:36 INFO - ##### Running run-tests step. 05:32:36 INFO - ##### 05:32:36 INFO - Running pre-action listener: _resource_record_pre_action 05:32:36 INFO - Running main action method: run_tests 05:32:36 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:32:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:32:36 INFO - Python 2.7.3 05:32:36 INFO - Return code: 0 05:32:36 INFO - grabbing minidump binary from tooltool 05:32:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:36 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 0x1e6f540>, '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 0x1e70440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e708f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:32:36 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 05:32:36 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:32:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:32:36 INFO - Return code: 0 05:32:36 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:32:36 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:32:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:32:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:32:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:32:36 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 05:32:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 05:32:37 INFO - Return code: 0 05:32:37 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 05:32:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --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 05:32:37 INFO - Using env: {'DISPLAY': ':0', 05:32:37 INFO - 'HOME': '/home/cltbld', 05:32:37 INFO - 'LANG': 'en_US.UTF-8', 05:32:37 INFO - 'LANGUAGE': 'en_US:en', 05:32:37 INFO - 'LOGNAME': 'cltbld', 05:32:37 INFO - 'MAIL': '/var/mail/cltbld', 05:32:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:32:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:32:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:37 INFO - 'MOZ_NO_REMOTE': '1', 05:32:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:32:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:37 INFO - 'NO_EM_RESTART': '1', 05:32:37 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:37 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:37 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:32:37 INFO - 'SHELL': '/bin/bash', 05:32:37 INFO - 'SHLVL': '1', 05:32:37 INFO - 'TERM': 'linux', 05:32:37 INFO - 'TMOUT': '86400', 05:32:37 INFO - 'USER': 'cltbld', 05:32:37 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815', 05:32:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:37 INFO - '_': '/tools/buildbot/bin/python'} 05:32:37 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 05:32:37 INFO - mozversion application_buildid: 20160114030246 05:32:37 INFO - mozversion application_changeset: 6fa2ab99f52feb1b6ead5581b8f5d398546a55a5 05:32:37 INFO - mozversion application_display_name: Nightly 05:32:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:32:37 INFO - mozversion application_name: Firefox 05:32:37 INFO - mozversion application_remotingname: firefox 05:32:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:32:37 INFO - mozversion application_vendor: Mozilla 05:32:37 INFO - mozversion application_version: 46.0a1 05:32:37 INFO - mozversion platform_buildid: 20160114030246 05:32:37 INFO - mozversion platform_changeset: 6fa2ab99f52feb1b6ead5581b8f5d398546a55a5 05:32:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:32:37 INFO - mozversion platform_version: 46.0a1 05:32:37 INFO - using testdate: 1452778357 05:32:37 INFO - actual date: 1452778357 05:32:37 INFO - starting webserver on 'localhost:34478' 05:32:37 INFO - SUITE-START | Running 3 tests 05:32:37 INFO - TEST-START | dromaeo_css 05:32:37 INFO - operating with platform_type : linux_ 05:32:37 INFO - Initialising browser for dromaeo_css test... 05:32:37 INFO - TEST-INFO | started process 21154 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpX2Xm19/profile http://localhost:34478/getInfo.html) 05:32:41 INFO - PROCESS | 21154 | __metrics Screen width/height:1600/1200 05:32:41 INFO - PROCESS | 21154 | colorDepth:24 05:32:41 INFO - PROCESS | 21154 | Browser inner width/height: 1024/697 05:32:41 INFO - PROCESS | 21154 | __metrics 05:32:41 INFO - PROCESS | 21154 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:32:41 INFO - PROCESS | 21154 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:32:42 INFO - TEST-INFO | 21154: exit 0 05:32:42 INFO - Browser initialized. 05:32:42 INFO - Running cycle 1/1 for dromaeo_css test... 05:32:42 INFO - TEST-INFO | started process 21248 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpX2Xm19/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:32:54 INFO - PROCESS | 21248 | RSS: Main: 150380544 05:32:54 INFO - PROCESS | 21248 | 05:33:46 INFO - PROCESS | 21248 | 1452778426191 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:35:54 INFO - PROCESS | 21248 | Cycle 1(1): loaded http://localhost:34478/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34478/tests/dromaeo/cssquery-ext.html) 05:35:55 INFO - PROCESS | 21248 | RSS: Main: 243134464 05:35:55 INFO - PROCESS | 21248 | 05:38:57 INFO - PROCESS | 21248 | Cycle 1(1): loaded http://localhost:34478/tests/dromaeo/cssquery-ext.html (next: http://localhost:34478/tests/dromaeo/cssquery-jquery.html) 05:38:58 INFO - PROCESS | 21248 | RSS: Main: 227012608 05:38:58 INFO - PROCESS | 21248 | 05:42:00 INFO - PROCESS | 21248 | Cycle 1(1): loaded http://localhost:34478/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34478/tests/dromaeo/cssquery-mootools.html) 05:42:00 INFO - PROCESS | 21248 | RSS: Main: 242790400 05:42:00 INFO - PROCESS | 21248 | 05:45:02 INFO - PROCESS | 21248 | Cycle 1(1): loaded http://localhost:34478/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34478/tests/dromaeo/cssquery-prototype.html) 05:45:03 INFO - PROCESS | 21248 | RSS: Main: 210157568 05:45:03 INFO - PROCESS | 21248 | 05:48:05 INFO - PROCESS | 21248 | Cycle 1(1): loaded http://localhost:34478/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34478/tests/dromaeo/cssquery-yui.html) 05:48:06 INFO - PROCESS | 21248 | RSS: Main: 210685952 05:48:06 INFO - PROCESS | 21248 | 05:51:08 INFO - PROCESS | 21248 | Cycle 1(1): loaded http://localhost:34478/tests/dromaeo/cssquery-yui.html (next: http://localhost:34478/tests/dromaeo/cssquery-dojo.html) 05:51:09 INFO - PROCESS | 21248 | RSS: Main: 308363264 05:51:09 INFO - PROCESS | 21248 | 05:51:09 INFO - PROCESS | 21248 | __start_tp_report 05:51:09 INFO - PROCESS | 21248 | _x_x_mozilla_page_load 05:51:09 INFO - PROCESS | 21248 | _x_x_mozilla_page_load_details 05:51:09 INFO - PROCESS | 21248 | |i|pagename|runs| 05:51:09 INFO - PROCESS | 21248 | |0;dojo.html;3107.0368185862385;3114.8754049838008;3117.1895307220116;3118.8284644344553;3132;4890.657653964224;4974.154393753061;5011.198208286675;5026.145555255611;5070.695758254503;5046.646734728571;5128.923066154001;5134.820281290155;5143.614228932826;5152.845414637558;4621.0757848430285;4644.140833945722;4685.20351540239;4704.835330763424;4741.620670346381;4525.796339164729;4677.391939047919;4690.718556886581;4726.26742854858;4766.642501812361;4560.726356418609;4597.609203217714;4604.079184163181;4622.838200662961;4634.281686338589;4627.167109920247;4638.860834174947;4678.485366609671;4690.132325519756;4691.085238378518;4536.070105628338;4644.907101857978;4660.906781864378;4670;4678.883027924329;4878.658493905428;4941.678790878582;4965.503449655042;4983.028309479653;5013.724245166514;5453.83638490842;5473.808416705396;5474.206240095164;5476.205950137208;5477.863053423656;5187.118189907725;5277.6305658603915;5514.035043867348;5543;5563.082091454946;5129.871753206163;5162.638615296931;5188.936268065076;5193.740312984336;5217.686938783685;5601.719914004283;5602.85992850182;5606.719664016864;5607.80372686954;5620.100783874642;5274.367075950834;5276.366835979707;5355.732213389393;5376.623636345418;5380.892382152335;5470.070088085035;5481.424450432727;5489.670619762827;5497.202905578749;5533.529649979716;5449.4005659377435;5467.644603100785;5468.48049435303;5475.67145971243;5519.668819870821;5306.946930530722;5358.490943360374;5383.380911195184;5440.891182176413;5460.918086228709;4264.786760662017;4268.701190916669;4269.637080848162;4271.957280427218;4312;3815.5039844819994;3818.8472461101246;3824.713146513967;3839.596842331515;3844.3464611015634;4906.607471402279;4930;5012.022655582126;5025.422076461181;5073.974630126826;5426.647267927572;5437.048516509595;5448.482394172547;5451.972740136274;5453.7818487260065;4944.703317800943;5085.898282034339;5091.261767043758;5093.18509038552;5121.487851214849;4559.156556037077;4593.425821772278;4620;4640.002399484127;4677.158111539888;5546.944530554723;5561.277033985556;5564.582656300713;5575.944240557543;5604.215409842625;5277.973610131925;5372.328458942632;5424.701641409761;5431.375391829832;5432.18517222404;5612.7754889803955;5623.381428042994;5652.349979752216;5654.264945557051;5699.23060386859;5319.0691628964005;5320.760565774472;5329.54698850602;5329.600279979095;5335.11970524859;5306.991671582387;5383.273258110262;5400.83797486076;5416.377116631637;5445.7821687133655;5240.397354304304;5247.947520524898;5250.921236181384;5261.4738526147075;5296.523312901857;5247.2129180623215;5248.448912864247;5248.790048398021;5253.13323301651;5292.973535132453;5034.320366750589;5035.5719763819025;5185.066687996197;5255.290535777621;5310.283111780015;5273.525382715574;5297.311349524537;5297.841064768063;5303.124984377535;5305.389880163676;5179.792808287626;5183.144781111226;5188.481151884781;5197.974010130077;5238.345206849144 05:51:09 INFO - PROCESS | 21248 | |1;ext.html;492.3328889354924;525.4640266927736;541.536985877075;562.2915126940055;576.9740361683724;27388.726112738867;27740.44519109619;34952.82523587379;34996.82501587495;35384;37482.62517374825;37670.43494347587;37830.24339513208;37863.810680946626;37898.24203515935;7324.340809327159;8354.582270886447;8559.186877246682;8567.228949394554;8568.957155214215;7352.852942941138;10071.798564028715;10193.592256309776;10202.38785672862;10291.742706432362;9945.801083978316;10096.141827944633;10131.898681013206;10156.390616562992;10162.644307449204;9790.755231119208;9914.157296629795;9930.851037234448;9941.155001824853;9949.253805964545;9894.703158905244;10033.548490317953;10041.397516148982;10053.34653247544;10320.483975801255;38569.22861542781;38951.026224344336;39064;39065.804670976744;39104.60895391038;2964.792464527484;3063.326068264978;3064.8620812063286;3187.0916788715103;3603.297357015383;29023;29909.700902991128;30156.396872062654;30246.395072098654;30302;3912.39357899526;4000.3599424092295;4001.599840015975;4006.779627120507;4067.186562687474;208163;208170;208281.9585902076;208418;208778;56976.43023569794;57669.423305767246;57757;57826.42173578295;57907;54748.72625636846;55028;60771;61218;66617.6669116661;16320.918395407947;16342.836571634369;16551.91724041372;16552.255148518347;16566.83433165677;12517.311547864772;12531;12583.748325033632;12585.496580136874;12618.747625047448;18460.81539184618;18571.44285671432;18637.69536132458;18682.252709891723;18797.436076917715;7649.31156195945;7695.115061767857;7868.449208555347;7882.251186137308;7928.722494712657;967.3132076225959;1021.5352014833232;1031.28841099641;1042.864427624404;1085.918556108295;28258.71741282602;28992.855035724686;29376.61143645184;29740.55389169164;29777.553336699966;998.8751406074241;1014.4826138669332;1017.0897047142913;1042.1089968077335;1103.6645658752839;14247.287635618177;14497.927510362591;15213.695726085416;15495.690086198212;15732.213389330487;326099;330966;331622;332069;332192;51787.74106129445;52023.73988130035;52025.479745202065;52095.21857172145;52171;1345.5694177863186;1346.2461021827612;1347.6630842289428;1373.8007988841562;1495;28110.2972425691;28712;28916.855415722788;29149;29229.269268268465;53028.46971530235;54021.72989135029;54059.72970135124;54189.45810542002;54204.18693719518;248717;251002;251119;251249;251400;27746.583801242592;28086.2978425541;31835.68164318327;31860.840695796374;32788;36910.8154459226;37124.44313335356;37200.25599488049;37768.244635107694;37811.81094094512;31857.362852742353;31890.52164217492;33412.83293583516;34419.655803441645;34874.47688284729;31497.685023149475;31680.5247921286;33137.84017559374;36744.265114697024;39473.80263098666;33863.49204761881;34419.827900860335;34676.133096672784;34677.306453871286;34750;29974.400511990076;31370.686293136776;32223.677763223004;32281.838590807834;32305.676943231207;23607.104827703235;23958;24061.037558497464;24376.6343504844;24453.353862766016 05:51:09 INFO - PROCESS | 21248 | |2;jquery.html;369.2062066556902;372.74466990111773;373.1678357263301;373.7514552822373;374.6628034768708;13749.106308089968;13787.424327871804;15478.916475846696;16210.918945405272;16982.91508542457;15350.232488375565;15846.128462934534;15989.360425582963;16018.279177437014;16737.581560460996;5205.27126202332;5343.706096164709;5366.543843773284;5366.570674346043;5372.677639341632;3984.7011474139413;4066.3493840985448;5294.179402192647;5333.946660533403;5774.335951365605;5767.740451679684;5917.378675239102;5938.079597662369;5983.970080149593;5992.610480318787;5864.266966629171;5883.882322353572;5905.4980326672285;5905.995980683316;5912.408759124075;5033.245013248005;5203.843884683465;5210.140326846066;5244.265802787613;5253.789848406059;12282.263064216175;12596.307203103826;12959.805602915963;19944.70082948757;20205.494862628406;2055.126571207246;2072.6580114281123;2096.7693553709078;2097.9370618881458;2139.1871088986086;9414.640229455044;9560.043995600454;12016.918477336994;12606.621801345975;12773.169743966613;1824.628500856538;1847.7690288713911;1854.6939754940418;1860.4046705054388;1860.8139186081419;55670.44329556733;56048.71975640136;57052.05599736605;57368.71315643395;57912.710436447545;10054.748631284276;10122.240831937634;10175.592976280866;10211.591536338463;10271.332363396505;13122.212667239804;13182.868171318163;13225;13235.404406801717;13237.07340486176;7698.384129269645;7701.114371847198;7703.845923081507;7707.075150981918;7767.3786097113225;7414.592197429085;7451.5901625411125;7455.739049133254;7459.888101678479;7502.924970750332;4448.088141930896;4460.219461594208;4472.08322293929;4487.5961163494785;4510.797014134372;1723.922423490946;1766.646670665872;1770.4246120011048;1830.8993005384566;1884.8586356023352;467.44841087516375;861.3109512390063;970.898055704155;1017.9287449878585;1019.9796004080025;10369.585216591402;10377;10449.850516443184;10593.894061059445;10804.351738895692;461.38174845706243;471.33541706194785;471.714612659343;472.3599522646833;499.61280007994094;5987.281526216795;6011.48902343297;6043.09353596964;6146.323904370515;6148.569600127948;581790;584654;595432;595868;595876;73603;73821;75927.62036189783;76363;76525.61737191278;459.3224993135166;473.0987468871805;491.16256782186287;499.3583245529477;500.94489606142565;10321.535530901278;10527.842082368923;10576;10590.364578125336;10679.359238445719;33377.66622333843;34359.31281374409;34519.137021574665;34571.48142777911;35054.64945350514;44053.77973110114;45431.54568454406;45656.60807487021;45731.08537829291;45764.77117614524;7342.3758980487255;7437.888431673638;7518.060242469692;7542.585157816373;7548.207438218908;9950.651727189803;10806.783864322511;10953.671389858317;11229.66311010671;11500.82748758786;9288.535573221447;9601.073078642921;9685.612575496902;9772.31593788428;9825.656102036395;9587;9666;9696.75758106053;9898.10917017472;10047.397156170653;9954.651587194414;10153.15711600753;10187.08316251541;10344.534495947852;10476.423796691255;7467.477276590586;7571.205023472677;7631.580263085583;7695.769126926201;7717.305442510201;8670.523121228141;10095.697129086138;10202.285839991426;10275.897241027793;10399.792004159723 05:51:09 INFO - PROCESS | 21248 | |3;mootools.html;1343.9193648381092;1473.3222717549927;1487.590912499063;1630.1278815833532;1801.9729704054432;6988.336108069731;7612.162662107171;7753.263439973193;7760.22397760223;7763.223677632234;7712.383009359251;7920.722774702886;7923;7929.722459713911;7939.967804185448;1994.30199430199;2052.2509284111343;2264.4225722440756;2337.415646088478;2339.2397470821966;2196.4618668426256;2203.2178576605315;2204.9779502204933;2205.4596623827183;2207.4371035385952;2255.672927425522;2360.36270207044;2361.598528250193;2361.95276094478;2364.3143488388346;2280.1791355112146;2298.804601608865;2313.502596941649;2326.720793504773;2345.624700048001;2293.9311820645407;2305.6656784766114;2315.270689732729;2321.466062805564;2338.8596684199;7785.299323060895;7819.17898620648;7827.76516704494;7842.882356764653;7849.45053846231;4693.296005599153;4828.710277383333;4838.225883858601;4857.902841943177;4858.004109157614;3109.7201251887213;3152.889648862302;3206.695363940422;3283.589551306087;3293.489509126089;972.9221662267001;976.6337623391229;999.2305924438142;1007.3099926550336;1032.6695457453618;10775.676729698122;10789.568417263301;10895.83656245157;10900.018998290192;10902.781944361148;4295.591918767712;4417.933730994037;4421.38100665907;4509.932351014738;4588.95411045892;9158.129977652112;9241.399309044891;9259.537023148816;9267.860982085274;9268.165865072177;4828.985912958318;4855.6115510759055;4867.878303042452;4879.634027447884;4880.560749532559;4463.995600989816;4473.776311184428;4477.910441791211;4489.551044895485;4492.73043617384;3351.3464874349756;3402.4045791986305;3690.538682664667;3691.280200360904;3751.362268414376;1186.2170967161633;1190.3691043746828;1196.946137423818;1199.0227964209141;1204.7168915304896;1230.2065167966612;1231.6489800406841;1246.5886257534992;1246.7506498700295;1268.657462485124;7216.206217316091;7281.781546553612;7283.162436319785;7294.124705035323;7324.6337683115635;1092.63396762084;1113.7828123515837;1117.1118960426481;1118.6755840806238;1120.176670147441;5692.9146062809095;5804.506616937608;5812.070068788974;5837.474627283565;5873.030949893306;9408.341416100944;9423.38747981379;9658.661946831828;9668.033196680417;9777.973312802196;9594.760130996781;9648.71053868385;9650.662226822027;9653.758656033515;9662.3719458235;7310.122785265802;7461.36578390832;7545;7609.162992070978;7616.162222155558;7214.783556493314;7241.565506069653;7248.166460856858;7252.311030452099;7253;8461.407701461085;8491.532965686947;8598.39811213209;8628.482291062557;8788.2529984952;8704.042555319036;8828.911710882809;8957.641694332415;8960.283177345927;8975.371723979259;6875.7937261882225;6954.269801670954;6955.1653801544135;6957.339052789976;6960.582365058112;6322.336154703874;6331.651759153291;7138.321859423347;7323.707051717872;7597.126330471845;6855.794326170223;6898.655067246718;7083.610401427764;7125.75059872902;7143.321384468467;6788.830279242861;6843.657817109224;6981.406580440719;7062.470314726519;7070.257622949516;6869.244383117753;6911.5853048817235;6912.861742765217;6914.308569143247;6916;6507.479401647952;6509.7396104155305;6551.901721474277;6592.538522303585;6600.768973085919;6595;6618.702158402787;6625.834354141185;6627.337266273527;6633.1045308882685 05:51:09 INFO - PROCESS | 21248 | |4;prototype.html;345.311104346828;349.0941008084021;350.8596561375448;351.77310634640656;351.9348920449717;5928.822135335947;5956.761729530819;5988.490978266847;6000.519958403323;6113.296970848353;5818;5976.940230597693;5977.7608895644125;5998.640081595107;6028.819135425933;4682.836100736475;4691.929621055687;4699.7415142167165;4701.92947105792;4706.152892479352;4490.595846373826;4564.680472366936;4566.703164294327;4663.440387153546;4691.929621055687;4588.954110458886;4589.449266088074;4599.40207772989;4609.078184363141;4621.86764242761;4597.218472859605;4604.769761511911;4606.677532572721;4620.3993480847275;4626.560476754737;4055.5944405559503;4078.9802549362657;4087.1417002429525;4087.591240875918;4089.0390758171807;5651.717414129277;5754.712264386806;5758.510526605243;5760.596758226884;5762.855928601776;5423.620346575742;5433.130699088127;5434.347878254595;5437.891242175174;5441.401445840955;6136.9079463808075;6399.9040014399825;6401.423871851509;6404.903926441107;6405.103285540028;4436.268015777393;4479.216137176014;4501.662375321831;4511.300748384006;4511.503734589192;6649.63427011519;6675.732970681167;6705.362990515893;6736.292689267654;6744.898826517655;4671.135839869635;4672.18236808564;4673.719576825401;4676.438827340741;4682.086993036393;4652.674312798139;4693.882652933704;4729.5743383095;4731.148393289172;4744.430668319755;5389.275431861845;5395.487428694268;5414.3232095988005;5415.160650099241;5416.756245968941;6412.935870641327;6413.45485633717;6415.807525774234;6419.486441084702;6423.678816059271;1650.2656317938643;1684.3683618643008;1685.5280521454013;1686.8987860728396;1687.670904173699;1091.5688303120223;1102.8290614954694;1104.9558017679365;1156.7570810129798;1180.5867946218755;5864.237649105589;6030.306514750772;6030.336662967158;6033.849153771103;6037.426444487766;6533.346665333524;6538.378854008862;6561.967190164114;6621.205455345293;6670.366315200048;5889.204957330706;6020;6021.2473440819895;6026.367231440636;6039.184710064085;3506.5967413747744;3536.982315088408;3537.1510837398837;3542.203004324058;3544.1316877364693;6783.18601767791;6798;6798.388145066968;6799.620132953526;6801.047853300542;6539.215294164731;6622.304658010936;6675;6678.666066696645;6683.2648408675;6762.932370676329;6778.694958726869;6780.457563394916;6788.287229840895;6795.7961261162245;6409.7436102555375;6500.057491663777;6502.577332473374;6516.6415847128865;6524.706388212447;6520.9347906520325;6562.475001999924;6576.177977752781;6588.8352791180605;6628.734850606114;6874;6876.484263680344;6877.243503214542;6880.380765731108;6883.380495755206;4471.709338892962;4595.0809838031855;4596.5863072323655;4616.792244348945;4618.861434156981;6637;6646.368594983469;6680.2651708313015;6705.966470167813;6712.127423434922;6489.3510648934725;6511.38141876521;6522.934770652422;6527.51043671717;6529.053287273414;6353.3011368748475;6415.422611964946;6454.515911306765;6488.026795980641;6496.707648155939;6360.459360954371;6364.554481186273;6367.172267605181;6370.29926708071;6374.521910856612;6499.5775274607;6501.1548498694865;6521.836954076186;6528.445082168069;6530.510211734044;6137.3862613738265;6337.302896681454;6339.334369891095;6340.175777149126;6355.5551111421755 05:51:09 INFO - PROCESS | 21248 | |5;yui.html;752.7214930475723;800.6197056398212;835.8996920369557;838.5555655502586;845.3237410071941;13385.464581416743;13425.395857186426;13498.932505337483;13537.864621353783;13559.11865728727;12620.368981550931;12665.683357916058;12678.429470673818;14260.144391336527;14496.057756245837;671.3152584363946;804.4811096842533;817.2685446525356;817.2726273616486;817.7342363731781;662.0797092042066;806.4153488720689;812.6180695073335;820.5240960243075;821.3059964330162;847.5931552854634;851.948883067018;854.1031916487694;860.1742327365737;860.4880096342666;698.9475789315796;708.8334241453255;821.7945513621595;848.1984524624232;851.6806197675871;617.1514168018974;618.832915112917;619.3280290884396;619.8140557832677;620.0698951572641;13187;13243.40404681782;13275.73448531024;13276.668083297898;13316.533921312708;12404;12731.936340318332;12821.67945801353;12882.227066376048;13010.154339967967;10047.296689231756;10073.294869359148;10205.183585313158;10365.637202697944;10407.583696652126;3513.9824300878586;3558.982205088984;3580.3913334732897;3603.9099022524383;3606.5131207286945;47202.76398617985;47631;47724;47966.52033479655;47989.52010479885;10125.341852779546;10163.136133428741;10183.54174062169;10264.384136951807;10676.145908327315;18586.07069646512;18690.439286821416;18695.25218991225;18734.812651873308;18749.156287967075;7530.171681115183;7596.468247222752;7603.201663825329;7682.0397450318715;7864.842703145905;4225.563308475133;4247.532771395144;4310.698251122391;4382.517923028464;4403.801828917706;3033.6966303369486;3081.5839861618842;3085.2441151917924;3085.8457077146054;3087.644920834133;543.6547792152012;547.854818473105;551.9337679478408;552.1414200917611;556.2796178948191;499.94000719913845;554.9139883318093;572.68788510262;601.4136217188294;626.7023163997065;7926;8316.958415208006;8375.539345335943;8407.855996720797;8570.271526920156;552.2130963377172;561.3208018297905;637.346719612401;650.6974256970436;709.1490211745926;4025.2352053109817;4172.937405938914;4267.1892340455215;4309.612134907873;4352.804123814436;51421;52331.47668523342;52415;52560.47439525632;52577;46922.29616555754;47292.763536182785;47838.28242576364;47910;48265;42008.57991420108;42195.156096878505;42876.78561607172;43221.78389108034;43602.563974360484;8643.697470588499;8644.351673624378;8888.333374996771;8891.183273933213;8941.284697224337;30559.847200763856;31371.843140785062;31551.36897261996;32092.83953580217;32148.03555893327;37980.430293546175;41018.58981410267;41032;41863;42000.789996049825;10221;10582.629607963685;10794.56821727122;11128.833067503832;11518.308901465938;12290.446929888356;12353.567625133077;12731.66641029344;12788.232706037668;12930.547430839875;10240.743981400525;10437.739056523647;10452.216083793897;10515.421651809223;10711.41087240178;10830.133589312994;10925.945370273415;11146.777064458827;11240.269382490113;11252.718682033015;11105.555777768799;11154.888451115385;11536.192466527262;11753.529858805552;11972.76054478923;9312.301577381859;9315.86026209594;9330.393524420891;9394.530273486434;9427.62289508412;9106.817863642558;9293.395929264576;9397.483138427453;9703.5148242589;9723.854142188015 05:51:09 INFO - PROCESS | 21248 | __end_tp_report 05:51:09 INFO - PROCESS | 21248 | __start_cc_report 05:51:09 INFO - PROCESS | 21248 | _x_x_mozilla_cycle_collect,777 05:51:09 INFO - PROCESS | 21248 | __end_cc_report 05:51:09 INFO - PROCESS | 21248 | __startTimestamp1452779469132__endTimestamp 05:51:09 INFO - PROCESS | 21248 | 05:51:09 INFO - PROCESS | 21248 | ------- Summary: start ------- 05:51:09 INFO - PROCESS | 21248 | Number of tests: 6 05:51:09 INFO - PROCESS | 21248 | 05:51:09 INFO - PROCESS | 21248 | [#0] dojo.html Cycles:165 Average:5046.84 Median:5217.69 stddev:535.08 (10.3%) stddev-sans-first:514.63 05:51:09 INFO - PROCESS | 21248 | Values: 3107.0 3114.9 3117.2 3118.8 3132.0 4890.7 4974.2 5011.2 5026.1 5070.7 5046.6 5128.9 5134.8 5143.6 5152.8 4621.1 4644.1 4685.2 4704.8 4741.6 4525.8 4677.4 4690.7 4726.3 4766.6 4560.7 4597.6 4604.1 4622.8 4634.3 4627.2 4638.9 4678.5 4690.1 4691.1 4536.1 4644.9 4660.9 4670.0 4678.9 4878.7 4941.7 4965.5 4983.0 5013.7 5453.8 5473.8 5474.2 5476.2 5477.9 5187.1 5277.6 5514.0 5543.0 5563.1 5129.9 5162.6 5188.9 5193.7 5217.7 5601.7 5602.9 5606.7 5607.8 5620.1 5274.4 5276.4 5355.7 5376.6 5380.9 5470.1 5481.4 5489.7 5497.2 5533.5 5449.4 5467.6 5468.5 5475.7 5519.7 5306.9 5358.5 5383.4 5440.9 5460.9 4264.8 4268.7 4269.6 4272.0 4312.0 3815.5 3818.8 3824.7 3839.6 3844.3 4906.6 4930.0 5012.0 5025.4 5074.0 5426.6 5437.0 5448.5 5452.0 5453.8 4944.7 5085.9 5091.3 5093.2 5121.5 4559.2 4593.4 4620.0 4640.0 4677.2 5546.9 5561.3 5564.6 5575.9 5604.2 5278.0 5372.3 5424.7 5431.4 5432.2 5612.8 5623.4 5652.3 5654.3 5699.2 5319.1 5320.8 5329.5 5329.6 5335.1 5307.0 5383.3 5400.8 5416.4 5445.8 5240.4 5247.9 5250.9 5261.5 5296.5 5247.2 5248.4 5248.8 5253.1 5293.0 5034.3 5035.6 5185.1 5255.3 5310.3 5273.5 5297.3 5297.8 5303.1 5305.4 5179.8 5183.1 5188.5 5198.0 5238.3 05:51:09 INFO - PROCESS | 21248 | 05:51:09 INFO - PROCESS | 21248 | [#1] ext.html Cycles:180 Average:43475.23 Median:32057.10 stddev:70028.89 (218.5%) stddev-sans-first:70150.97 05:51:09 INFO - PROCESS | 21248 | Values: 492.3 525.5 541.5 562.3 577.0 27388.7 27740.4 34952.8 34996.8 35384.0 37482.6 37670.4 37830.2 37863.8 37898.2 7324.3 8354.6 8559.2 8567.2 8569.0 7352.9 10071.8 10193.6 10202.4 10291.7 9945.8 10096.1 10131.9 10156.4 10162.6 9790.8 9914.2 9930.9 9941.2 9949.3 9894.7 10033.5 10041.4 10053.3 10320.5 38569.2 38951.0 39064.0 39065.8 39104.6 2964.8 3063.3 3064.9 3187.1 3603.3 29023.0 29909.7 30156.4 30246.4 30302.0 3912.4 4000.4 4001.6 4006.8 4067.2 208163.0 208170.0 208282.0 208418.0 208778.0 56976.4 57669.4 57757.0 57826.4 57907.0 54748.7 55028.0 60771.0 61218.0 66617.7 16320.9 16342.8 16551.9 16552.3 16566.8 12517.3 12531.0 12583.7 12585.5 12618.7 18460.8 18571.4 18637.7 18682.3 18797.4 7649.3 7695.1 7868.4 7882.3 7928.7 967.3 1021.5 1031.3 1042.9 1085.9 28258.7 28992.9 29376.6 29740.6 29777.6 998.9 1014.5 1017.1 1042.1 1103.7 14247.3 14497.9 15213.7 15495.7 15732.2 326099.0 330966.0 331622.0 332069.0 332192.0 51787.7 52023.7 52025.5 52095.2 52171.0 1345.6 1346.2 1347.7 1373.8 1495.0 28110.3 28712.0 28916.9 29149.0 29229.3 53028.5 54021.7 54059.7 54189.5 54204.2 248717.0 251002.0 251119.0 251249.0 251400.0 27746.6 28086.3 31835.7 31860.8 32788.0 36910.8 37124.4 37200.3 37768.2 37811.8 31857.4 31890.5 33412.8 34419.7 34874.5 31497.7 31680.5 33137.8 36744.3 39473.8 33863.5 34419.8 34676.1 34677.3 34750.0 29974.4 31370.7 32223.7 32281.8 32305.7 23607.1 23958.0 24061.0 24376.6 24453.4 05:51:09 INFO - PROCESS | 21248 | 05:51:09 INFO - PROCESS | 21248 | [#2] jquery.html Cycles:180 Average:28651.14 Median:460.35 stddev:96576.07 (20978.7%) stddev-sans-first:96823.64 05:51:09 INFO - PROCESS | 21248 | Values: 369.2 372.7 373.2 373.8 374.7 13749.1 13787.4 15478.9 16210.9 16982.9 15350.2 15846.1 15989.4 16018.3 16737.6 5205.3 5343.7 5366.5 5366.6 5372.7 3984.7 4066.3 5294.2 5333.9 5774.3 5767.7 5917.4 5938.1 5984.0 5992.6 5864.3 5883.9 5905.5 5906.0 5912.4 5033.2 5203.8 5210.1 5244.3 5253.8 12282.3 12596.3 12959.8 19944.7 20205.5 2055.1 2072.7 2096.8 2097.9 2139.2 9414.6 9560.0 12016.9 12606.6 12773.2 1824.6 1847.8 1854.7 1860.4 1860.8 55670.4 56048.7 57052.1 57368.7 57912.7 10054.7 10122.2 10175.6 10211.6 10271.3 13122.2 13182.9 13225.0 13235.4 13237.1 7698.4 7701.1 7703.8 7707.1 7767.4 7414.6 7451.6 7455.7 7459.9 7502.9 4448.1 4460.2 4472.1 4487.6 4510.8 1723.9 1766.6 1770.4 1830.9 1884.9 467.4 861.3 970.9 1017.9 1020.0 10369.6 10377.0 10449.9 10593.9 10804.4 461.4 471.3 471.7 472.4 499.6 5987.3 6011.5 6043.1 6146.3 6148.6 581790.0 584654.0 595432.0 595868.0 595876.0 73603.0 73821.0 75927.6 76363.0 76525.6 459.3 473.1 491.2 499.4 500.9 10321.5 10527.8 10576.0 10590.4 10679.4 33377.7 34359.3 34519.1 34571.5 35054.6 44053.8 45431.5 45656.6 45731.1 45764.8 7342.4 7437.9 7518.1 7542.6 7548.2 9950.7 10806.8 10953.7 11229.7 11500.8 9288.5 9601.1 9685.6 9772.3 9825.7 9587.0 9666.0 9696.8 9898.1 10047.4 9954.7 10153.2 10187.1 10344.5 10476.4 7467.5 7571.2 7631.6 7695.8 7717.3 8670.5 10095.7 10202.3 10275.9 10399.8 05:51:09 INFO - PROCESS | 21248 | 05:51:09 INFO - PROCESS | 21248 | [#3] mootools.html Cycles:180 Average:5519.71 Median:6530.82 stddev:2858.49 (43.8%) stddev-sans-first:2849.28 05:51:09 INFO - PROCESS | 21248 | Values: 1343.9 1473.3 1487.6 1630.1 1802.0 6988.3 7612.2 7753.3 7760.2 7763.2 7712.4 7920.7 7923.0 7929.7 7940.0 1994.3 2052.3 2264.4 2337.4 2339.2 2196.5 2203.2 2205.0 2205.5 2207.4 2255.7 2360.4 2361.6 2362.0 2364.3 2280.2 2298.8 2313.5 2326.7 2345.6 2293.9 2305.7 2315.3 2321.5 2338.9 7785.3 7819.2 7827.8 7842.9 7849.5 4693.3 4828.7 4838.2 4857.9 4858.0 3109.7 3152.9 3206.7 3283.6 3293.5 972.9 976.6 999.2 1007.3 1032.7 10775.7 10789.6 10895.8 10900.0 10902.8 4295.6 4417.9 4421.4 4509.9 4589.0 9158.1 9241.4 9259.5 9267.9 9268.2 4829.0 4855.6 4867.9 4879.6 4880.6 4464.0 4473.8 4477.9 4489.6 4492.7 3351.3 3402.4 3690.5 3691.3 3751.4 1186.2 1190.4 1196.9 1199.0 1204.7 1230.2 1231.6 1246.6 1246.8 1268.7 7216.2 7281.8 7283.2 7294.1 7324.6 1092.6 1113.8 1117.1 1118.7 1120.2 5692.9 5804.5 5812.1 5837.5 5873.0 9408.3 9423.4 9658.7 9668.0 9778.0 9594.8 9648.7 9650.7 9653.8 9662.4 7310.1 7461.4 7545.0 7609.2 7616.2 7214.8 7241.6 7248.2 7252.3 7253.0 8461.4 8491.5 8598.4 8628.5 8788.3 8704.0 8828.9 8957.6 8960.3 8975.4 6875.8 6954.3 6955.2 6957.3 6960.6 6322.3 6331.7 7138.3 7323.7 7597.1 6855.8 6898.7 7083.6 7125.8 7143.3 6788.8 6843.7 6981.4 7062.5 7070.3 6869.2 6911.6 6912.9 6914.3 6916.0 6507.5 6509.7 6551.9 6592.5 6600.8 6595.0 6618.7 6625.8 6627.3 6633.1 05:51:09 INFO - PROCESS | 21248 | 05:51:09 INFO - PROCESS | 21248 | [#4] prototype.html Cycles:180 Average:5369.35 Median:6020.62 stddev:1597.99 (26.5%) stddev-sans-first:1557.34 05:51:09 INFO - PROCESS | 21248 | Values: 345.3 349.1 350.9 351.8 351.9 5928.8 5956.8 5988.5 6000.5 6113.3 5818.0 5976.9 5977.8 5998.6 6028.8 4682.8 4691.9 4699.7 4701.9 4706.2 4490.6 4564.7 4566.7 4663.4 4691.9 4589.0 4589.4 4599.4 4609.1 4621.9 4597.2 4604.8 4606.7 4620.4 4626.6 4055.6 4079.0 4087.1 4087.6 4089.0 5651.7 5754.7 5758.5 5760.6 5762.9 5423.6 5433.1 5434.3 5437.9 5441.4 6136.9 6399.9 6401.4 6404.9 6405.1 4436.3 4479.2 4501.7 4511.3 4511.5 6649.6 6675.7 6705.4 6736.3 6744.9 4671.1 4672.2 4673.7 4676.4 4682.1 4652.7 4693.9 4729.6 4731.1 4744.4 5389.3 5395.5 5414.3 5415.2 5416.8 6412.9 6413.5 6415.8 6419.5 6423.7 1650.3 1684.4 1685.5 1686.9 1687.7 1091.6 1102.8 1105.0 1156.8 1180.6 5864.2 6030.3 6030.3 6033.8 6037.4 6533.3 6538.4 6562.0 6621.2 6670.4 5889.2 6020.0 6021.2 6026.4 6039.2 3506.6 3537.0 3537.2 3542.2 3544.1 6783.2 6798.0 6798.4 6799.6 6801.0 6539.2 6622.3 6675.0 6678.7 6683.3 6762.9 6778.7 6780.5 6788.3 6795.8 6409.7 6500.1 6502.6 6516.6 6524.7 6520.9 6562.5 6576.2 6588.8 6628.7 6874.0 6876.5 6877.2 6880.4 6883.4 4471.7 4595.1 4596.6 4616.8 4618.9 6637.0 6646.4 6680.3 6706.0 6712.1 6489.4 6511.4 6522.9 6527.5 6529.1 6353.3 6415.4 6454.5 6488.0 6496.7 6360.5 6364.6 6367.2 6370.3 6374.5 6499.6 6501.2 6521.8 6528.4 6530.5 6137.4 6337.3 6339.3 6340.2 6355.6 05:51:09 INFO - PROCESS | 21248 | 05:51:09 INFO - PROCESS | 21248 | [#5] yui.html Cycles:180 Average:13234.42 Median:23787.29 stddev:14769.87 (62.1%) stddev-sans-first:14781.56 05:51:09 INFO - PROCESS | 21248 | Values: 752.7 800.6 835.9 838.6 845.3 13385.5 13425.4 13498.9 13537.9 13559.1 12620.4 12665.7 12678.4 14260.1 14496.1 671.3 804.5 817.3 817.3 817.7 662.1 806.4 812.6 820.5 821.3 847.6 851.9 854.1 860.2 860.5 698.9 708.8 821.8 848.2 851.7 617.2 618.8 619.3 619.8 620.1 13187.0 13243.4 13275.7 13276.7 13316.5 12404.0 12731.9 12821.7 12882.2 13010.2 10047.3 10073.3 10205.2 10365.6 10407.6 3514.0 3559.0 3580.4 3603.9 3606.5 47202.8 47631.0 47724.0 47966.5 47989.5 10125.3 10163.1 10183.5 10264.4 10676.1 18586.1 18690.4 18695.3 18734.8 18749.2 7530.2 7596.5 7603.2 7682.0 7864.8 4225.6 4247.5 4310.7 4382.5 4403.8 3033.7 3081.6 3085.2 3085.8 3087.6 543.7 547.9 551.9 552.1 556.3 499.9 554.9 572.7 601.4 626.7 7926.0 8317.0 8375.5 8407.9 8570.3 552.2 561.3 637.3 650.7 709.1 4025.2 4172.9 4267.2 4309.6 4352.8 51421.0 52331.5 52415.0 52560.5 52577.0 46922.3 47292.8 47838.3 47910.0 48265.0 42008.6 42195.2 42876.8 43221.8 43602.6 8643.7 8644.4 8888.3 8891.2 8941.3 30559.8 31371.8 31551.4 32092.8 32148.0 37980.4 41018.6 41032.0 41863.0 42000.8 10221.0 10582.6 10794.6 11128.8 11518.3 12290.4 12353.6 12731.7 12788.2 12930.5 10240.7 10437.7 10452.2 10515.4 10711.4 10830.1 10925.9 11146.8 11240.3 11252.7 11105.6 11154.9 11536.2 11753.5 11972.8 9312.3 9315.9 9330.4 9394.5 9427.6 9106.8 9293.4 9397.5 9703.5 9723.9 05:51:09 INFO - PROCESS | 21248 | -------- Summary: end -------- 05:51:09 INFO - PROCESS | 21248 | 05:51:09 INFO - TEST-INFO | 21248: exit 0 05:51:09 INFO - TEST-OK | dromaeo_css | took 1112458ms 05:51:09 INFO - TEST-START | kraken 05:51:09 INFO - operating with platform_type : linux_ 05:51:09 INFO - Initialising browser for kraken test... 05:51:09 INFO - TEST-INFO | started process 21441 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpQnLrpU/profile http://localhost:34478/getInfo.html) 05:51:14 INFO - PROCESS | 21441 | __metrics Screen width/height:1600/1200 05:51:14 INFO - PROCESS | 21441 | colorDepth:24 05:51:14 INFO - PROCESS | 21441 | Browser inner width/height: 1024/697 05:51:14 INFO - PROCESS | 21441 | __metrics 05:51:14 INFO - PROCESS | 21441 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:51:14 INFO - PROCESS | 21441 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:51:15 INFO - TEST-INFO | 21441: exit 0 05:51:15 INFO - Browser initialized. 05:51:15 INFO - Running cycle 1/1 for kraken test... 05:51:15 INFO - TEST-INFO | started process 21520 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpQnLrpU/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:51:27 INFO - PROCESS | 21520 | RSS: Main: 150425600 05:51:27 INFO - PROCESS | 21520 | 05:51:29 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:34478/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34478/tests/kraken/driver.html?testName=audio-beat-detection) 05:51:30 INFO - PROCESS | 21520 | RSS: Main: 188760064 05:51:30 INFO - PROCESS | 21520 | 05:51:32 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:34478/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34478/tests/kraken/driver.html?testName=audio-dft) 05:51:33 INFO - PROCESS | 21520 | RSS: Main: 178765824 05:51:33 INFO - PROCESS | 21520 | 05:51:36 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:34478/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34478/tests/kraken/driver.html?testName=audio-fft) 05:51:36 INFO - PROCESS | 21520 | RSS: Main: 161988608 05:51:36 INFO - PROCESS | 21520 | 05:51:38 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:34478/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34478/tests/kraken/driver.html?testName=audio-oscillator) 05:51:39 INFO - PROCESS | 21520 | RSS: Main: 165179392 05:51:39 INFO - PROCESS | 21520 | 05:51:41 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:34478/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34478/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:51:42 INFO - PROCESS | 21520 | RSS: Main: 167243776 05:51:42 INFO - PROCESS | 21520 | 05:51:44 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:34478/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34478/tests/kraken/driver.html?testName=imaging-darkroom) 05:51:45 INFO - PROCESS | 21520 | RSS: Main: 166481920 05:51:45 INFO - PROCESS | 21520 | 05:51:48 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:34478/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34478/tests/kraken/driver.html?testName=imaging-desaturate) 05:51:49 INFO - PROCESS | 21520 | RSS: Main: 166469632 05:51:49 INFO - PROCESS | 21520 | 05:51:52 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:34478/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34478/tests/kraken/driver.html?testName=json-parse-financial) 05:51:52 INFO - PROCESS | 21520 | RSS: Main: 166211584 05:51:52 INFO - PROCESS | 21520 | 05:51:54 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:34478/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34478/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:51:54 INFO - PROCESS | 21520 | RSS: Main: 176893952 05:51:54 INFO - PROCESS | 21520 | 05:51:56 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:34478/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34478/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:51:57 INFO - PROCESS | 21520 | RSS: Main: 173420544 05:51:57 INFO - PROCESS | 21520 | 05:51:59 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:34478/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34478/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:51:59 INFO - PROCESS | 21520 | RSS: Main: 184471552 05:51:59 INFO - PROCESS | 21520 | 05:52:02 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:34478/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34478/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:52:02 INFO - PROCESS | 21520 | RSS: Main: 189997056 05:52:02 INFO - PROCESS | 21520 | 05:52:05 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:34478/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34478/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:52:06 INFO - PROCESS | 21520 | RSS: Main: 187908096 05:52:06 INFO - PROCESS | 21520 | 05:52:07 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:34478/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34478/tests/kraken/driver.html?testName=ai-astar) 05:52:07 INFO - PROCESS | 21520 | RSS: Main: 247697408 05:52:07 INFO - PROCESS | 21520 | 05:52:07 INFO - PROCESS | 21520 | __start_tp_report 05:52:07 INFO - PROCESS | 21520 | _x_x_mozilla_page_load 05:52:07 INFO - PROCESS | 21520 | _x_x_mozilla_page_load_details 05:52:07 INFO - PROCESS | 21520 | |i|pagename|runs| 05:52:07 INFO - PROCESS | 21520 | |0;ai-astar;109;109;103;108;110;108;98;108;108;108 05:52:07 INFO - PROCESS | 21520 | |1;audio-beat-detection;157;126;150;164;125;148;150;132;176;129 05:52:07 INFO - PROCESS | 21520 | |2;audio-dft;162;161;160;161;159;163;159;163;161;160 05:52:07 INFO - PROCESS | 21520 | |3;audio-fft;74;73;106;94;71;71;73;113;94;76 05:52:07 INFO - PROCESS | 21520 | |4;audio-oscillator;105;95;138;95;95;95;138;96;95;139 05:52:07 INFO - PROCESS | 21520 | |5;imaging-gaussian-blur;109;109;109;110;110;109;109;108;109;109 05:52:07 INFO - PROCESS | 21520 | |6;imaging-darkroom;146;145;146;146;145;147;146;146;146;145 05:52:07 INFO - PROCESS | 21520 | |7;imaging-desaturate;95;95;96;95;96;95;95;96;96;96 05:52:07 INFO - PROCESS | 21520 | |8;json-parse-financial;54;55;55;56;56;54;55;63;52;55 05:52:07 INFO - PROCESS | 21520 | |9;json-stringify-tinderbox;67;69;68;68;68;71;69;68;68;68 05:52:07 INFO - PROCESS | 21520 | |10;stanford-crypto-aes;80;81;81;80;82;86;89;81;80;80 05:52:07 INFO - PROCESS | 21520 | |11;stanford-crypto-ccm;124;129;113;118;116;119;117;113;120;114 05:52:07 INFO - PROCESS | 21520 | |12;stanford-crypto-pbkdf2;144;147;144;145;146;136;144;146;145;147 05:52:07 INFO - PROCESS | 21520 | |13;stanford-crypto-sha256-iterative;69;67;67;67;74;68;67;70;67;69 05:52:07 INFO - PROCESS | 21520 | __end_tp_report 05:52:07 INFO - PROCESS | 21520 | __start_cc_report 05:52:07 INFO - PROCESS | 21520 | _x_x_mozilla_cycle_collect,942 05:52:07 INFO - PROCESS | 21520 | __end_cc_report 05:52:07 INFO - PROCESS | 21520 | __startTimestamp1452779527977__endTimestamp 05:52:07 INFO - PROCESS | 21520 | 05:52:07 INFO - PROCESS | 21520 | ------- Summary: start ------- 05:52:07 INFO - PROCESS | 21520 | Number of tests: 14 05:52:07 INFO - PROCESS | 21520 | 05:52:07 INFO - PROCESS | 21520 | [#0] ai-astar Cycles:10 Average:106.90 Median:108.50 stddev:3.63 (3.3%) stddev-sans-first:3.77 05:52:07 INFO - PROCESS | 21520 | Values: 109.0 109.0 103.0 108.0 110.0 108.0 98.0 108.0 108.0 108.0 05:52:07 INFO - PROCESS | 21520 | 05:52:07 INFO - PROCESS | 21520 | [#1] audio-beat-detection Cycles:10 Average:145.70 Median:150.00 stddev:17.34 (11.6%) stddev-sans-first:17.90 05:52:07 INFO - PROCESS | 21520 | Values: 157.0 126.0 150.0 164.0 125.0 148.0 150.0 132.0 176.0 129.0 05:52:07 INFO - PROCESS | 21520 | 05:52:07 INFO - PROCESS | 21520 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.45 (0.9%) stddev-sans-first:1.48 05:52:07 INFO - PROCESS | 21520 | Values: 162.0 161.0 160.0 161.0 159.0 163.0 159.0 163.0 161.0 160.0 05:52:07 INFO - PROCESS | 21520 | 05:52:07 INFO - PROCESS | 21520 | [#3] audio-fft Cycles:10 Average:84.50 Median:73.50 stddev:15.87 (21.6%) stddev-sans-first:16.37 05:52:07 INFO - PROCESS | 21520 | Values: 74.0 73.0 106.0 94.0 71.0 71.0 73.0 113.0 94.0 76.0 05:52:07 INFO - PROCESS | 21520 | 05:52:07 INFO - PROCESS | 21520 | [#4] audio-oscillator Cycles:10 Average:109.10 Median:95.00 stddev:20.40 (21.5%) stddev-sans-first:21.59 05:52:07 INFO - PROCESS | 21520 | Values: 105.0 95.0 138.0 95.0 95.0 95.0 138.0 96.0 95.0 139.0 05:52:07 INFO - PROCESS | 21520 | 05:52:07 INFO - PROCESS | 21520 | [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 05:52:07 INFO - PROCESS | 21520 | Values: 109.0 109.0 109.0 110.0 110.0 109.0 109.0 108.0 109.0 109.0 05:52:07 INFO - PROCESS | 21520 | 05:52:07 INFO - PROCESS | 21520 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 05:52:07 INFO - PROCESS | 21520 | Values: 146.0 145.0 146.0 146.0 145.0 147.0 146.0 146.0 146.0 145.0 05:52:07 INFO - PROCESS | 21520 | 05:52:07 INFO - PROCESS | 21520 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 05:52:07 INFO - PROCESS | 21520 | Values: 95.0 95.0 96.0 95.0 96.0 95.0 95.0 96.0 96.0 96.0 05:52:07 INFO - PROCESS | 21520 | 05:52:07 INFO - PROCESS | 21520 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:55.00 stddev:2.88 (5.2%) stddev-sans-first:3.00 05:52:07 INFO - PROCESS | 21520 | Values: 54.0 55.0 55.0 56.0 56.0 54.0 55.0 63.0 52.0 55.0 05:52:07 INFO - PROCESS | 21520 | 05:52:07 INFO - PROCESS | 21520 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:68.00 stddev:1.07 (1.6%) stddev-sans-first:1.01 05:52:07 INFO - PROCESS | 21520 | Values: 67.0 69.0 68.0 68.0 68.0 71.0 69.0 68.0 68.0 68.0 05:52:07 INFO - PROCESS | 21520 | 05:52:07 INFO - PROCESS | 21520 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:81.00 stddev:3.06 (3.8%) stddev-sans-first:3.15 05:52:07 INFO - PROCESS | 21520 | Values: 80.0 81.0 81.0 80.0 82.0 86.0 89.0 81.0 80.0 80.0 05:52:07 INFO - PROCESS | 21520 | 05:52:07 INFO - PROCESS | 21520 | [#11] stanford-crypto-ccm Cycles:10 Average:118.30 Median:118.50 stddev:5.08 (4.3%) stddev-sans-first:4.95 05:52:07 INFO - PROCESS | 21520 | Values: 124.0 129.0 113.0 118.0 116.0 119.0 117.0 113.0 120.0 114.0 05:52:07 INFO - PROCESS | 21520 | 05:52:07 INFO - PROCESS | 21520 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:144.40 Median:145.50 stddev:3.17 (2.2%) stddev-sans-first:3.36 05:52:07 INFO - PROCESS | 21520 | Values: 144.0 147.0 144.0 145.0 146.0 136.0 144.0 146.0 145.0 147.0 05:52:07 INFO - PROCESS | 21520 | 05:52:07 INFO - PROCESS | 21520 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.50 stddev:2.22 (3.2%) stddev-sans-first:2.35 05:52:07 INFO - PROCESS | 21520 | Values: 69.0 67.0 67.0 67.0 74.0 68.0 67.0 70.0 67.0 69.0 05:52:07 INFO - PROCESS | 21520 | -------- Summary: end -------- 05:52:07 INFO - PROCESS | 21520 | 05:52:08 INFO - TEST-INFO | 21520: exit 0 05:52:08 INFO - TEST-OK | kraken | took 59086ms 05:52:08 INFO - TEST-START | v8_7 05:52:08 INFO - operating with platform_type : linux_ 05:52:08 INFO - Initialising browser for v8_7 test... 05:52:08 INFO - TEST-INFO | started process 21770 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpuAyvUL/profile http://localhost:34478/getInfo.html) 05:52:12 INFO - PROCESS | 21770 | __metrics Screen width/height:1600/1200 05:52:12 INFO - PROCESS | 21770 | colorDepth:24 05:52:12 INFO - PROCESS | 21770 | Browser inner width/height: 1024/697 05:52:12 INFO - PROCESS | 21770 | __metrics 05:52:13 INFO - PROCESS | 21770 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:52:13 INFO - PROCESS | 21770 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:52:14 INFO - TEST-INFO | 21770: exit 0 05:52:14 INFO - Browser initialized. 05:52:14 INFO - Running cycle 1/1 for v8_7 test... 05:52:14 INFO - TEST-INFO | started process 21851 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpuAyvUL/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:52:26 INFO - PROCESS | 21851 | RSS: Main: 149770240 05:52:26 INFO - PROCESS | 21851 | 05:52:48 INFO - PROCESS | 21851 | Cycle 1(1): loaded http://localhost:34478/tests/v8_7/run.html (next: http://localhost:34478/tests/v8_7/run.html) 05:52:48 INFO - PROCESS | 21851 | RSS: Main: 535617536 05:52:48 INFO - PROCESS | 21851 | 05:52:48 INFO - PROCESS | 21851 | __start_tp_report 05:52:48 INFO - PROCESS | 21851 | _x_x_mozilla_page_load 05:52:48 INFO - PROCESS | 21851 | _x_x_mozilla_page_load_details 05:52:48 INFO - PROCESS | 21851 | |i|pagename|runs| 05:52:48 INFO - PROCESS | 21851 | |0;Richards;166.58337497917708 05:52:48 INFO - PROCESS | 21851 | |1;DeltaBlue;190.22256039566292 05:52:48 INFO - PROCESS | 21851 | |2;Encrypt;279.9552071668533 05:52:48 INFO - PROCESS | 21851 | |3;Decrypt;5246.073298429319 05:52:48 INFO - PROCESS | 21851 | |4;RayTrace;1949.317738791423 05:52:48 INFO - PROCESS | 21851 | |5;Earley;891.3624220837044 05:52:48 INFO - PROCESS | 21851 | |6;Boyer;12280.487804878048 05:52:48 INFO - PROCESS | 21851 | |7;RegExp;42270.833333333336 05:52:48 INFO - PROCESS | 21851 | |8;Splay;575.3739930955121 05:52:48 INFO - PROCESS | 21851 | |9;NavierStokes;4826.923076923077 05:52:48 INFO - PROCESS | 21851 | __end_tp_report 05:52:48 INFO - PROCESS | 21851 | __startTimestamp1452779568923__endTimestamp 05:52:48 INFO - PROCESS | 21851 | 05:52:48 INFO - PROCESS | 21851 | ------- Summary: start ------- 05:52:48 INFO - PROCESS | 21851 | Number of tests: 10 05:52:48 INFO - PROCESS | 21851 | 05:52:48 INFO - PROCESS | 21851 | [#0] Richards Cycles:1 Average:166.58 Median:166.58 stddev:0.00 (0.0%) 05:52:48 INFO - PROCESS | 21851 | Values: 166.6 05:52:48 INFO - PROCESS | 21851 | 05:52:48 INFO - PROCESS | 21851 | [#1] DeltaBlue Cycles:1 Average:190.22 Median:190.22 stddev:0.00 (0.0%) 05:52:48 INFO - PROCESS | 21851 | Values: 190.2 05:52:48 INFO - PROCESS | 21851 | 05:52:48 INFO - PROCESS | 21851 | [#2] Encrypt Cycles:1 Average:279.96 Median:279.96 stddev:0.00 (0.0%) 05:52:48 INFO - PROCESS | 21851 | Values: 280.0 05:52:48 INFO - PROCESS | 21851 | 05:52:48 INFO - PROCESS | 21851 | [#3] Decrypt Cycles:1 Average:5246.07 Median:5246.07 stddev:0.00 (0.0%) 05:52:48 INFO - PROCESS | 21851 | Values: 5246.1 05:52:48 INFO - PROCESS | 21851 | 05:52:48 INFO - PROCESS | 21851 | [#4] RayTrace Cycles:1 Average:1949.32 Median:1949.32 stddev:0.00 (0.0%) 05:52:48 INFO - PROCESS | 21851 | Values: 1949.3 05:52:48 INFO - PROCESS | 21851 | 05:52:48 INFO - PROCESS | 21851 | [#5] Earley Cycles:1 Average:891.36 Median:891.36 stddev:0.00 (0.0%) 05:52:48 INFO - PROCESS | 21851 | Values: 891.4 05:52:48 INFO - PROCESS | 21851 | 05:52:48 INFO - PROCESS | 21851 | [#6] Boyer Cycles:1 Average:12280.49 Median:12280.49 stddev:0.00 (0.0%) 05:52:48 INFO - PROCESS | 21851 | Values: 12280.5 05:52:48 INFO - PROCESS | 21851 | 05:52:48 INFO - PROCESS | 21851 | [#7] RegExp Cycles:1 Average:42270.83 Median:42270.83 stddev:0.00 (0.0%) 05:52:48 INFO - PROCESS | 21851 | Values: 42270.8 05:52:48 INFO - PROCESS | 21851 | 05:52:48 INFO - PROCESS | 21851 | [#8] Splay Cycles:1 Average:575.37 Median:575.37 stddev:0.00 (0.0%) 05:52:48 INFO - PROCESS | 21851 | Values: 575.4 05:52:48 INFO - PROCESS | 21851 | 05:52:48 INFO - PROCESS | 21851 | [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 05:52:48 INFO - PROCESS | 21851 | Values: 4826.9 05:52:48 INFO - PROCESS | 21851 | -------- Summary: end -------- 05:52:48 INFO - PROCESS | 21851 | 05:52:49 INFO - TEST-INFO | 21851: exit 0 05:52:49 INFO - TEST-OK | v8_7 | took 40796ms 05:52:49 INFO - SUITE-END | took 1212s 05:52:49 INFO - Completed test suite (00:20:12) 05:52:49 INFO - Working with test: dromaeo_css 05:52:49 INFO - Generating results file: dromaeo_css 05:52:49 INFO - Working with test: kraken 05:52:49 INFO - Generating results file: kraken 05:52:49 INFO - javascript benchmark 05:52:49 INFO - Working with test: v8_7 05:52:49 INFO - Generating results file: v8_7 05:52:49 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:52:50 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 05:52:50 INFO - process_Request line: dromaeo_css 6754.13 graph.html#tests=[[72,1,35]] 05:52:50 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:52:50 INFO - process_Request line: kraken 1494.60 graph.html#tests=[[232,1,35]] 05:52:50 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:52:50 INFO - process_Request line: v8_7 18765.64 graph.html#tests=[[230,1,35]] 05:52:50 INFO - RETURN: dromaeo_css: 6754.13 05:52:50 INFO - RETURN: kraken: 1494.60 05:52:50 INFO - RETURN: v8_7: 18765.64 05:52:50 INFO - javascript benchmark 05:52:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3107.0368185862385, 3114.8754049838008, 3117.1895307220116, 3118.8284644344553, 3132.0, 4890.657653964224, 4974.154393753061, 5011.198208286675, 5026.145555255611, 5070.695758254503, 5046.646734728571, 5128.923066154001, 5134.820281290155, 5143.614228932826, 5152.845414637558, 4621.0757848430285, 4644.140833945722, 4685.20351540239, 4704.835330763424, 4741.620670346381, 4525.796339164729, 4677.391939047919, 4690.718556886581, 4726.26742854858, 4766.642501812361, 4560.726356418609, 4597.609203217714, 4604.079184163181, 4622.838200662961, 4634.281686338589, 4627.167109920247, 4638.860834174947, 4678.485366609671, 4690.132325519756, 4691.085238378518, 4536.070105628338, 4644.907101857978, 4660.906781864378, 4670.0, 4678.883027924329, 4878.658493905428, 4941.678790878582, 4965.503449655042, 4983.028309479653, 5013.724245166514, 5453.83638490842, 5473.808416705396, 5474.206240095164, 5476.205950137208, 5477.863053423656, 5187.118189907725, 5277.6305658603915, 5514.035043867348, 5543.0, 5563.082091454946, 5129.871753206163, 5162.638615296931, 5188.936268065076, 5193.740312984336, 5217.686938783685, 5601.719914004283, 5602.85992850182, 5606.719664016864, 5607.80372686954, 5620.100783874642, 5274.367075950834, 5276.366835979707, 5355.732213389393, 5376.623636345418, 5380.892382152335, 5470.070088085035, 5481.424450432727, 5489.670619762827, 5497.202905578749, 5533.529649979716, 5449.4005659377435, 5467.644603100785, 5468.48049435303, 5475.67145971243, 5519.668819870821, 5306.946930530722, 5358.490943360374, 5383.380911195184, 5440.891182176413, 5460.918086228709, 4264.786760662017, 4268.701190916669, 4269.637080848162, 4271.957280427218, 4312.0, 3815.5039844819994, 3818.8472461101246, 3824.713146513967, 3839.596842331515, 3844.3464611015634, 4906.607471402279, 4930.0, 5012.022655582126, 5025.422076461181, 5073.974630126826, 5426.647267927572, 5437.048516509595, 5448.482394172547, 5451.972740136274, 5453.7818487260065, 4944.703317800943, 5085.898282034339, 5091.261767043758, 5093.18509038552, 5121.487851214849, 4559.156556037077, 4593.425821772278, 4620.0, 4640.002399484127, 4677.158111539888, 5546.944530554723, 5561.277033985556, 5564.582656300713, 5575.944240557543, 5604.215409842625, 5277.973610131925, 5372.328458942632, 5424.701641409761, 5431.375391829832, 5432.18517222404, 5612.7754889803955, 5623.381428042994, 5652.349979752216, 5654.264945557051, 5699.23060386859, 5319.0691628964005, 5320.760565774472, 5329.54698850602, 5329.600279979095, 5335.11970524859, 5306.991671582387, 5383.273258110262, 5400.83797486076, 5416.377116631637, 5445.7821687133655, 5240.397354304304, 5247.947520524898, 5250.921236181384, 5261.4738526147075, 5296.523312901857, 5247.2129180623215, 5248.448912864247, 5248.790048398021, 5253.13323301651, 5292.973535132453, 5034.320366750589, 5035.5719763819025, 5185.066687996197, 5255.290535777621, 5310.283111780015, 5273.525382715574, 5297.311349524537, 5297.841064768063, 5303.124984377535, 5305.389880163676, 5179.792808287626, 5183.144781111226, 5188.481151884781, 5197.974010130077, 5238.345206849144], "unit": "score", "name": "dojo.html", "value": 5014.139969120937}, {"lowerIsBetter": false, "replicates": [492.3328889354924, 525.4640266927736, 541.536985877075, 562.2915126940055, 576.9740361683724, 27388.726112738867, 27740.44519109619, 34952.82523587379, 34996.82501587495, 35384.0, 37482.62517374825, 37670.43494347587, 37830.24339513208, 37863.810680946626, 37898.24203515935, 7324.340809327159, 8354.582270886447, 8559.186877246682, 8567.228949394554, 8568.957155214215, 7352.852942941138, 10071.798564028715, 10193.592256309776, 10202.38785672862, 10291.742706432362, 9945.801083978316, 10096.141827944633, 10131.898681013206, 10156.390616562992, 10162.644307449204, 9790.755231119208, 9914.157296629795, 9930.851037234448, 9941.155001824853, 9949.253805964545, 9894.703158905244, 10033.548490317953, 10041.397516148982, 10053.34653247544, 10320.483975801255, 38569.22861542781, 38951.026224344336, 39064.0, 39065.804670976744, 39104.60895391038, 2964.792464527484, 3063.326068264978, 3064.8620812063286, 3187.0916788715103, 3603.297357015383, 29023.0, 29909.700902991128, 30156.396872062654, 30246.395072098654, 30302.0, 3912.39357899526, 4000.3599424092295, 4001.599840015975, 4006.779627120507, 4067.186562687474, 208163.0, 208170.0, 208281.9585902076, 208418.0, 208778.0, 56976.43023569794, 57669.423305767246, 57757.0, 57826.42173578295, 57907.0, 54748.72625636846, 55028.0, 60771.0, 61218.0, 66617.6669116661, 16320.918395407947, 16342.836571634369, 16551.91724041372, 16552.255148518347, 16566.83433165677, 12517.311547864772, 12531.0, 12583.748325033632, 12585.496580136874, 12618.747625047448, 18460.81539184618, 18571.44285671432, 18637.69536132458, 18682.252709891723, 18797.436076917715, 7649.31156195945, 7695.115061767857, 7868.449208555347, 7882.251186137308, 7928.722494712657, 967.3132076225959, 1021.5352014833232, 1031.28841099641, 1042.864427624404, 1085.918556108295, 28258.71741282602, 28992.855035724686, 29376.61143645184, 29740.55389169164, 29777.553336699966, 998.8751406074241, 1014.4826138669332, 1017.0897047142913, 1042.1089968077335, 1103.6645658752839, 14247.287635618177, 14497.927510362591, 15213.695726085416, 15495.690086198212, 15732.213389330487, 326099.0, 330966.0, 331622.0, 332069.0, 332192.0, 51787.74106129445, 52023.73988130035, 52025.479745202065, 52095.21857172145, 52171.0, 1345.5694177863186, 1346.2461021827612, 1347.6630842289428, 1373.8007988841562, 1495.0, 28110.2972425691, 28712.0, 28916.855415722788, 29149.0, 29229.269268268465, 53028.46971530235, 54021.72989135029, 54059.72970135124, 54189.45810542002, 54204.18693719518, 248717.0, 251002.0, 251119.0, 251249.0, 251400.0, 27746.583801242592, 28086.2978425541, 31835.68164318327, 31860.840695796374, 32788.0, 36910.8154459226, 37124.44313335356, 37200.25599488049, 37768.244635107694, 37811.81094094512, 31857.362852742353, 31890.52164217492, 33412.83293583516, 34419.655803441645, 34874.47688284729, 31497.685023149475, 31680.5247921286, 33137.84017559374, 36744.265114697024, 39473.80263098666, 33863.49204761881, 34419.827900860335, 34676.133096672784, 34677.306453871286, 34750.0, 29974.400511990076, 31370.686293136776, 32223.677763223004, 32281.838590807834, 32305.676943231207, 23607.104827703235, 23958.0, 24061.037558497464, 24376.6343504844, 24453.353862766016], "unit": "score", "name": "ext.html", "value": 18242.13288064549}, {"lowerIsBetter": false, "replicates": [369.2062066556902, 372.74466990111773, 373.1678357263301, 373.7514552822373, 374.6628034768708, 13749.106308089968, 13787.424327871804, 15478.916475846696, 16210.918945405272, 16982.91508542457, 15350.232488375565, 15846.128462934534, 15989.360425582963, 16018.279177437014, 16737.581560460996, 5205.27126202332, 5343.706096164709, 5366.543843773284, 5366.570674346043, 5372.677639341632, 3984.7011474139413, 4066.3493840985448, 5294.179402192647, 5333.946660533403, 5774.335951365605, 5767.740451679684, 5917.378675239102, 5938.079597662369, 5983.970080149593, 5992.610480318787, 5864.266966629171, 5883.882322353572, 5905.4980326672285, 5905.995980683316, 5912.408759124075, 5033.245013248005, 5203.843884683465, 5210.140326846066, 5244.265802787613, 5253.789848406059, 12282.263064216175, 12596.307203103826, 12959.805602915963, 19944.70082948757, 20205.494862628406, 2055.126571207246, 2072.6580114281123, 2096.7693553709078, 2097.9370618881458, 2139.1871088986086, 9414.640229455044, 9560.043995600454, 12016.918477336994, 12606.621801345975, 12773.169743966613, 1824.628500856538, 1847.7690288713911, 1854.6939754940418, 1860.4046705054388, 1860.8139186081419, 55670.44329556733, 56048.71975640136, 57052.05599736605, 57368.71315643395, 57912.710436447545, 10054.748631284276, 10122.240831937634, 10175.592976280866, 10211.591536338463, 10271.332363396505, 13122.212667239804, 13182.868171318163, 13225.0, 13235.404406801717, 13237.07340486176, 7698.384129269645, 7701.114371847198, 7703.845923081507, 7707.075150981918, 7767.3786097113225, 7414.592197429085, 7451.5901625411125, 7455.739049133254, 7459.888101678479, 7502.924970750332, 4448.088141930896, 4460.219461594208, 4472.08322293929, 4487.5961163494785, 4510.797014134372, 1723.922423490946, 1766.646670665872, 1770.4246120011048, 1830.8993005384566, 1884.8586356023352, 467.44841087516375, 861.3109512390063, 970.898055704155, 1017.9287449878585, 1019.9796004080025, 10369.585216591402, 10377.0, 10449.850516443184, 10593.894061059445, 10804.351738895692, 461.38174845706243, 471.33541706194785, 471.714612659343, 472.3599522646833, 499.61280007994094, 5987.281526216795, 6011.48902343297, 6043.09353596964, 6146.323904370515, 6148.569600127948, 581790.0, 584654.0, 595432.0, 595868.0, 595876.0, 73603.0, 73821.0, 75927.62036189783, 76363.0, 76525.61737191278, 459.3224993135166, 473.0987468871805, 491.16256782186287, 499.3583245529477, 500.94489606142565, 10321.535530901278, 10527.842082368923, 10576.0, 10590.364578125336, 10679.359238445719, 33377.66622333843, 34359.31281374409, 34519.137021574665, 34571.48142777911, 35054.64945350514, 44053.77973110114, 45431.54568454406, 45656.60807487021, 45731.08537829291, 45764.77117614524, 7342.3758980487255, 7437.888431673638, 7518.060242469692, 7542.585157816373, 7548.207438218908, 9950.651727189803, 10806.783864322511, 10953.671389858317, 11229.66311010671, 11500.82748758786, 9288.535573221447, 9601.073078642921, 9685.612575496902, 9772.31593788428, 9825.656102036395, 9587.0, 9666.0, 9696.75758106053, 9898.10917017472, 10047.397156170653, 9954.651587194414, 10153.15711600753, 10187.08316251541, 10344.534495947852, 10476.423796691255, 7467.477276590586, 7571.205023472677, 7631.580263085583, 7695.769126926201, 7717.305442510201, 8670.523121228141, 10095.697129086138, 10202.285839991426, 10275.897241027793, 10399.792004159723], "unit": "score", "name": "jquery.html", "value": 7623.648184057917}, {"lowerIsBetter": false, "replicates": [1343.9193648381092, 1473.3222717549927, 1487.590912499063, 1630.1278815833532, 1801.9729704054432, 6988.336108069731, 7612.162662107171, 7753.263439973193, 7760.22397760223, 7763.223677632234, 7712.383009359251, 7920.722774702886, 7923.0, 7929.722459713911, 7939.967804185448, 1994.30199430199, 2052.2509284111343, 2264.4225722440756, 2337.415646088478, 2339.2397470821966, 2196.4618668426256, 2203.2178576605315, 2204.9779502204933, 2205.4596623827183, 2207.4371035385952, 2255.672927425522, 2360.36270207044, 2361.598528250193, 2361.95276094478, 2364.3143488388346, 2280.1791355112146, 2298.804601608865, 2313.502596941649, 2326.720793504773, 2345.624700048001, 2293.9311820645407, 2305.6656784766114, 2315.270689732729, 2321.466062805564, 2338.8596684199, 7785.299323060895, 7819.17898620648, 7827.76516704494, 7842.882356764653, 7849.45053846231, 4693.296005599153, 4828.710277383333, 4838.225883858601, 4857.902841943177, 4858.004109157614, 3109.7201251887213, 3152.889648862302, 3206.695363940422, 3283.589551306087, 3293.489509126089, 972.9221662267001, 976.6337623391229, 999.2305924438142, 1007.3099926550336, 1032.6695457453618, 10775.676729698122, 10789.568417263301, 10895.83656245157, 10900.018998290192, 10902.781944361148, 4295.591918767712, 4417.933730994037, 4421.38100665907, 4509.932351014738, 4588.95411045892, 9158.129977652112, 9241.399309044891, 9259.537023148816, 9267.860982085274, 9268.165865072177, 4828.985912958318, 4855.6115510759055, 4867.878303042452, 4879.634027447884, 4880.560749532559, 4463.995600989816, 4473.776311184428, 4477.910441791211, 4489.551044895485, 4492.73043617384, 3351.3464874349756, 3402.4045791986305, 3690.538682664667, 3691.280200360904, 3751.362268414376, 1186.2170967161633, 1190.3691043746828, 1196.946137423818, 1199.0227964209141, 1204.7168915304896, 1230.2065167966612, 1231.6489800406841, 1246.5886257534992, 1246.7506498700295, 1268.657462485124, 7216.206217316091, 7281.781546553612, 7283.162436319785, 7294.124705035323, 7324.6337683115635, 1092.63396762084, 1113.7828123515837, 1117.1118960426481, 1118.6755840806238, 1120.176670147441, 5692.9146062809095, 5804.506616937608, 5812.070068788974, 5837.474627283565, 5873.030949893306, 9408.341416100944, 9423.38747981379, 9658.661946831828, 9668.033196680417, 9777.973312802196, 9594.760130996781, 9648.71053868385, 9650.662226822027, 9653.758656033515, 9662.3719458235, 7310.122785265802, 7461.36578390832, 7545.0, 7609.162992070978, 7616.162222155558, 7214.783556493314, 7241.565506069653, 7248.166460856858, 7252.311030452099, 7253.0, 8461.407701461085, 8491.532965686947, 8598.39811213209, 8628.482291062557, 8788.2529984952, 8704.042555319036, 8828.911710882809, 8957.641694332415, 8960.283177345927, 8975.371723979259, 6875.7937261882225, 6954.269801670954, 6955.1653801544135, 6957.339052789976, 6960.582365058112, 6322.336154703874, 6331.651759153291, 7138.321859423347, 7323.707051717872, 7597.126330471845, 6855.794326170223, 6898.655067246718, 7083.610401427764, 7125.75059872902, 7143.321384468467, 6788.830279242861, 6843.657817109224, 6981.406580440719, 7062.470314726519, 7070.257622949516, 6869.244383117753, 6911.5853048817235, 6912.861742765217, 6914.308569143247, 6916.0, 6507.479401647952, 6509.7396104155305, 6551.901721474277, 6592.538522303585, 6600.768973085919, 6595.0, 6618.702158402787, 6625.834354141185, 6627.337266273527, 6633.1045308882685], "unit": "score", "name": "mootools.html", "value": 4538.559125522199}, {"lowerIsBetter": false, "replicates": [345.311104346828, 349.0941008084021, 350.8596561375448, 351.77310634640656, 351.9348920449717, 5928.822135335947, 5956.761729530819, 5988.490978266847, 6000.519958403323, 6113.296970848353, 5818.0, 5976.940230597693, 5977.7608895644125, 5998.640081595107, 6028.819135425933, 4682.836100736475, 4691.929621055687, 4699.7415142167165, 4701.92947105792, 4706.152892479352, 4490.595846373826, 4564.680472366936, 4566.703164294327, 4663.440387153546, 4691.929621055687, 4588.954110458886, 4589.449266088074, 4599.40207772989, 4609.078184363141, 4621.86764242761, 4597.218472859605, 4604.769761511911, 4606.677532572721, 4620.3993480847275, 4626.560476754737, 4055.5944405559503, 4078.9802549362657, 4087.1417002429525, 4087.591240875918, 4089.0390758171807, 5651.717414129277, 5754.712264386806, 5758.510526605243, 5760.596758226884, 5762.855928601776, 5423.620346575742, 5433.130699088127, 5434.347878254595, 5437.891242175174, 5441.401445840955, 6136.9079463808075, 6399.9040014399825, 6401.423871851509, 6404.903926441107, 6405.103285540028, 4436.268015777393, 4479.216137176014, 4501.662375321831, 4511.300748384006, 4511.503734589192, 6649.63427011519, 6675.732970681167, 6705.362990515893, 6736.292689267654, 6744.898826517655, 4671.135839869635, 4672.18236808564, 4673.719576825401, 4676.438827340741, 4682.086993036393, 4652.674312798139, 4693.882652933704, 4729.5743383095, 4731.148393289172, 4744.430668319755, 5389.275431861845, 5395.487428694268, 5414.3232095988005, 5415.160650099241, 5416.756245968941, 6412.935870641327, 6413.45485633717, 6415.807525774234, 6419.486441084702, 6423.678816059271, 1650.2656317938643, 1684.3683618643008, 1685.5280521454013, 1686.8987860728396, 1687.670904173699, 1091.5688303120223, 1102.8290614954694, 1104.9558017679365, 1156.7570810129798, 1180.5867946218755, 5864.237649105589, 6030.306514750772, 6030.336662967158, 6033.849153771103, 6037.426444487766, 6533.346665333524, 6538.378854008862, 6561.967190164114, 6621.205455345293, 6670.366315200048, 5889.204957330706, 6020.0, 6021.2473440819895, 6026.367231440636, 6039.184710064085, 3506.5967413747744, 3536.982315088408, 3537.1510837398837, 3542.203004324058, 3544.1316877364693, 6783.18601767791, 6798.0, 6798.388145066968, 6799.620132953526, 6801.047853300542, 6539.215294164731, 6622.304658010936, 6675.0, 6678.666066696645, 6683.2648408675, 6762.932370676329, 6778.694958726869, 6780.457563394916, 6788.287229840895, 6795.7961261162245, 6409.7436102555375, 6500.057491663777, 6502.577332473374, 6516.6415847128865, 6524.706388212447, 6520.9347906520325, 6562.475001999924, 6576.177977752781, 6588.8352791180605, 6628.734850606114, 6874.0, 6876.484263680344, 6877.243503214542, 6880.380765731108, 6883.380495755206, 4471.709338892962, 4595.0809838031855, 4596.5863072323655, 4616.792244348945, 4618.861434156981, 6637.0, 6646.368594983469, 6680.2651708313015, 6705.966470167813, 6712.127423434922, 6489.3510648934725, 6511.38141876521, 6522.934770652422, 6527.51043671717, 6529.053287273414, 6353.3011368748475, 6415.422611964946, 6454.515911306765, 6488.026795980641, 6496.707648155939, 6360.459360954371, 6364.554481186273, 6367.172267605181, 6370.29926708071, 6374.521910856612, 6499.5775274607, 6501.1548498694865, 6521.836954076186, 6528.445082168069, 6530.510211734044, 6137.3862613738265, 6337.302896681454, 6339.334369891095, 6340.175777149126, 6355.5551111421755], "unit": "score", "name": "prototype.html", "value": 4856.283473835463}, {"lowerIsBetter": false, "replicates": [752.7214930475723, 800.6197056398212, 835.8996920369557, 838.5555655502586, 845.3237410071941, 13385.464581416743, 13425.395857186426, 13498.932505337483, 13537.864621353783, 13559.11865728727, 12620.368981550931, 12665.683357916058, 12678.429470673818, 14260.144391336527, 14496.057756245837, 671.3152584363946, 804.4811096842533, 817.2685446525356, 817.2726273616486, 817.7342363731781, 662.0797092042066, 806.4153488720689, 812.6180695073335, 820.5240960243075, 821.3059964330162, 847.5931552854634, 851.948883067018, 854.1031916487694, 860.1742327365737, 860.4880096342666, 698.9475789315796, 708.8334241453255, 821.7945513621595, 848.1984524624232, 851.6806197675871, 617.1514168018974, 618.832915112917, 619.3280290884396, 619.8140557832677, 620.0698951572641, 13187.0, 13243.40404681782, 13275.73448531024, 13276.668083297898, 13316.533921312708, 12404.0, 12731.936340318332, 12821.67945801353, 12882.227066376048, 13010.154339967967, 10047.296689231756, 10073.294869359148, 10205.183585313158, 10365.637202697944, 10407.583696652126, 3513.9824300878586, 3558.982205088984, 3580.3913334732897, 3603.9099022524383, 3606.5131207286945, 47202.76398617985, 47631.0, 47724.0, 47966.52033479655, 47989.52010479885, 10125.341852779546, 10163.136133428741, 10183.54174062169, 10264.384136951807, 10676.145908327315, 18586.07069646512, 18690.439286821416, 18695.25218991225, 18734.812651873308, 18749.156287967075, 7530.171681115183, 7596.468247222752, 7603.201663825329, 7682.0397450318715, 7864.842703145905, 4225.563308475133, 4247.532771395144, 4310.698251122391, 4382.517923028464, 4403.801828917706, 3033.6966303369486, 3081.5839861618842, 3085.2441151917924, 3085.8457077146054, 3087.644920834133, 543.6547792152012, 547.854818473105, 551.9337679478408, 552.1414200917611, 556.2796178948191, 499.94000719913845, 554.9139883318093, 572.68788510262, 601.4136217188294, 626.7023163997065, 7926.0, 8316.958415208006, 8375.539345335943, 8407.855996720797, 8570.271526920156, 552.2130963377172, 561.3208018297905, 637.346719612401, 650.6974256970436, 709.1490211745926, 4025.2352053109817, 4172.937405938914, 4267.1892340455215, 4309.612134907873, 4352.804123814436, 51421.0, 52331.47668523342, 52415.0, 52560.47439525632, 52577.0, 46922.29616555754, 47292.763536182785, 47838.28242576364, 47910.0, 48265.0, 42008.57991420108, 42195.156096878505, 42876.78561607172, 43221.78389108034, 43602.563974360484, 8643.697470588499, 8644.351673624378, 8888.333374996771, 8891.183273933213, 8941.284697224337, 30559.847200763856, 31371.843140785062, 31551.36897261996, 32092.83953580217, 32148.03555893327, 37980.430293546175, 41018.58981410267, 41032.0, 41863.0, 42000.789996049825, 10221.0, 10582.629607963685, 10794.56821727122, 11128.833067503832, 11518.308901465938, 12290.446929888356, 12353.567625133077, 12731.66641029344, 12788.232706037668, 12930.547430839875, 10240.743981400525, 10437.739056523647, 10452.216083793897, 10515.421651809223, 10711.41087240178, 10830.133589312994, 10925.945370273415, 11146.777064458827, 11240.269382490113, 11252.718682033015, 11105.555777768799, 11154.888451115385, 11536.192466527262, 11753.529858805552, 11972.76054478923, 9312.301577381859, 9315.86026209594, 9330.393524420891, 9394.530273486434, 9427.62289508412, 9106.817863642558, 9293.395929264576, 9397.483138427453, 9703.5148242589, 9723.854142188015], "unit": "score", "name": "yui.html", "value": 6176.2103116089065}], "name": "dromaeo_css", "value": 6754.128797359959}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 109.0, 103.0, 108.0, 110.0, 108.0, 98.0, 108.0, 108.0, 108.0], "unit": "score", "name": "ai-astar", "value": 106.9}, {"lowerIsBetter": true, "replicates": [157.0, 126.0, 150.0, 164.0, 125.0, 148.0, 150.0, 132.0, 176.0, 129.0], "unit": "score", "name": "audio-beat-detection", "value": 145.7}, {"lowerIsBetter": true, "replicates": [162.0, 161.0, 160.0, 161.0, 159.0, 163.0, 159.0, 163.0, 161.0, 160.0], "unit": "score", "name": "audio-dft", "value": 160.9}, {"lowerIsBetter": true, "replicates": [74.0, 73.0, 106.0, 94.0, 71.0, 71.0, 73.0, 113.0, 94.0, 76.0], "unit": "score", "name": "audio-fft", "value": 84.5}, {"lowerIsBetter": true, "replicates": [105.0, 95.0, 138.0, 95.0, 95.0, 95.0, 138.0, 96.0, 95.0, 139.0], "unit": "score", "name": "audio-oscillator", "value": 109.1}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 110.0, 110.0, 109.0, 109.0, 108.0, 109.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 146.0, 146.0, 145.0, 147.0, 146.0, 146.0, 146.0, 145.0], "unit": "score", "name": "imaging-darkroom", "value": 145.8}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [54.0, 55.0, 55.0, 56.0, 56.0, 54.0, 55.0, 63.0, 52.0, 55.0], "unit": "score", "name": "json-parse-financial", "value": 55.5}, {"lowerIsBetter": true, "replicates": [67.0, 69.0, 68.0, 68.0, 68.0, 71.0, 69.0, 68.0, 68.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.4}, {"lowerIsBetter": true, "replicates": [80.0, 81.0, 81.0, 80.0, 82.0, 86.0, 89.0, 81.0, 80.0, 80.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.0}, {"lowerIsBetter": true, "replicates": [124.0, 129.0, 113.0, 118.0, 116.0, 119.0, 117.0, 113.0, 120.0, 114.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 118.3}, {"lowerIsBetter": true, "replicates": [144.0, 147.0, 144.0, 145.0, 146.0, 136.0, 144.0, 146.0, 145.0, 147.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 144.4}, {"lowerIsBetter": true, "replicates": [69.0, 67.0, 67.0, 67.0, 74.0, 68.0, 67.0, 70.0, 67.0, 69.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.5}], "name": "kraken", "value": 1494.6000000000001}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.58337497917708], "unit": "score", "name": "Richards", "value": 211.91790600000007}, {"lowerIsBetter": false, "replicates": [190.22256039566292], "unit": "score", "name": "DeltaBlue", "value": 347.58232600000014}, {"lowerIsBetter": false, "replicates": [279.9552071668533], "unit": "score", "name": "Encrypt", "value": 950.7985320000004}, {"lowerIsBetter": false, "replicates": [5246.073298429319], "unit": "score", "name": "Decrypt", "value": 50.739092814371276}, {"lowerIsBetter": false, "replicates": [1949.317738791423], "unit": "score", "name": "RayTrace", "value": 379.61435700000015}, {"lowerIsBetter": false, "replicates": [891.3624220837044], "unit": "score", "name": "Earley", "value": 747.6902587412585}, {"lowerIsBetter": false, "replicates": [12280.487804878048], "unit": "score", "name": "Boyer", "value": 54.27007547169812}, {"lowerIsBetter": false, "replicates": [42270.833333333336], "unit": "score", "name": "RegExp", "value": 21.551148348940345}, {"lowerIsBetter": false, "replicates": [575.3739930955121], "unit": "score", "name": "Splay", "value": 141.631358}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 18765.643835168925}]} 05:52:50 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "18765.64"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1494.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6754.13"}}} 05:52:50 INFO - Return code: 0 05:52:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 05:52:50 INFO - # TBPL SUCCESS # 05:52:50 INFO - Running post-action listener: _resource_record_post_action 05:52:50 INFO - Running post-run listener: _resource_record_post_run 05:52:51 INFO - Total resource usage - Wall time: 1230s; CPU: 13.0%; Read bytes: 8192; Write bytes: 541974528; Read time: 316; Write time: 398416 05:52:51 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 193032192; Read time: 220; Write time: 182992 05:52:51 INFO - run-tests - Wall time: 1214s; CPU: 13.0%; Read bytes: 4096; Write bytes: 302481408; Read time: 96; Write time: 182204 05:52:51 INFO - Running post-run listener: _upload_blobber_files 05:52:51 INFO - Blob upload gear active. 05:52:51 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 05:52:51 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 05:52:51 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', 'Firefox', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 05:52:51 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 Firefox -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 05:52:51 INFO - (blobuploader) - INFO - Open directory for files ... 05:52:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 05:52:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:52:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:52 INFO - (blobuploader) - INFO - Done attempting. 05:52:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:52:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:52:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:53 INFO - (blobuploader) - INFO - Done attempting. 05:52:53 INFO - (blobuploader) - INFO - Iteration through files over. 05:52:53 INFO - Return code: 0 05:52:53 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 05:52:53 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 05:52:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ef4de175bfdaa00f3fb737db57ff542d9e1f1b4e4cdd96862dd3040dd0cc86560d5277fc667fc556ae7753a3530f24ffeb7e6b9aa5490c9ca0d36425f899da6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea64adf186ef767f6b5cd7802ced2ad8ae27e28af1ba818aaa4056ef79dd35ff0dd209781c45979edab74f22130bc5f615ce6a1a7de16e075e19caf47e9f69b5"} 05:52:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 05:52:53 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 05:52:53 INFO - Contents: 05:52:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ef4de175bfdaa00f3fb737db57ff542d9e1f1b4e4cdd96862dd3040dd0cc86560d5277fc667fc556ae7753a3530f24ffeb7e6b9aa5490c9ca0d36425f899da6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea64adf186ef767f6b5cd7802ced2ad8ae27e28af1ba818aaa4056ef79dd35ff0dd209781c45979edab74f22130bc5f615ce6a1a7de16e075e19caf47e9f69b5"} 05:52:53 INFO - Copying logs to upload dir... 05:52:53 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1282.400201 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-01-14 05:52:52.312190) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-14 05:52:52.315981) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ef4de175bfdaa00f3fb737db57ff542d9e1f1b4e4cdd96862dd3040dd0cc86560d5277fc667fc556ae7753a3530f24ffeb7e6b9aa5490c9ca0d36425f899da6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea64adf186ef767f6b5cd7802ced2ad8ae27e28af1ba818aaa4056ef79dd35ff0dd209781c45979edab74f22130bc5f615ce6a1a7de16e075e19caf47e9f69b5"} build_url:https://queue.taskcluster.net/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011365 build_url: 'https://queue.taskcluster.net/v1/task/aQjpZWi7QlWmPIsQ3NrC8w/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/Firefox/sha512/2ef4de175bfdaa00f3fb737db57ff542d9e1f1b4e4cdd96862dd3040dd0cc86560d5277fc667fc556ae7753a3530f24ffeb7e6b9aa5490c9ca0d36425f899da6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea64adf186ef767f6b5cd7802ced2ad8ae27e28af1ba818aaa4056ef79dd35ff0dd209781c45979edab74f22130bc5f615ce6a1a7de16e075e19caf47e9f69b5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-14 05:52:52.341014) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 05:52:52.341347) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1452776098.56044-652634815 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004381 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 05:52:52.425099) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-14 05:52:52.425410) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-14 05:52:52.425752) ========= ========= Total master_lag: 0.21 =========