builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-035 starttime: 1452307224.65 results: success (0) buildid: 20160108163004 builduid: 857424765f2d49a6997dbaf219a3addf revision: 0f363ae95dc90d593394ef464aa500804c824962 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:24.645402) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:24.645852) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:24.646172) ========= 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=ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006311 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:24.688293) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:24.688606) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:24.716520) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:24.716837) ========= 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=ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004553 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:24.768220) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:24.768529) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:24.768942) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:24.769334) ========= 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=ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-08 18:40:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.3M=0s 2016-01-08 18:40:25 (43.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116814 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:24.907134) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:24.907446) ========= 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=ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022670 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:24.974323) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:24.974960) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0f363ae95dc90d593394ef464aa500804c824962 --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 0f363ae95dc90d593394ef464aa500804c824962 --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=ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-08 18:40:25,588 truncating revision to first 12 chars 2016-01-08 18:40:25,588 Setting DEBUG logging. 2016-01-08 18:40:25,588 attempt 1/10 2016-01-08 18:40:25,588 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0f363ae95dc9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-08 18:40:25,900 unpacking tar archive at: mozilla-central-0f363ae95dc9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.588612 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:25.580025) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:25.580347) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:25.591750) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:25.592034) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-08 18:40:25.592435) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-01-08 18:40:25.592785) ========= /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=ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:40:26 INFO - MultiFileLogger online at 20160108 18:40:26 in /builds/slave/test-pgo 18:40:26 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 18:40:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 18:40:26 INFO - {'append_to_log': False, 18:40:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 18:40:26 INFO - 'blob_upload_branch': 'Firefox', 18:40:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 18:40:26 INFO - 'branch': 'Firefox', 18:40:26 INFO - 'buildbot_json_path': 'buildprops.json', 18:40:26 INFO - 'config_files': ('talos/linux_config.py',), 18:40:26 INFO - 'default_actions': ('clobber', 18:40:26 INFO - 'read-buildbot-config', 18:40:26 INFO - 'download-and-extract', 18:40:26 INFO - 'populate-webroot', 18:40:26 INFO - 'create-virtualenv', 18:40:26 INFO - 'install', 18:40:26 INFO - 'run-tests'), 18:40:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:40:26 INFO - 'download_minidump_stackwalk': True, 18:40:26 INFO - 'download_symbols': 'ondemand', 18:40:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:40:26 INFO - 'tooltool.py': '/tools/tooltool.py', 18:40:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:40:26 INFO - '/tools/misc-python/virtualenv.py')}, 18:40:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:40:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:40:26 INFO - 'installer_path': 'installer.exe', 18:40:26 INFO - 'log_level': 'info', 18:40:26 INFO - 'log_name': 'talos', 18:40:26 INFO - 'log_to_console': True, 18:40:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:40:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:40:26 INFO - 'opt_config_files': (), 18:40:26 INFO - 'pip_index': False, 18:40:26 INFO - 'sps_profile': False, 18:40:26 INFO - 'sps_profile_interval': 0, 18:40:26 INFO - 'suite': 'dromaeojs', 18:40:26 INFO - 'system_bits': '32', 18:40:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:40:26 INFO - 'title': 'talos-linux64-ix-035', 18:40:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:40:26 INFO - 'use_talos_json': True, 18:40:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 18:40:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:40:26 INFO - 'work_dir': 'build'} 18:40:26 INFO - ##### 18:40:26 INFO - ##### Running clobber step. 18:40:26 INFO - ##### 18:40:26 INFO - Running pre-action listener: _resource_record_pre_action 18:40:26 INFO - Running main action method: clobber 18:40:26 INFO - rmtree: /builds/slave/test-pgo/build 18:40:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 18:40:27 INFO - Running post-action listener: _resource_record_post_action 18:40:27 INFO - ##### 18:40:27 INFO - ##### Running read-buildbot-config step. 18:40:27 INFO - ##### 18:40:27 INFO - Running pre-action listener: _resource_record_pre_action 18:40:27 INFO - Running main action method: read_buildbot_config 18:40:27 INFO - Using buildbot properties: 18:40:27 INFO - { 18:40:27 INFO - "project": "", 18:40:27 INFO - "product": "firefox", 18:40:27 INFO - "script_repo_revision": "production", 18:40:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:40:27 INFO - "repository": "", 18:40:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 18:40:27 INFO - "buildid": "20160108163004", 18:40:27 INFO - "pgo_build": "True", 18:40:27 INFO - "basedir": "/builds/slave/test-pgo", 18:40:27 INFO - "buildnumber": 635, 18:40:27 INFO - "slavename": "talos-linux64-ix-035", 18:40:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:40:27 INFO - "platform": "ubuntu64_hw", 18:40:27 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 18:40:27 INFO - "branch": "mozilla-central", 18:40:27 INFO - "repo_path": "mozilla-central", 18:40:27 INFO - "revision": "0f363ae95dc90d593394ef464aa500804c824962", 18:40:27 INFO - "stage_platform": "linux64-pgo", 18:40:27 INFO - "builduid": "857424765f2d49a6997dbaf219a3addf", 18:40:27 INFO - "slavebuilddir": "test" 18:40:27 INFO - } 18:40:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 18:40:27 INFO - Running post-action listener: _resource_record_post_action 18:40:27 INFO - ##### 18:40:27 INFO - ##### Running download-and-extract step. 18:40:27 INFO - ##### 18:40:27 INFO - Running pre-action listener: _resource_record_pre_action 18:40:27 INFO - Running main action method: download_and_extract 18:40:27 INFO - mkdir: /builds/slave/test-pgo/build/tests 18:40:27 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')]} 18:40:27 INFO - https://queue.taskcluster.net/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 18:40:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/test_packages.json 18:40:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/test_packages.json 18:40:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 18:40:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 18:40:29 INFO - Downloaded 1302 bytes. 18:40:29 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 18:40:29 INFO - Using the following test package requirements: 18:40:29 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 18:40:29 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 18:40:29 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:40:29 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 18:40:29 INFO - u'jsshell-linux-x86_64.zip'], 18:40:29 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 18:40:29 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:40:29 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 18:40:29 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 18:40:29 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:40:29 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 18:40:29 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:40:29 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 18:40:29 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:40:29 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 18:40:29 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 18:40:29 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:40:29 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:40:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:29 INFO - https://queue.taskcluster.net/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:40:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 18:40:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 18:40:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/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 18:40:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/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 18:40:31 INFO - Downloaded 24028222 bytes. 18:40:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:40:31 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 18:40:31 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:40:32 INFO - Return code: 0 18:40:32 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 18:40: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')]} 18:40:32 INFO - https://queue.taskcluster.net/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:40:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 18:40:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 18:40:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/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 18:40:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/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 18:40:33 INFO - Downloaded 24028222 bytes. 18:40:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:40:33 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 18:40:33 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:40:34 INFO - Return code: 0 18:40:34 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')]} 18:40:34 INFO - https://queue.taskcluster.net/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:40:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 18:40:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 18:40:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/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 18:40:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/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 18:40:35 INFO - Downloaded 11132285 bytes. 18:40:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 18:40:35 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 18:40:35 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 18:40:36 INFO - Return code: 0 18:40: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')]} 18:40:36 INFO - https://queue.taskcluster.net/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:40:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 18:40:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 18:40:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/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 18:40:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/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 18:40:37 INFO - Downloaded 55896222 bytes. 18:40:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 18:40:37 INFO - mkdir: /builds/slave/test-pgo/properties 18:40:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 18:40:37 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 18:40:37 INFO - Contents: 18:40:37 INFO - build_url:https://queue.taskcluster.net/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 18:40:37 INFO - Running post-action listener: _resource_record_post_action 18:40:37 INFO - Running post-action listener: set_extra_try_arguments 18:40:37 INFO - ##### 18:40:37 INFO - ##### Running populate-webroot step. 18:40:37 INFO - ##### 18:40:37 INFO - Running pre-action listener: _resource_record_pre_action 18:40:37 INFO - Running main action method: populate_webroot 18:40:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:40:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:40:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:40:37 INFO - '--tppagecycles', 18:40:37 INFO - '7'], 18:40:37 INFO - 'tests': ['tsvgm']}}, 18:40:37 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:40:37 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:40:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:40:37 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:40:37 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:40:37 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:40:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:40:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:37 INFO - 'talos_options': ['--e10s'], 18:40:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:40:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:37 INFO - 'tests': ['damp', 'tps']}, 18:40:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:37 INFO - 'talos_options': ['--e10s'], 18:40:37 INFO - 'tests': ['damp', 'tps']}, 18:40:37 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:40:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:40:37 INFO - 'other': {'tests': ['a11yr', 18:40:37 INFO - 'ts_paint', 18:40:37 INFO - 'tpaint', 18:40:37 INFO - 'sessionrestore', 18:40:37 INFO - 'sessionrestore_no_auto_restore']}, 18:40:37 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:40:37 INFO - 'tests': ['a11yr', 18:40:37 INFO - 'ts_paint', 18:40:37 INFO - 'tpaint', 18:40:37 INFO - 'sessionrestore', 18:40:37 INFO - 'sessionrestore_no_auto_restore']}, 18:40:37 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:40:37 INFO - 'tests': ['a11yr', 18:40:37 INFO - 'ts_paint', 18:40:37 INFO - 'tpaint', 18:40:37 INFO - 'sessionrestore', 18:40:37 INFO - 'sessionrestore_no_auto_restore']}, 18:40:37 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:40:37 INFO - 'tests': ['a11yr', 18:40:37 INFO - 'ts_paint', 18:40:37 INFO - 'tpaint', 18:40:37 INFO - 'sessionrestore', 18:40:37 INFO - 'sessionrestore_no_auto_restore']}, 18:40:37 INFO - 'other_l64': {'tests': ['a11yr', 18:40:37 INFO - 'ts_paint', 18:40:37 INFO - 'tpaint', 18:40:37 INFO - 'sessionrestore', 18:40:37 INFO - 'sessionrestore_no_auto_restore']}, 18:40:37 INFO - 'other_nol64': {'tests': ['a11yr', 18:40:37 INFO - 'ts_paint', 18:40:37 INFO - 'tpaint', 18:40:37 INFO - 'sessionrestore', 18:40:37 INFO - 'sessionrestore_no_auto_restore']}, 18:40:37 INFO - 'svgr': {'tests': ['tsvgx', 18:40:37 INFO - 'tsvgr_opacity', 18:40:37 INFO - 'tart', 18:40:37 INFO - 'tscrollx', 18:40:37 INFO - 'cart']}, 18:40:37 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:40:37 INFO - 'tests': ['tsvgx', 18:40:37 INFO - 'tsvgr_opacity', 18:40:37 INFO - 'tart', 18:40:37 INFO - 'tscrollx', 18:40:37 INFO - 'cart']}, 18:40:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:37 INFO - 'tests': ['tp5o']}, 18:40:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:37 INFO - 'talos_options': ['--e10s'], 18:40:37 INFO - 'tests': ['tp5o']}, 18:40:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:37 INFO - 'talos_options': ['--xperf_path', 18:40:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:40:37 INFO - 'tests': ['tp5n']}, 18:40:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:37 INFO - 'talos_options': ['--e10s', 18:40:37 INFO - '--xperf_path', 18:40:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:40:37 INFO - 'tests': ['tp5n']}}, 18:40:37 INFO - 'talos.zip': {'path': '', 18:40:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:40:37 INFO - Running post-action listener: _resource_record_post_action 18:40:37 INFO - ##### 18:40:37 INFO - ##### Running create-virtualenv step. 18:40:37 INFO - ##### 18:40:37 INFO - Running pre-action listener: _resource_record_pre_action 18:40:37 INFO - Running main action method: create_virtualenv 18:40:37 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 18:40:37 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 18:40:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 18:40:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:40:38 INFO - Using real prefix '/usr' 18:40:38 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 18:40:38 INFO - Installing distribute.............................................................................................................................................................................................done. 18:40:40 INFO - Installing pip.................done. 18:40:40 INFO - Return code: 0 18:40:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 18:40:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:40 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 0x7fd635b00f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2530630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c1840>, '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 0x252e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x252eb60>, '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': 'ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409', '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 18:40:40 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 18:40:40 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 18:40:40 INFO - Using env: {'DISPLAY': ':0', 18:40:40 INFO - 'HOME': '/home/cltbld', 18:40:40 INFO - 'LANG': 'en_US.UTF-8', 18:40:40 INFO - 'LANGUAGE': 'en_US:en', 18:40:40 INFO - 'LOGNAME': 'cltbld', 18:40:40 INFO - 'MAIL': '/var/mail/cltbld', 18:40:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:40 INFO - 'MOZ_NO_REMOTE': '1', 18:40:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:40:40 INFO - 'NO_EM_RESTART': '1', 18:40:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:40:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:40:40 INFO - 'PWD': '/builds/slave/test-pgo', 18:40:40 INFO - 'SHELL': '/bin/bash', 18:40:40 INFO - 'SHLVL': '1', 18:40:40 INFO - 'TERM': 'linux', 18:40:40 INFO - 'TMOUT': '86400', 18:40:40 INFO - 'USER': 'cltbld', 18:40:40 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409', 18:40:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:40 INFO - '_': '/tools/buildbot/bin/python'} 18:40:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:40 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 18:40:40 INFO - Cleaning up... 18:40:40 INFO - Return code: 0 18:40:40 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 18:40:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:40 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 0x7fd635b00f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2530630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c1840>, '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 0x252e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x252eb60>, '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': 'ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409', '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 18:40:40 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 18:40:40 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 18:40:40 INFO - Using env: {'DISPLAY': ':0', 18:40:40 INFO - 'HOME': '/home/cltbld', 18:40:40 INFO - 'LANG': 'en_US.UTF-8', 18:40:40 INFO - 'LANGUAGE': 'en_US:en', 18:40:40 INFO - 'LOGNAME': 'cltbld', 18:40:40 INFO - 'MAIL': '/var/mail/cltbld', 18:40:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:40 INFO - 'MOZ_NO_REMOTE': '1', 18:40:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:40:40 INFO - 'NO_EM_RESTART': '1', 18:40:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:40:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:40:40 INFO - 'PWD': '/builds/slave/test-pgo', 18:40:40 INFO - 'SHELL': '/bin/bash', 18:40:40 INFO - 'SHLVL': '1', 18:40:40 INFO - 'TERM': 'linux', 18:40:40 INFO - 'TMOUT': '86400', 18:40:40 INFO - 'USER': 'cltbld', 18:40:40 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409', 18:40:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:40 INFO - '_': '/tools/buildbot/bin/python'} 18:40:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:41 INFO - Downloading/unpacking psutil>=0.7.1 18:40:41 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 18:40:41 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 18:40:41 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 18:40:41 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 18:40:41 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 18:40:41 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 18:40:42 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 18:40:42 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:40:42 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 18:40:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:40:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:40:42 INFO - Installing collected packages: psutil 18:40:42 INFO - Running setup.py install for psutil 18:40:42 INFO - building 'psutil._psutil_linux' extension 18:40:42 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 18:40:43 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 18:40:43 INFO - building 'psutil._psutil_posix' extension 18:40:43 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 18:40:43 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 18:40:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:40:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:40:43 INFO - Successfully installed psutil 18:40:43 INFO - Cleaning up... 18:40:43 INFO - Return code: 0 18:40:43 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 18:40:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:43 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 0x7fd635b00f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2530630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c1840>, '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 0x252e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x252eb60>, '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': 'ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409', '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 18:40:43 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 18:40:43 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 18:40:43 INFO - Using env: {'DISPLAY': ':0', 18:40:43 INFO - 'HOME': '/home/cltbld', 18:40:43 INFO - 'LANG': 'en_US.UTF-8', 18:40:43 INFO - 'LANGUAGE': 'en_US:en', 18:40:43 INFO - 'LOGNAME': 'cltbld', 18:40:43 INFO - 'MAIL': '/var/mail/cltbld', 18:40:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:43 INFO - 'MOZ_NO_REMOTE': '1', 18:40:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:40:43 INFO - 'NO_EM_RESTART': '1', 18:40:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:40:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:40:43 INFO - 'PWD': '/builds/slave/test-pgo', 18:40:43 INFO - 'SHELL': '/bin/bash', 18:40:43 INFO - 'SHLVL': '1', 18:40:43 INFO - 'TERM': 'linux', 18:40:43 INFO - 'TMOUT': '86400', 18:40:43 INFO - 'USER': 'cltbld', 18:40:43 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409', 18:40:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:43 INFO - '_': '/tools/buildbot/bin/python'} 18:40:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:43 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:40:43 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 18:40:43 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 18:40:43 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 18:40:43 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 18:40:43 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 18:40:43 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 18:40:45 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:40:45 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:40:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:40:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:40:45 INFO - Installing collected packages: mozsystemmonitor 18:40:45 INFO - Running setup.py install for mozsystemmonitor 18:40:45 INFO - Successfully installed mozsystemmonitor 18:40:45 INFO - Cleaning up... 18:40:45 INFO - Return code: 0 18:40:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 18:40:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:45 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 0x7fd635b00f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2530630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c1840>, '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 0x252e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x252eb60>, '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': 'ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409', '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 18:40:45 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 18:40:45 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 18:40:45 INFO - Using env: {'DISPLAY': ':0', 18:40:45 INFO - 'HOME': '/home/cltbld', 18:40:45 INFO - 'LANG': 'en_US.UTF-8', 18:40:45 INFO - 'LANGUAGE': 'en_US:en', 18:40:45 INFO - 'LOGNAME': 'cltbld', 18:40:45 INFO - 'MAIL': '/var/mail/cltbld', 18:40:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:45 INFO - 'MOZ_NO_REMOTE': '1', 18:40:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:40:45 INFO - 'NO_EM_RESTART': '1', 18:40:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:40:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:40:45 INFO - 'PWD': '/builds/slave/test-pgo', 18:40:45 INFO - 'SHELL': '/bin/bash', 18:40:45 INFO - 'SHLVL': '1', 18:40:45 INFO - 'TERM': 'linux', 18:40:45 INFO - 'TMOUT': '86400', 18:40:45 INFO - 'USER': 'cltbld', 18:40:45 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409', 18:40:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:45 INFO - '_': '/tools/buildbot/bin/python'} 18:40:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:45 INFO - Downloading/unpacking blobuploader==1.2.4 18:40:45 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 18:40:45 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 18:40:45 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 18:40:45 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 18:40:45 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 18:40:45 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 18:40:47 INFO - Downloading blobuploader-1.2.4.tar.gz 18:40:47 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:40:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:40:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:40:47 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 18:40:47 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 18:40:47 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 18:40:47 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 18:40:47 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 18:40:47 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 18:40:47 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:40:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 18:40:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:40:47 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 18:40:47 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 18:40:47 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 18:40:47 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 18:40:47 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 18:40:47 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 18:40:48 INFO - Downloading docopt-0.6.1.tar.gz 18:40:48 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:40:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 18:40:48 INFO - Installing collected packages: blobuploader, requests, docopt 18:40:48 INFO - Running setup.py install for blobuploader 18:40:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:40:48 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 18:40:48 INFO - Running setup.py install for requests 18:40:48 INFO - Running setup.py install for docopt 18:40:48 INFO - Successfully installed blobuploader requests docopt 18:40:48 INFO - Cleaning up... 18:40:48 INFO - Return code: 0 18:40:48 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:40:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:48 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 0x7fd635b00f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2530630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c1840>, '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 0x252e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x252eb60>, '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': 'ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409', '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 18:40:48 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 18:40:48 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 18:40:48 INFO - Using env: {'DISPLAY': ':0', 18:40:48 INFO - 'HOME': '/home/cltbld', 18:40:48 INFO - 'LANG': 'en_US.UTF-8', 18:40:48 INFO - 'LANGUAGE': 'en_US:en', 18:40:48 INFO - 'LOGNAME': 'cltbld', 18:40:48 INFO - 'MAIL': '/var/mail/cltbld', 18:40:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:48 INFO - 'MOZ_NO_REMOTE': '1', 18:40:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:40:48 INFO - 'NO_EM_RESTART': '1', 18:40:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:40:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:40:48 INFO - 'PWD': '/builds/slave/test-pgo', 18:40:48 INFO - 'SHELL': '/bin/bash', 18:40:48 INFO - 'SHLVL': '1', 18:40:48 INFO - 'TERM': 'linux', 18:40:48 INFO - 'TMOUT': '86400', 18:40:48 INFO - 'USER': 'cltbld', 18:40:48 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409', 18:40:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:48 INFO - '_': '/tools/buildbot/bin/python'} 18:40:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:40:48 INFO - Running setup.py (path:/tmp/pip-div07c-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:40:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:40:48 INFO - Running setup.py (path:/tmp/pip-dqbLeY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:40:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:40:49 INFO - Running setup.py (path:/tmp/pip-E5khus-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:40:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:40:49 INFO - Running setup.py (path:/tmp/pip-pgNNUL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:40:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:40:49 INFO - Running setup.py (path:/tmp/pip-SwtNJA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:40:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:40:49 INFO - Running setup.py (path:/tmp/pip-XAVMWq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:40:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:40:49 INFO - Running setup.py (path:/tmp/pip-X2lUKI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:40:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:40:49 INFO - Running setup.py (path:/tmp/pip-wVkOjI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:40:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:40:49 INFO - Running setup.py (path:/tmp/pip-DJgKBm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:40:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:40:49 INFO - Running setup.py (path:/tmp/pip-gFCLGa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:40:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:40:49 INFO - Running setup.py (path:/tmp/pip-l4o8iD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:40:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:40:49 INFO - Running setup.py (path:/tmp/pip-ziroMc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:40:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:40:49 INFO - Running setup.py (path:/tmp/pip-K4B9FU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:40:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:40:49 INFO - Running setup.py (path:/tmp/pip-8CjnEb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:40:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:40:49 INFO - Running setup.py (path:/tmp/pip-VV5niM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:40:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:40:49 INFO - Running setup.py (path:/tmp/pip-FgxC1d-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:40:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:40:50 INFO - Running setup.py (path:/tmp/pip-_tPzMA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:40:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:40:50 INFO - Running setup.py install for manifestparser 18:40:50 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 18:40:50 INFO - Running setup.py install for mozcrash 18:40:50 INFO - Running setup.py install for mozdebug 18:40:50 INFO - Running setup.py install for mozdevice 18:40:50 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 18:40:50 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 18:40:50 INFO - Running setup.py install for mozfile 18:40:50 INFO - Running setup.py install for mozhttpd 18:40:50 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 18:40:50 INFO - Running setup.py install for mozinfo 18:40:50 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 18:40:50 INFO - Running setup.py install for mozInstall 18:40:50 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 18:40:50 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 18:40:50 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 18:40:50 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 18:40:50 INFO - Running setup.py install for mozleak 18:40:51 INFO - Running setup.py install for mozlog 18:40:51 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 18:40:51 INFO - Running setup.py install for moznetwork 18:40:51 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 18:40:51 INFO - Running setup.py install for mozprocess 18:40:51 INFO - Running setup.py install for mozprofile 18:40:51 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 18:40:51 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 18:40:51 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 18:40:51 INFO - Running setup.py install for mozrunner 18:40:51 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 18:40:51 INFO - Running setup.py install for mozscreenshot 18:40:51 INFO - Running setup.py install for moztest 18:40:51 INFO - Running setup.py install for mozversion 18:40:51 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 18:40:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:40:51 INFO - Cleaning up... 18:40:51 INFO - Return code: 0 18:40:51 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:40:51 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')]} 18:40:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:51 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')]} 18:40:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:51 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 0x7fd635b00f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2530630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c1840>, '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 0x252e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x252eb60>, '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': 'ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409', '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 18:40:51 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 18:40:51 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 18:40:51 INFO - Using env: {'DISPLAY': ':0', 18:40:51 INFO - 'HOME': '/home/cltbld', 18:40:51 INFO - 'LANG': 'en_US.UTF-8', 18:40:51 INFO - 'LANGUAGE': 'en_US:en', 18:40:51 INFO - 'LOGNAME': 'cltbld', 18:40:51 INFO - 'MAIL': '/var/mail/cltbld', 18:40:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:51 INFO - 'MOZ_NO_REMOTE': '1', 18:40:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:40:51 INFO - 'NO_EM_RESTART': '1', 18:40:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:40:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:40:51 INFO - 'PWD': '/builds/slave/test-pgo', 18:40:51 INFO - 'SHELL': '/bin/bash', 18:40:51 INFO - 'SHLVL': '1', 18:40:51 INFO - 'TERM': 'linux', 18:40:51 INFO - 'TMOUT': '86400', 18:40:51 INFO - 'USER': 'cltbld', 18:40:51 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409', 18:40:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:51 INFO - '_': '/tools/buildbot/bin/python'} 18:40:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:40:52 INFO - Running setup.py (path:/tmp/pip-V5LyrW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:40:52 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)) 18:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:40:52 INFO - Running setup.py (path:/tmp/pip-88OaRx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:40:52 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)) 18:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:40:52 INFO - Running setup.py (path:/tmp/pip-fvRzKm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:40:52 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)) 18:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:40:52 INFO - Running setup.py (path:/tmp/pip-yTIs_j-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:40:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:40:52 INFO - Running setup.py (path:/tmp/pip-6Dit7Y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:40:52 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)) 18:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:40:52 INFO - Running setup.py (path:/tmp/pip-tzrQT1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:40:52 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)) 18:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:40:52 INFO - Running setup.py (path:/tmp/pip-UZNMPD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:40:52 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)) 18:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:40:52 INFO - Running setup.py (path:/tmp/pip-iZ33Q5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:40:52 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)) 18:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:40:52 INFO - Running setup.py (path:/tmp/pip-XavCY1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:40:52 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)) 18:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:40:52 INFO - Running setup.py (path:/tmp/pip-7DtusT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:40:52 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)) 18:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:40:52 INFO - Running setup.py (path:/tmp/pip-fPwVZJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:40:52 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)) 18:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:40:52 INFO - Running setup.py (path:/tmp/pip-vHrQyo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:40:52 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)) 18:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:40:52 INFO - Running setup.py (path:/tmp/pip-HBOtGM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:40:53 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)) 18:40:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:40:53 INFO - Running setup.py (path:/tmp/pip-vp_2Nk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:40:53 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)) 18:40:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:40:53 INFO - Running setup.py (path:/tmp/pip-P9INZu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:40:53 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)) 18:40:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:40:53 INFO - Running setup.py (path:/tmp/pip-ou56w5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:40:53 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)) 18:40:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:40:53 INFO - Running setup.py (path:/tmp/pip-OMWrCk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:40:53 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)) 18:40:53 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)) 18:40:53 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)) 18:40:53 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)) 18:40:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:40:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:40:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:40:53 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 18:40:53 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 18:40:53 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 18:40:53 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 18:40:53 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 18:40:53 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 18:40:55 INFO - Downloading blessings-1.5.1.tar.gz 18:40:55 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 18:40:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 18:40:55 INFO - Installing collected packages: blessings 18:40:55 INFO - Running setup.py install for blessings 18:40:55 INFO - Successfully installed blessings 18:40:55 INFO - Cleaning up... 18:40:55 INFO - Return code: 0 18:40:55 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 18:40:55 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:40:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:40:55 INFO - Reading from file tmpfile_stdout 18:40:55 INFO - Current package versions: 18:40:55 INFO - argparse == 1.2.1 18:40:55 INFO - blessings == 1.5.1 18:40:55 INFO - blobuploader == 1.2.4 18:40:55 INFO - docopt == 0.6.1 18:40:55 INFO - manifestparser == 1.1 18:40:55 INFO - mozInstall == 1.12 18:40:55 INFO - mozcrash == 0.16 18:40:55 INFO - mozdebug == 0.1 18:40:55 INFO - mozdevice == 0.47 18:40:55 INFO - mozfile == 1.2 18:40:55 INFO - mozhttpd == 0.7 18:40:55 INFO - mozinfo == 0.9 18:40:55 INFO - mozleak == 0.1 18:40:55 INFO - mozlog == 3.1 18:40:55 INFO - moznetwork == 0.27 18:40:55 INFO - mozprocess == 0.22 18:40:55 INFO - mozprofile == 0.28 18:40:55 INFO - mozrunner == 6.11 18:40:55 INFO - mozscreenshot == 0.1 18:40:55 INFO - mozsystemmonitor == 0.0 18:40:55 INFO - moztest == 0.7 18:40:55 INFO - mozversion == 1.4 18:40:55 INFO - psutil == 3.1.1 18:40:55 INFO - requests == 1.2.3 18:40:55 INFO - wsgiref == 0.1.2 18:40:55 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:55 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 0x7fd635b00f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2530630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c1840>, '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 0x252e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x252eb60>, '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': 'ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409', '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 18:40:55 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 18:40:55 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 18:40:55 INFO - Using env: {'DISPLAY': ':0', 18:40:55 INFO - 'HOME': '/home/cltbld', 18:40:55 INFO - 'LANG': 'en_US.UTF-8', 18:40:55 INFO - 'LANGUAGE': 'en_US:en', 18:40:55 INFO - 'LOGNAME': 'cltbld', 18:40:55 INFO - 'MAIL': '/var/mail/cltbld', 18:40:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:55 INFO - 'MOZ_NO_REMOTE': '1', 18:40:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:40:55 INFO - 'NO_EM_RESTART': '1', 18:40:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:40:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:40:55 INFO - 'PWD': '/builds/slave/test-pgo', 18:40:55 INFO - 'SHELL': '/bin/bash', 18:40:55 INFO - 'SHLVL': '1', 18:40:55 INFO - 'TERM': 'linux', 18:40:55 INFO - 'TMOUT': '86400', 18:40:55 INFO - 'USER': 'cltbld', 18:40:55 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409', 18:40:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:55 INFO - '_': '/tools/buildbot/bin/python'} 18:40:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:55 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)) 18:40:55 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)) 18:40:55 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)) 18:40:55 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)) 18:40:55 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)) 18:40:55 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)) 18:40:55 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)) 18:40:55 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)) 18:40:55 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)) 18:40:55 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)) 18:40:55 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)) 18:40:55 INFO - Cleaning up... 18:40:55 INFO - Return code: 0 18:40:55 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 18:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:55 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 0x7fd635b00f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2530630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c1840>, '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 0x252e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x252eb60>, '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': 'ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409', '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 18:40:55 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 18:40:55 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 18:40:55 INFO - Using env: {'DISPLAY': ':0', 18:40:55 INFO - 'HOME': '/home/cltbld', 18:40:55 INFO - 'LANG': 'en_US.UTF-8', 18:40:55 INFO - 'LANGUAGE': 'en_US:en', 18:40:55 INFO - 'LOGNAME': 'cltbld', 18:40:55 INFO - 'MAIL': '/var/mail/cltbld', 18:40:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:55 INFO - 'MOZ_NO_REMOTE': '1', 18:40:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:40:55 INFO - 'NO_EM_RESTART': '1', 18:40:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:40:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:40:55 INFO - 'PWD': '/builds/slave/test-pgo', 18:40:55 INFO - 'SHELL': '/bin/bash', 18:40:55 INFO - 'SHLVL': '1', 18:40:55 INFO - 'TERM': 'linux', 18:40:55 INFO - 'TMOUT': '86400', 18:40:55 INFO - 'USER': 'cltbld', 18:40:55 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409', 18:40:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:55 INFO - '_': '/tools/buildbot/bin/python'} 18:40:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:55 INFO - Downloading/unpacking jsonschema 18:40:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:40:57 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 18:40:57 INFO - Downloading/unpacking functools32 (from jsonschema) 18:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:57 INFO - Downloading functools32-3.2.3-2.tar.gz 18:40:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 18:40:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 18:40:57 INFO - warning: no files found matching '*.txt' 18:40:57 INFO - no previously-included directories found matching 'build' 18:40:57 INFO - no previously-included directories found matching 'dist' 18:40:57 INFO - no previously-included directories found matching '.git*' 18:40:57 INFO - Installing collected packages: jsonschema, functools32 18:40:57 INFO - Running setup.py install for functools32 18:40:57 INFO - warning: no files found matching '*.txt' 18:40:57 INFO - no previously-included directories found matching 'build' 18:40:57 INFO - no previously-included directories found matching 'dist' 18:40:58 INFO - no previously-included directories found matching '.git*' 18:40:58 INFO - Successfully installed jsonschema functools32 18:40:58 INFO - Cleaning up... 18:40:58 INFO - Return code: 0 18:40:58 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 18:40:58 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')]} 18:40:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:58 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')]} 18:40:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:58 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 0x7fd635b00f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2530630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c1840>, '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 0x252e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x252eb60>, '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': 'ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409', '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 18:40:58 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 18:40:58 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 18:40:58 INFO - Using env: {'DISPLAY': ':0', 18:40:58 INFO - 'HOME': '/home/cltbld', 18:40:58 INFO - 'LANG': 'en_US.UTF-8', 18:40:58 INFO - 'LANGUAGE': 'en_US:en', 18:40:58 INFO - 'LOGNAME': 'cltbld', 18:40:58 INFO - 'MAIL': '/var/mail/cltbld', 18:40:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:58 INFO - 'MOZ_NO_REMOTE': '1', 18:40:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:40:58 INFO - 'NO_EM_RESTART': '1', 18:40:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:40:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:40:58 INFO - 'PWD': '/builds/slave/test-pgo', 18:40:58 INFO - 'SHELL': '/bin/bash', 18:40:58 INFO - 'SHLVL': '1', 18:40:58 INFO - 'TERM': 'linux', 18:40:58 INFO - 'TMOUT': '86400', 18:40:58 INFO - 'USER': 'cltbld', 18:40:58 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409', 18:40:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:58 INFO - '_': '/tools/buildbot/bin/python'} 18:40:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:58 INFO - Downloading/unpacking flake8 18:40:58 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 18:40:58 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 18:40:58 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 18:40:58 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 18:40:58 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 18:40:58 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 18:40:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:40:59 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 18:40:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:40: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 18:40: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 18:40: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 18:40: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 18:40: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 18:40: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 18:41:00 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:41:00 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 18:41:00 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:41:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:41:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:41:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:00 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 18:41:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:41:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:41:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:41:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 18:41:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 18:41:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:41:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:41:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:41:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:41:00 INFO - no previously-included directories found matching 'docs/_build' 18:41:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:41:00 INFO - Running setup.py install for pep8 18:41:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:41:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:41:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:41:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:41:00 INFO - no previously-included directories found matching 'docs/_build' 18:41:00 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 18:41:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:41:00 INFO - Cleaning up... 18:41:00 INFO - Return code: 0 18:41:00 INFO - Running post-action listener: _resource_record_post_action 18:41:00 INFO - Running post-action listener: _start_resource_monitoring 18:41:00 INFO - Starting resource monitoring. 18:41:00 INFO - ##### 18:41:00 INFO - ##### Running install step. 18:41:00 INFO - ##### 18:41:00 INFO - Running pre-action listener: _resource_record_pre_action 18:41:00 INFO - Running main action method: install 18:41:00 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:41:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:41:01 INFO - Reading from file tmpfile_stdout 18:41:01 INFO - Detecting whether we're running mozinstall >=1.0... 18:41:01 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 18:41:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 18:41:01 INFO - Reading from file tmpfile_stdout 18:41:01 INFO - Output received: 18:41:01 INFO - Usage: mozinstall [options] installer 18:41:01 INFO - Options: 18:41:01 INFO - -h, --help show this help message and exit 18:41:01 INFO - -d DEST, --destination=DEST 18:41:01 INFO - Directory to install application into. [default: 18:41:01 INFO - "/builds/slave/test-pgo"] 18:41:01 INFO - --app=APP Application being installed. [default: firefox] 18:41:01 INFO - mkdir: /builds/slave/test-pgo/build/application 18:41:01 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'] 18:41:01 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 18:41:17 INFO - Reading from file tmpfile_stdout 18:41:17 INFO - Output received: 18:41:17 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 18:41:17 INFO - Running post-action listener: _resource_record_post_action 18:41:17 INFO - ##### 18:41:17 INFO - ##### Running run-tests step. 18:41:17 INFO - ##### 18:41:17 INFO - Running pre-action listener: _resource_record_pre_action 18:41:17 INFO - Running main action method: run_tests 18:41:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 18:41:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 18:41:17 INFO - Python 2.7.3 18:41:17 INFO - Return code: 0 18:41:17 INFO - grabbing minidump binary from tooltool 18:41:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:41:17 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 0x25c1840>, '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 0x252e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x252eb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 18:41:17 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 18:41:17 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 18:41:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:41:17 INFO - Return code: 0 18:41:17 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 18:41:17 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 18:41:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 18:41:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 18:41:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 18:41:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 18:41:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 18:41:18 INFO - Return code: 0 18:41:18 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/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 18:41:18 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/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --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 18:41:18 INFO - Using env: {'DISPLAY': ':0', 18:41:18 INFO - 'HOME': '/home/cltbld', 18:41:18 INFO - 'LANG': 'en_US.UTF-8', 18:41:18 INFO - 'LANGUAGE': 'en_US:en', 18:41:18 INFO - 'LOGNAME': 'cltbld', 18:41:18 INFO - 'MAIL': '/var/mail/cltbld', 18:41:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:41:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 18:41:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:18 INFO - 'MOZ_NO_REMOTE': '1', 18:41:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:41:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:18 INFO - 'NO_EM_RESTART': '1', 18:41:18 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:41:18 INFO - 'PWD': '/builds/slave/test-pgo', 18:41:18 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 18:41:18 INFO - 'SHELL': '/bin/bash', 18:41:18 INFO - 'SHLVL': '1', 18:41:18 INFO - 'TERM': 'linux', 18:41:18 INFO - 'TMOUT': '86400', 18:41:18 INFO - 'USER': 'cltbld', 18:41:18 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409', 18:41:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:18 INFO - '_': '/tools/buildbot/bin/python'} 18:41:18 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/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 18:41:18 INFO - mozversion application_buildid: 20160108163004 18:41:18 INFO - mozversion application_changeset: 0f363ae95dc90d593394ef464aa500804c824962 18:41:18 INFO - mozversion application_display_name: Nightly 18:41:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:41:18 INFO - mozversion application_name: Firefox 18:41:18 INFO - mozversion application_remotingname: firefox 18:41:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:41:18 INFO - mozversion application_vendor: Mozilla 18:41:18 INFO - mozversion application_version: 46.0a1 18:41:18 INFO - mozversion platform_buildid: 20160108163004 18:41:18 INFO - mozversion platform_changeset: 0f363ae95dc90d593394ef464aa500804c824962 18:41:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:41:18 INFO - mozversion platform_version: 46.0a1 18:41:18 INFO - using testdate: 1452307278 18:41:18 INFO - actual date: 1452307278 18:41:18 INFO - starting webserver on 'localhost:41108' 18:41:18 INFO - SUITE-START | Running 3 tests 18:41:18 INFO - TEST-START | dromaeo_css 18:41:18 INFO - operating with platform_type : linux_ 18:41:18 INFO - Initialising browser for dromaeo_css test... 18:41:18 INFO - TEST-INFO | started process 4335 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGZ0X_n/profile http://localhost:41108/getInfo.html) 18:41:22 INFO - PROCESS | 4335 | __metrics Screen width/height:1600/1200 18:41:22 INFO - PROCESS | 4335 | colorDepth:24 18:41:22 INFO - PROCESS | 4335 | Browser inner width/height: 1024/697 18:41:22 INFO - PROCESS | 4335 | __metrics 18:41:22 INFO - PROCESS | 4335 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 18:41:22 INFO - PROCESS | 4335 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:41:23 INFO - TEST-INFO | 4335: exit 0 18:41:23 INFO - Browser initialized. 18:41:23 INFO - Running cycle 1/1 for dromaeo_css test... 18:41:23 INFO - TEST-INFO | started process 4428 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGZ0X_n/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:41:35 INFO - PROCESS | 4428 | RSS: Main: 148889600 18:41:35 INFO - PROCESS | 4428 | 18:42:26 INFO - PROCESS | 4428 | 1452307346884 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] 18:44:35 INFO - PROCESS | 4428 | Cycle 1(1): loaded http://localhost:41108/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41108/tests/dromaeo/cssquery-ext.html) 18:44:36 INFO - PROCESS | 4428 | RSS: Main: 270053376 18:44:36 INFO - PROCESS | 4428 | 18:47:38 INFO - PROCESS | 4428 | Cycle 1(1): loaded http://localhost:41108/tests/dromaeo/cssquery-ext.html (next: http://localhost:41108/tests/dromaeo/cssquery-jquery.html) 18:47:39 INFO - PROCESS | 4428 | RSS: Main: 239210496 18:47:39 INFO - PROCESS | 4428 | 18:50:41 INFO - PROCESS | 4428 | Cycle 1(1): loaded http://localhost:41108/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41108/tests/dromaeo/cssquery-mootools.html) 18:50:42 INFO - PROCESS | 4428 | RSS: Main: 239005696 18:50:42 INFO - PROCESS | 4428 | 18:53:43 INFO - PROCESS | 4428 | Cycle 1(1): loaded http://localhost:41108/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41108/tests/dromaeo/cssquery-prototype.html) 18:53:44 INFO - PROCESS | 4428 | RSS: Main: 207470592 18:53:44 INFO - PROCESS | 4428 | 18:56:46 INFO - PROCESS | 4428 | Cycle 1(1): loaded http://localhost:41108/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41108/tests/dromaeo/cssquery-yui.html) 18:56:47 INFO - PROCESS | 4428 | RSS: Main: 217280512 18:56:47 INFO - PROCESS | 4428 | 18:59:50 INFO - PROCESS | 4428 | Cycle 1(1): loaded http://localhost:41108/tests/dromaeo/cssquery-yui.html (next: http://localhost:41108/tests/dromaeo/cssquery-dojo.html) 18:59:50 INFO - PROCESS | 4428 | RSS: Main: 237846528 18:59:50 INFO - PROCESS | 4428 | 18:59:50 INFO - PROCESS | 4428 | __start_tp_report 18:59:50 INFO - PROCESS | 4428 | _x_x_mozilla_page_load 18:59:50 INFO - PROCESS | 4428 | _x_x_mozilla_page_load_details 18:59:50 INFO - PROCESS | 4428 | |i|pagename|runs| 18:59:50 INFO - PROCESS | 4428 | |0;dojo.html;3036.802607830491;3063.5098384258495;3095.3809238152376;3118.36073604911;3153.069844395903;4995.075910956476;5096.821611243608;5140.331756871607;5148.304978827857;5182.896342073156;5142.460041695625;5187.325647665798;5223.2165175223645;5244.79020839167;5245.554127899133;4647.302904564325;4655.34825124482;4728.527147285278;4797.6401769867225;4797.90404191916;4636.327732478789;4753.714777113367;4769.427668679763;4811.639127065484;4812.133815913135;4699.107169637775;4717.858464246078;4805.471398146201;4807.086653535817;4822.252550854623;4729.0541891621815;4729.456112547066;4730.976345118287;4734;4766.73782941938;4706.670533062687;4707.388039554836;4733;4735.692180008323;4739.9526004739855;4975.179095449246;5022.4977502249485;5057.848264552069;5109.335786347751;5149.227615857614;5586.525145362649;5587.608867379285;5593.328800543961;5597.3003374578175;5610.719464026783;5534.225208470817;5625.746841392147;5633.211350410905;5638.097904335327;5641.605087643826;5165.431802501722;5171.818986335498;5211.609129315354;5227.895442091137;5268.894622107537;5680.8863822723315;5686.061799803069;5688.77244910199;5692.7722891083895;5737.913931291034;5378.838634840961;5468.4531546845;5478;5481.890362192734;5491.368492623399;5480.095784195642;5492;5534.169874518774;5548.0845660466375;5598.076317407582;5521;5521.420250873601;5555.138953462219;5555.694436806015;5557.3886872444;5427.402985671573;5520.641158324696;5531.197976293495;5537.529310008695;5617.831465056055;4312.698111132253;4323.48118225815;4364.08354245612;4367.737935723867;4408.603225709701;3856.8842934712;3858.575556688762;3865.594112618191;3873;3891.805409729502;4999.600031997432;5031.6729412588065;5057.266696329012;5104.69371837691;5106.744662766847;5446.91829622556;5550.361708403585;5623.831285061455;5652.830415087554;5658.094704847204;5066.290719299227;5071.644984851025;5081.415637201695;5083.440821509631;5123.948760512421;4620.122176786467;4677.625789936865;4693.389859318335;4694.155052090657;4695.107929493453;5675.432456754292;5687.488126068673;5727.198192253088;5730.398308177665;5765.538756899438;5439.972800135974;5443.918341224806;5444.210589464585;5448.373437054789;5458.61789674719;5547.362053363915;5584.050711379156;5587.660953778049;5600.579956503197;5601.495865372136;5467.097928841697;5506.669599824023;5533.889322213453;5567.972160139173;5576.553875689854;5510.448955104618;5551.528120109836;5556.138798486241;5565.471280228372;5568.276124103841;5293.179557168645;5390.05674007055;5431.076716958048;5440.809571665131;5487.122060470306;5255.973720131375;5308.840734777963;5331.493508116722;5363.436839131991;5418.674879507243;5362.222477740784;5365.704886231295;5369.1140961740875;5377.516023557899;5380.488853558905;5274.077036518517;5425.620206585501;5452.536534394462;5479;5540.141278101901;5283.815066472655;5344.518993290622;5349.384820745664;5352.063388907003;5400.108982017923 18:59:50 INFO - PROCESS | 4428 | |1;ext.html;545.1196317946518;550.961432699711;558.3606770248066;563.5153767759726;585.7305639405873;28076.438471230587;28580.42839143216;28640.427191456158;28701.425971480585;36164.2767144657;42811.35782963258;43489;43562.56437435625;43589.7820510897;43639.78180109103;8583.313334933222;8874.42316249445;8876.778080547974;8885.866711999293;8887.777805554848;7445.702171913117;7638.159802421757;8484.745457636282;9954.104130628242;10237.641682541112;9933.205343572497;10040.548175332127;10040.89959100407;10042.799144017115;10049.447280399576;7531.887021694679;9949.95025024874;10030.548625311878;10063.094321511098;10075.143612792921;7474.701011959515;9972.70081897537;10321.483925803755;10323.48382580868;10325.84511232332;35949.64050359489;36514.452283215775;36536.634633653855;36637.08407289813;36651;3353.7149342305934;3429.331280400323;3607.080194550399;3620.9591509612405;3694.612065733086;29301.413971720445;30295.394092118255;30981.225469363446;31092.689073109206;31160.844195779104;3902.2195560887653;3974.2647610192207;4027.0939038716347;4040.8181631826346;4165.958340416587;276187;276609;277620;278626;279063.99999999796;51864.99822523986;52646.47353526492;58643.4135658638;58827.70586147042;59991;54867.725661371434;54962.45037549653;55086;55154;55330.446695533334;15496.612584685248;15665.373385064699;15695.136767477898;15705.764413533807;15730;11687.474063667156;11723.237989530653;11776.52893884252;11794.705132371759;11829.822552661717;18890.3388381406;18913.527161821065;19370.322038728576;19377.51556211106;19477.610447790965;7353.522021068614;7498.925010749932;7614.162442131361;7687.692492300245;7724.420668449889;939.6711151097061;947.9383840050375;1052.552665117322;1060.2313322840878;1063.31416236527;28008.719912801018;29209;29403.705962940097;30011.549826752616;31238.375232495222;1007.9798404031878;1040.401768982838;1073.6617965340893;1110.4003837927428;1166.7958107331183;13537.39081741324;13778.173309601256;15188.240587970557;15611.3755449783;15755.763663545056;243067;245337;245850;246963;247023;48049.519504805205;48184;48205;48206.035879282215;48230;1349.925754083535;1358.130796290375;1377.2700468751577;1385.06225306781;1391.839938407096;22298.442538936655;28068.71931280661;28962.565561516174;29616.259593509472;30254.54618180685;47229;47339.289910652056;47552.52447475481;47669;47776.283355748994;246278;248589;248868;249059;249630;27711.722882770915;29341;30501.23746906345;32156.678433216304;32202;37579;38159.618403815606;38451.230975380895;38647.22705545817;38667.80666096652;31681.841590791897;32021.519677205328;32179.195520112185;32839.34321313608;32935.34129317448;28296.85851570729;33089.172770679965;33286;33343.33313333671;33541.32917341688;32186.35627287488;32712.34575308433;32894.50658240176;32935.67064329326;33090.33819323648;27826.304342391602;28753;29998;32206.67793322037;32910.34179316448;24084.27747167588;24320.391990200387;24445;24612.753872461046;24641.876790615934 18:59:50 INFO - PROCESS | 4428 | |2;jquery.html;353.5253227408143;355.3692196351476;357.33000623830316;358.9461580762885;362.3984186250824;13326.666833329171;13685.704575895075;16122.274497647604;16601.83398166018;17818.732719009226;14480.42078316866;15469.767953480707;15825.287862046207;15831.366745330173;16447.67104657906;3626.202235508197;3760.9435858462143;3765.7175711821724;3767;3767.171222331083;4006.699497537682;4184.686148538856;5436.429174936634;5509.944900551003;5751.741171647286;5712.571557133211;5892.204552385416;5922.230110085683;5931.88136237275;5936.376680448556;5930.229070220866;5997.4002599740115;5998.070299103668;6000.699965001732;6000.8499787505225;5179.249008893727;5193.506616871391;5252.080885844962;5285.207218917193;5352.57179425645;19549.120289587;19709.211631534723;19908.502287442785;19925.302614408425;19967.900160499248;2194.7695491973273;2293.1056887813766;2305.0779688124717;2308.4228942764307;2316.4787922717255;8627.568621568897;9065.320100992452;12634.936825315815;12927.87072129276;13181.275029873354;1763.7883453985603;1826.6711991841464;1855.6381505606412;1856.4801855480487;1859.665260253154;54432.45567544353;55180.44819551834;55424.69238873321;55630.99999999959;56447.99999999959;9442.48066356357;9446;9469.242460603135;9476.620935162517;9488.478133702574;11932.582359617372;11977.760444791056;12001.699957501132;12002.519899203933;12002.759944801055;7129.429645628337;7193.640317984185;7222.602756848424;7285.562866228043;7376.188619251878;7139.32176443237;7224.385927196142;7471;7559.28186822251;7563.432742544332;3961.148353104039;4006.8397264109694;4039.9596004040172;4071.816768245436;4248.851290204828;1588.4361051826666;1623.7320842060928;1643.9342426303053;1676.807167175766;1692.4753326468588;880.4277219807177;951.7715748220377;976.998576459135;978.716406456343;992.6972273456521;15572.143532105616;15712.842871571367;15807.13060781645;16098.436554720529;16147.757783633255;901.1169054326797;909.5770466733001;927.3971918253054;973.3867663372031;980.6812785844629;8844.292456603456;8978.281737460988;9176.082391760903;9308.488033158106;9310.860337094813;500572;509470;517384;519438;530243;42354.57645423568;50708.23937640939;68013.65993170002;70900;71335.64332178306;1151.7581307925261;1165.4289398194992;1167.649705088487;1168.7195073182374;1178.293024185482;14152.929235353757;14170.149791012573;14199.43202271939;14555.563333100024;14728.55814325572;28397.432051358443;29187;29387.265318367212;30188.396232075676;31328;38795;39204.607953920095;39279.21441571095;39969.60030399659;40598.59401406066;6913.619750913748;7102.5028248022145;7234.63826808668;7312.81717957034;7328.1938986712485;7978.441509094308;10116.393016419039;11020.393878336768;11280.605178818701;11625.825612615987;8742.56287185651;8845.51349675774;8918.10818918103;9116.498592577207;9146.451212927246;9371.625134994523;9939.30424870281;10171.389716617026;10229.386236825814;10522.368657880552;6925.861482770417;8935.955320223356;9266.536673166449;9316.767080823034;9774.462404567817;7251.456140789356;7251.746188883364;7252.709891604277;7255.4921155520715;7421.998030265845;8662.653493860174;9118.042605526323;9185.173334399937;9188.540572971191;9300.488473133772 18:59:50 INFO - PROCESS | 4428 | |3;mootools.html;1320.2474589483998;1453.6002599285196;1458.9562313130607;1598.768178614101;1767.5669460982053;6774.356436138565;7258.237885022076;7418.888716669254;7436.776896693101;7441.069866266716;7024.157101147856;7093.326134017273;7153.713851445936;7168.713251469935;7187.389071928892;2202.658587918871;2204.614192516311;2210.1159536185573;2224.810891074261;2228.2646726580188;2138.219549864296;2167.3606286145623;2170.696102545637;2175.0212404418;2180.6728990651372;2166.480044789255;2189.540196558725;2192.8136108430804;2193.2323686709606;2196.9450763730956;2196.615592271354;2200.9449763755874;2205.073868975034;2205.4817117977263;2206.051397898903;2171.706819579352;2204.371754050088;2217.1020736601704;2219.1123550579737;2220.189630784752;7223;7253.202147763742;7254.746083887038;7261.2012678605315;7266.200717921024;4445.844395446144;4536.88657783558;4541.137183935075;4573.062522182944;4575.15359658461;2886.956695649567;2889.2343528964843;2945.661248956376;2962.3482833776534;2962.9407411851644;920.1028996728203;931.1619542411815;941.6610020392653;962.6341990043738;978.515634760798;9846.458444785534;10023.198144148451;10025;10033.949830250802;10050.748731281703;3932.4297976793205;3988.980055099706;4016.2971479990883;4031.113155105872;4042.7776472293717;8392.370572206986;8405.873911891327;8409.663613455516;8410.453320534145;8423.578821059045;4384.693071484965;4410.404595379583;4417.845375411845;4422.358757980075;4443.044745379759;3694.2057457646733;3790.298794722985;4070.2673518766314;4082.4488694026522;4085.20338533983;3470.8958731238104;3494.7553671243277;3516.4373700208357;3528.4354503279865;3546.060294022086;1049.58541376156;1049.9790004199874;1053.262716098734;1057.5663977769077;1062.2298833345762;1317.5454468208452;1320.577415227118;1332.1540821578367;1348.298884580013;1364.433759989613;6878.793636190922;6955.061066755923;6985.93014069863;6987.8602427951155;7178.892316615255;1113.5434471866495;1118.7538741476862;1132.213111887247;1133.7675776465805;1157.7163594919298;5521.613487055867;5580.469855363734;5588.30146231721;5588.692621905753;5596.97201513998;8778.166074222938;8861.069587693328;9117.95441022804;9209.677661281823;9228.26173906086;8986.730398088068;9076.183143517115;9098.954505227432;9106.453612783254;9124.270058395312;6925.168979722465;7003.509754317251;7125.216226215216;7133.215346311901;7148.356647901714;6804.795856124324;6853.211880633791;6854.5201835873;6890.896636550356;6897.275786042594;8004.079530854026;8075.434719569574;8085.151059138706;8204.753857384288;8228.382871284557;8168.918310816815;8416.3266938646;8579.356548259031;8582.485050896716;8583.442076265022;6552.63960482178;6598.241202261609;6707.496937729592;6766.15423072116;6768.492363072691;7207.783766486804;7234.819129521804;7235.963820180866;7236.529625574321;7249.927500724925;6686.53194276396;6788.422984046411;6807.251202367835;6901.378875901193;6917.861642767016;6701.530892837454;6721.529492935643;6758.93241067583;6809.455243580601;6824.556403833735;6532.640704761284;6655.500837437308;6691.76578819739;6694.497912656668;6704.363085506869;6300.117983482224;6312.7159277833525;6327.2407311121115;6338.936610633835;6348.111264422892;6310.810675679737;6314.58955167931;6335.841603959938;6335.873282534416;6340.619562826245 18:59:50 INFO - PROCESS | 4428 | |4;prototype.html;322.8643969532796;333.00929734041233;339.69088129801884;342.53757427472914;348.22346168045243;5666.150077488369;5809.2738407699035;5860.50185734212;5881.088431293151;5903.26209223847;5655.519280861132;5799.246098007253;5806.216160818289;5816.243888294526;5825.650460972344;4535.501094879561;4554.795034223468;4556.3621093047;4558.133954548642;4559.498455169929;4338.132373525292;4437.689361744679;4447.710898791584;4450.755208463534;4564.338170965208;4333.804978775946;4416.050549131936;4451.2655411857;4456.064226512436;4492.932606010912;4448;4450.043240703264;4466.240739074349;4469.843555475542;4485.865424037284;3929.8231579578987;3931.7247792654525;3936.1340505088833;3936.2914675358425;3937.7243592948507;5361.329833770778;5486.478784515504;5527.751251193706;5556.555475561945;5565.415631358689;5097.745112744348;5104.61715371345;5106.463821298785;5107.463716309771;5110.514501122388;5886.234789477383;6123.142760013601;6124.571280010401;6125.173101631267;6133.509319254449;4132.318167502359;4195.853145139906;4287.48550173978;4288.16380805743;4293.0340673348555;6371.808845734636;6407.135036770023;6420.711068001951;6423.807285781388;6443.355664433566;4363.258246098171;4364.8254069837485;4367.759773212504;4370.978145109318;4377.277749171351;4349.108432771274;4413.536578659258;4420.58004489573;4428.047969686533;4464.352668862996;5138.434772175057;5180.300659411007;5187.792488300426;5190.870228244248;5193.039287731782;6287.842803929847;6294.370562943761;6296.968515157395;6298.307186209513;6308.716107775161;1563.4137198550543;1596.22583047222;1597.2413103775846;1597.9280932358072;1597.9280932358072;1066.903978641926;1067.114295135036;1081.2269227502375;1083.285031878956;1098.7912296663608;5842.737076831468;5846.619969701956;5848.853778655568;5855.2973643163095;5857.4435428634215;6393.1688880445245;6396.168498095218;6403.167588213502;6403.822700281833;6412.422881940554;5850.063989761703;5851.824445266649;5885.676287804126;5901.940980590225;5914.172015917775;3379.729621630264;3430.9828450857585;3481.164520515109;3486.7210623150086;3489.3195826813526;6588.439982601532;6598.241202261705;6601.570897891622;6601.70292336846;6610.537262391586;6541.771038013647;6600.86798264032;6608.372204640552;6616.007598860209;6623.138991931018;6478.481721462272;6599.406053455212;6615.86768264632;6618.338166183439;6623.867522649616;6213.409726076015;6331.113644089924;6425.650613371245;6427;6430.48556115519;6279.591826531444;6289.056641503811;6408.29508754046;6411.807645770635;6432.00303952888;6593.406593406617;6609.933900660932;6619.801405957829;6627.072209890523;6627.635480048643;4385.035292235703;4430.712003719748;4431.892026993251;4434.667399945081;4469.351943968041;6470.773522926675;6475.514336424692;6476.417122459001;6476.5466417350335;6485.805425837232;6300.558960872878;6303.810885673437;6304.3380445054445;6309.179806625109;6312.179416675986;6169.629822210682;6279.246490421178;6292.968535157295;6299.055141728777;6305.085762564311;6174.845628859135;6184.381561843779;6185.474234690102;6188.319284878749;6199;6314.842128946813;6322.525810564134;6323.968380158254;6325.051242313506;6329.841753956004;6039.939600604113;6140.4473597376455;6144.907826382697;6149.108379285068;6158.753649853956 18:59:50 INFO - PROCESS | 4428 | |5;yui.html;749.1684230504139;811.5495899775624;843.9662413503456;852.8422241885252;861.685484798049;12296;12632.11575189737;12951.935240323784;12959.352032398378;12990.48038078477;12343.13598048137;12873.163244389109;12900.93549532251;12924.612261632143;12950.35248237589;672.3209558346057;681.7920534237056;806.1656185847648;807.3016840433013;823.0247157118833;810.8135128920352;813.9389545784061;816.6692489541745;819.5246756880995;819.5779173725506;792.9801754956114;800.3397197312212;800.8198155415014;802.574635443216;804.264098350009;708.3943228539576;718.3534818663193;826.1490664615426;850.2900078434515;857.9485230886166;625.6621424430801;630.2122347066168;630.6090224061065;631.1384959530254;632.4908448698795;12289.754204915851;12398.504059837596;12420.627381178489;12467.314297713621;12576.81134782979;12615.180013299107;12636.30500322482;13020.153690010118;13066.216027038408;13246.345775542239;10563.366198028067;10607.893921060844;10641.946790266076;10674.199435042403;10727.83908241377;3633.218857945554;3675.5589329280656;3707.3141468828085;3827.3684842001035;3830.9616903830884;44279.55720442787;45062.54937450616;45495.54504454913;45712.77143614294;45767.771161144316;9105.81788364229;9143.679971200976;9340.019297973751;9397.953010234905;9489.383190092622;16723.83276167247;16736.581585460463;16744.58138546522;16771.496855094367;16789.24448399825;7230.566166030055;7287.817804554823;7559.659815308324;7654.961725191339;7655.425843061793;4025.0742329264435;4054.71616986814;4172.937405938914;4177.874663760092;4189.308764053958;2926.4878646236825;2952.143878275319;2956.654722101439;2960.4967155583604;2964.9110526683803;542.8371488553419;554.9142112538619;559.9384067752544;566.59205372132;571.2573654249542;589.949854262384;632.3234139470723;668.9295636054566;672.579637726421;736.7568702328326;8234.300084492765;8310.54292013945;8401.873971890427;8581;8613.310935125175;562.1085048810722;597.5667640960269;622.3714048810643;639.7377075399064;674.1909708349999;3889.9805500972316;4007.338789099824;4064.7154699171365;4079.224947260011;4137.172565486914;51211.23183152256;52408.21387679188;52436;53801.730991345576;54709.45290547043;46802;47145.5285447148;47151;47393.28910066352;47542.28686569704;39021;40193.59806401957;40221.79889100594;42511.149777004284;43453.13093738107;7857.174996625501;8133.75598732039;8143.75568732939;8214.794630134056;8370.707025254087;33267.334653306316;33750;34017;34077.82961085179;34455.65544344525;40121.39817902792;41808.1638367237;41986;42466.938326542084;43144;10957.178211634;11008.944955275492;11078.667639970814;11232.66302010941;11301.15241356885;11385.259958102697;11716.297022178696;12180.208286461706;12637.052221083639;12936.288504132363;10902.509387077442;11432.085433165494;11463.254888432226;11529.308241505536;11714.062874970152;11009;11124.833127502932;11301.37842418642;11833.11251656112;11945.4027298633;11337.319760814378;11486.310821350746;11603.825942610698;11660.708482287671;11673.416329183678;8785.12148785142;8954.731358059269;9052.502112383881;9080.727578172666;9230.584623692084;8647.697330593399;8839.204471597586;9681.128698417177;9780.559874805793;9919.20646348276 18:59:50 INFO - PROCESS | 4428 | __end_tp_report 18:59:50 INFO - PROCESS | 4428 | __start_cc_report 18:59:50 INFO - PROCESS | 4428 | _x_x_mozilla_cycle_collect,993 18:59:50 INFO - PROCESS | 4428 | __end_cc_report 18:59:50 INFO - PROCESS | 4428 | __startTimestamp1452308390657__endTimestamp 18:59:50 INFO - PROCESS | 4428 | 18:59:50 INFO - PROCESS | 4428 | ------- Summary: start ------- 18:59:50 INFO - PROCESS | 4428 | Number of tests: 6 18:59:50 INFO - PROCESS | 4428 | 18:59:50 INFO - PROCESS | 4428 | [#0] dojo.html Cycles:165 Average:5143.18 Median:5349.38 stddev:559.30 (10.5%) stddev-sans-first:536.05 18:59:50 INFO - PROCESS | 4428 | Values: 3036.8 3063.5 3095.4 3118.4 3153.1 4995.1 5096.8 5140.3 5148.3 5182.9 5142.5 5187.3 5223.2 5244.8 5245.6 4647.3 4655.3 4728.5 4797.6 4797.9 4636.3 4753.7 4769.4 4811.6 4812.1 4699.1 4717.9 4805.5 4807.1 4822.3 4729.1 4729.5 4731.0 4734.0 4766.7 4706.7 4707.4 4733.0 4735.7 4740.0 4975.2 5022.5 5057.8 5109.3 5149.2 5586.5 5587.6 5593.3 5597.3 5610.7 5534.2 5625.7 5633.2 5638.1 5641.6 5165.4 5171.8 5211.6 5227.9 5268.9 5680.9 5686.1 5688.8 5692.8 5737.9 5378.8 5468.5 5478.0 5481.9 5491.4 5480.1 5492.0 5534.2 5548.1 5598.1 5521.0 5521.4 5555.1 5555.7 5557.4 5427.4 5520.6 5531.2 5537.5 5617.8 4312.7 4323.5 4364.1 4367.7 4408.6 3856.9 3858.6 3865.6 3873.0 3891.8 4999.6 5031.7 5057.3 5104.7 5106.7 5446.9 5550.4 5623.8 5652.8 5658.1 5066.3 5071.6 5081.4 5083.4 5123.9 4620.1 4677.6 4693.4 4694.2 4695.1 5675.4 5687.5 5727.2 5730.4 5765.5 5440.0 5443.9 5444.2 5448.4 5458.6 5547.4 5584.1 5587.7 5600.6 5601.5 5467.1 5506.7 5533.9 5568.0 5576.6 5510.4 5551.5 5556.1 5565.5 5568.3 5293.2 5390.1 5431.1 5440.8 5487.1 5256.0 5308.8 5331.5 5363.4 5418.7 5362.2 5365.7 5369.1 5377.5 5380.5 5274.1 5425.6 5452.5 5479.0 5540.1 5283.8 5344.5 5349.4 5352.1 5400.1 18:59:50 INFO - PROCESS | 4428 | 18:59:50 INFO - PROCESS | 4428 | [#1] ext.html Cycles:180 Average:42361.97 Median:31851.68 stddev:66993.03 (210.3%) stddev-sans-first:67107.38 18:59:50 INFO - PROCESS | 4428 | Values: 545.1 551.0 558.4 563.5 585.7 28076.4 28580.4 28640.4 28701.4 36164.3 42811.4 43489.0 43562.6 43589.8 43639.8 8583.3 8874.4 8876.8 8885.9 8887.8 7445.7 7638.2 8484.7 9954.1 10237.6 9933.2 10040.5 10040.9 10042.8 10049.4 7531.9 9950.0 10030.5 10063.1 10075.1 7474.7 9972.7 10321.5 10323.5 10325.8 35949.6 36514.5 36536.6 36637.1 36651.0 3353.7 3429.3 3607.1 3621.0 3694.6 29301.4 30295.4 30981.2 31092.7 31160.8 3902.2 3974.3 4027.1 4040.8 4166.0 276187.0 276609.0 277620.0 278626.0 279064.0 51865.0 52646.5 58643.4 58827.7 59991.0 54867.7 54962.5 55086.0 55154.0 55330.4 15496.6 15665.4 15695.1 15705.8 15730.0 11687.5 11723.2 11776.5 11794.7 11829.8 18890.3 18913.5 19370.3 19377.5 19477.6 7353.5 7498.9 7614.2 7687.7 7724.4 939.7 947.9 1052.6 1060.2 1063.3 28008.7 29209.0 29403.7 30011.5 31238.4 1008.0 1040.4 1073.7 1110.4 1166.8 13537.4 13778.2 15188.2 15611.4 15755.8 243067.0 245337.0 245850.0 246963.0 247023.0 48049.5 48184.0 48205.0 48206.0 48230.0 1349.9 1358.1 1377.3 1385.1 1391.8 22298.4 28068.7 28962.6 29616.3 30254.5 47229.0 47339.3 47552.5 47669.0 47776.3 246278.0 248589.0 248868.0 249059.0 249630.0 27711.7 29341.0 30501.2 32156.7 32202.0 37579.0 38159.6 38451.2 38647.2 38667.8 31681.8 32021.5 32179.2 32839.3 32935.3 28296.9 33089.2 33286.0 33343.3 33541.3 32186.4 32712.3 32894.5 32935.7 33090.3 27826.3 28753.0 29998.0 32206.7 32910.3 24084.3 24320.4 24445.0 24612.8 24641.9 18:59:50 INFO - PROCESS | 4428 | 18:59:50 INFO - PROCESS | 4428 | [#2] jquery.html Cycles:180 Average:26091.99 Median:513427.00 stddev:84100.90 (16.4%) stddev-sans-first:84314.61 18:59:50 INFO - PROCESS | 4428 | Values: 353.5 355.4 357.3 358.9 362.4 13326.7 13685.7 16122.3 16601.8 17818.7 14480.4 15469.8 15825.3 15831.4 16447.7 3626.2 3760.9 3765.7 3767.0 3767.2 4006.7 4184.7 5436.4 5509.9 5751.7 5712.6 5892.2 5922.2 5931.9 5936.4 5930.2 5997.4 5998.1 6000.7 6000.8 5179.2 5193.5 5252.1 5285.2 5352.6 19549.1 19709.2 19908.5 19925.3 19967.9 2194.8 2293.1 2305.1 2308.4 2316.5 8627.6 9065.3 12634.9 12927.9 13181.3 1763.8 1826.7 1855.6 1856.5 1859.7 54432.5 55180.4 55424.7 55631.0 56448.0 9442.5 9446.0 9469.2 9476.6 9488.5 11932.6 11977.8 12001.7 12002.5 12002.8 7129.4 7193.6 7222.6 7285.6 7376.2 7139.3 7224.4 7471.0 7559.3 7563.4 3961.1 4006.8 4040.0 4071.8 4248.9 1588.4 1623.7 1643.9 1676.8 1692.5 880.4 951.8 977.0 978.7 992.7 15572.1 15712.8 15807.1 16098.4 16147.8 901.1 909.6 927.4 973.4 980.7 8844.3 8978.3 9176.1 9308.5 9310.9 500572.0 509470.0 517384.0 519438.0 530243.0 42354.6 50708.2 68013.7 70900.0 71335.6 1151.8 1165.4 1167.6 1168.7 1178.3 14152.9 14170.1 14199.4 14555.6 14728.6 28397.4 29187.0 29387.3 30188.4 31328.0 38795.0 39204.6 39279.2 39969.6 40598.6 6913.6 7102.5 7234.6 7312.8 7328.2 7978.4 10116.4 11020.4 11280.6 11625.8 8742.6 8845.5 8918.1 9116.5 9146.5 9371.6 9939.3 10171.4 10229.4 10522.4 6925.9 8936.0 9266.5 9316.8 9774.5 7251.5 7251.7 7252.7 7255.5 7422.0 8662.7 9118.0 9185.2 9188.5 9300.5 18:59:50 INFO - PROCESS | 4428 | 18:59:50 INFO - PROCESS | 4428 | [#3] mootools.html Cycles:180 Average:5232.17 Median:6337.40 stddev:2683.64 (42.3%) stddev-sans-first:2675.05 18:59:50 INFO - PROCESS | 4428 | Values: 1320.2 1453.6 1459.0 1598.8 1767.6 6774.4 7258.2 7418.9 7436.8 7441.1 7024.2 7093.3 7153.7 7168.7 7187.4 2202.7 2204.6 2210.1 2224.8 2228.3 2138.2 2167.4 2170.7 2175.0 2180.7 2166.5 2189.5 2192.8 2193.2 2196.9 2196.6 2200.9 2205.1 2205.5 2206.1 2171.7 2204.4 2217.1 2219.1 2220.2 7223.0 7253.2 7254.7 7261.2 7266.2 4445.8 4536.9 4541.1 4573.1 4575.2 2887.0 2889.2 2945.7 2962.3 2962.9 920.1 931.2 941.7 962.6 978.5 9846.5 10023.2 10025.0 10033.9 10050.7 3932.4 3989.0 4016.3 4031.1 4042.8 8392.4 8405.9 8409.7 8410.5 8423.6 4384.7 4410.4 4417.8 4422.4 4443.0 3694.2 3790.3 4070.3 4082.4 4085.2 3470.9 3494.8 3516.4 3528.4 3546.1 1049.6 1050.0 1053.3 1057.6 1062.2 1317.5 1320.6 1332.2 1348.3 1364.4 6878.8 6955.1 6985.9 6987.9 7178.9 1113.5 1118.8 1132.2 1133.8 1157.7 5521.6 5580.5 5588.3 5588.7 5597.0 8778.2 8861.1 9118.0 9209.7 9228.3 8986.7 9076.2 9099.0 9106.5 9124.3 6925.2 7003.5 7125.2 7133.2 7148.4 6804.8 6853.2 6854.5 6890.9 6897.3 8004.1 8075.4 8085.2 8204.8 8228.4 8168.9 8416.3 8579.4 8582.5 8583.4 6552.6 6598.2 6707.5 6766.2 6768.5 7207.8 7234.8 7236.0 7236.5 7249.9 6686.5 6788.4 6807.3 6901.4 6917.9 6701.5 6721.5 6758.9 6809.5 6824.6 6532.6 6655.5 6691.8 6694.5 6704.4 6300.1 6312.7 6327.2 6338.9 6348.1 6310.8 6314.6 6335.8 6335.9 6340.6 18:59:50 INFO - PROCESS | 4428 | 18:59:50 INFO - PROCESS | 4428 | [#4] prototype.html Cycles:180 Average:5190.89 Median:5850.94 stddev:1563.90 (26.7%) stddev-sans-first:1525.01 18:59:50 INFO - PROCESS | 4428 | Values: 322.9 333.0 339.7 342.5 348.2 5666.2 5809.3 5860.5 5881.1 5903.3 5655.5 5799.2 5806.2 5816.2 5825.7 4535.5 4554.8 4556.4 4558.1 4559.5 4338.1 4437.7 4447.7 4450.8 4564.3 4333.8 4416.1 4451.3 4456.1 4492.9 4448.0 4450.0 4466.2 4469.8 4485.9 3929.8 3931.7 3936.1 3936.3 3937.7 5361.3 5486.5 5527.8 5556.6 5565.4 5097.7 5104.6 5106.5 5107.5 5110.5 5886.2 6123.1 6124.6 6125.2 6133.5 4132.3 4195.9 4287.5 4288.2 4293.0 6371.8 6407.1 6420.7 6423.8 6443.4 4363.3 4364.8 4367.8 4371.0 4377.3 4349.1 4413.5 4420.6 4428.0 4464.4 5138.4 5180.3 5187.8 5190.9 5193.0 6287.8 6294.4 6297.0 6298.3 6308.7 1563.4 1596.2 1597.2 1597.9 1597.9 1066.9 1067.1 1081.2 1083.3 1098.8 5842.7 5846.6 5848.9 5855.3 5857.4 6393.2 6396.2 6403.2 6403.8 6412.4 5850.1 5851.8 5885.7 5901.9 5914.2 3379.7 3431.0 3481.2 3486.7 3489.3 6588.4 6598.2 6601.6 6601.7 6610.5 6541.8 6600.9 6608.4 6616.0 6623.1 6478.5 6599.4 6615.9 6618.3 6623.9 6213.4 6331.1 6425.7 6427.0 6430.5 6279.6 6289.1 6408.3 6411.8 6432.0 6593.4 6609.9 6619.8 6627.1 6627.6 4385.0 4430.7 4431.9 4434.7 4469.4 6470.8 6475.5 6476.4 6476.5 6485.8 6300.6 6303.8 6304.3 6309.2 6312.2 6169.6 6279.2 6293.0 6299.1 6305.1 6174.8 6184.4 6185.5 6188.3 6199.0 6314.8 6322.5 6324.0 6325.1 6329.8 6039.9 6140.4 6144.9 6149.1 6158.8 18:59:50 INFO - PROCESS | 4428 | 18:59:50 INFO - PROCESS | 4428 | [#5] yui.html Cycles:180 Average:13092.39 Median:44671.05 stddev:14689.42 (32.9%) stddev-sans-first:14701.38 18:59:50 INFO - PROCESS | 4428 | Values: 749.2 811.5 844.0 852.8 861.7 12296.0 12632.1 12951.9 12959.4 12990.5 12343.1 12873.2 12900.9 12924.6 12950.4 672.3 681.8 806.2 807.3 823.0 810.8 813.9 816.7 819.5 819.6 793.0 800.3 800.8 802.6 804.3 708.4 718.4 826.1 850.3 857.9 625.7 630.2 630.6 631.1 632.5 12289.8 12398.5 12420.6 12467.3 12576.8 12615.2 12636.3 13020.2 13066.2 13246.3 10563.4 10607.9 10641.9 10674.2 10727.8 3633.2 3675.6 3707.3 3827.4 3831.0 44279.6 45062.5 45495.5 45712.8 45767.8 9105.8 9143.7 9340.0 9398.0 9489.4 16723.8 16736.6 16744.6 16771.5 16789.2 7230.6 7287.8 7559.7 7655.0 7655.4 4025.1 4054.7 4172.9 4177.9 4189.3 2926.5 2952.1 2956.7 2960.5 2964.9 542.8 554.9 559.9 566.6 571.3 589.9 632.3 668.9 672.6 736.8 8234.3 8310.5 8401.9 8581.0 8613.3 562.1 597.6 622.4 639.7 674.2 3890.0 4007.3 4064.7 4079.2 4137.2 51211.2 52408.2 52436.0 53801.7 54709.5 46802.0 47145.5 47151.0 47393.3 47542.3 39021.0 40193.6 40221.8 42511.1 43453.1 7857.2 8133.8 8143.8 8214.8 8370.7 33267.3 33750.0 34017.0 34077.8 34455.7 40121.4 41808.2 41986.0 42466.9 43144.0 10957.2 11008.9 11078.7 11232.7 11301.2 11385.3 11716.3 12180.2 12637.1 12936.3 10902.5 11432.1 11463.3 11529.3 11714.1 11009.0 11124.8 11301.4 11833.1 11945.4 11337.3 11486.3 11603.8 11660.7 11673.4 8785.1 8954.7 9052.5 9080.7 9230.6 8647.7 8839.2 9681.1 9780.6 9919.2 18:59:50 INFO - PROCESS | 4428 | -------- Summary: end -------- 18:59:50 INFO - PROCESS | 4428 | 18:59:51 INFO - TEST-INFO | 4428: exit 0 18:59:51 INFO - TEST-OK | dromaeo_css | took 1112783ms 18:59:51 INFO - TEST-START | kraken 18:59:51 INFO - operating with platform_type : linux_ 18:59:51 INFO - Initialising browser for kraken test... 18:59:51 INFO - TEST-INFO | started process 4622 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpn1AlLA/profile http://localhost:41108/getInfo.html) 18:59:55 INFO - PROCESS | 4622 | __metrics Screen width/height:1600/1200 18:59:55 INFO - PROCESS | 4622 | colorDepth:24 18:59:55 INFO - PROCESS | 4622 | Browser inner width/height: 1024/697 18:59:55 INFO - PROCESS | 4622 | __metrics 18:59:55 INFO - PROCESS | 4622 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 18:59:55 INFO - PROCESS | 4622 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:59:56 INFO - TEST-INFO | 4622: exit 0 18:59:56 INFO - Browser initialized. 18:59:56 INFO - Running cycle 1/1 for kraken test... 18:59:56 INFO - TEST-INFO | started process 4705 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpn1AlLA/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:00:07 INFO - PROCESS | 4705 | RSS: Main: 148865024 19:00:07 INFO - PROCESS | 4705 | 19:00:10 INFO - PROCESS | 4705 | Cycle 1(1): loaded http://localhost:41108/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41108/tests/kraken/driver.html?testName=audio-beat-detection) 19:00:10 INFO - PROCESS | 4705 | RSS: Main: 185298944 19:00:10 INFO - PROCESS | 4705 | 19:00:13 INFO - PROCESS | 4705 | Cycle 1(1): loaded http://localhost:41108/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41108/tests/kraken/driver.html?testName=audio-dft) 19:00:14 INFO - PROCESS | 4705 | RSS: Main: 174915584 19:00:14 INFO - PROCESS | 4705 | 19:00:17 INFO - PROCESS | 4705 | Cycle 1(1): loaded http://localhost:41108/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41108/tests/kraken/driver.html?testName=audio-fft) 19:00:17 INFO - PROCESS | 4705 | RSS: Main: 161132544 19:00:17 INFO - PROCESS | 4705 | 19:00:19 INFO - PROCESS | 4705 | Cycle 1(1): loaded http://localhost:41108/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41108/tests/kraken/driver.html?testName=audio-oscillator) 19:00:20 INFO - PROCESS | 4705 | RSS: Main: 163962880 19:00:20 INFO - PROCESS | 4705 | 19:00:22 INFO - PROCESS | 4705 | Cycle 1(1): loaded http://localhost:41108/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41108/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:00:23 INFO - PROCESS | 4705 | RSS: Main: 164732928 19:00:23 INFO - PROCESS | 4705 | 19:00:25 INFO - PROCESS | 4705 | Cycle 1(1): loaded http://localhost:41108/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41108/tests/kraken/driver.html?testName=imaging-darkroom) 19:00:26 INFO - PROCESS | 4705 | RSS: Main: 165351424 19:00:26 INFO - PROCESS | 4705 | 19:00:29 INFO - PROCESS | 4705 | Cycle 1(1): loaded http://localhost:41108/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41108/tests/kraken/driver.html?testName=imaging-desaturate) 19:00:30 INFO - PROCESS | 4705 | RSS: Main: 163663872 19:00:30 INFO - PROCESS | 4705 | 19:00:33 INFO - PROCESS | 4705 | Cycle 1(1): loaded http://localhost:41108/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41108/tests/kraken/driver.html?testName=json-parse-financial) 19:00:33 INFO - PROCESS | 4705 | RSS: Main: 164544512 19:00:33 INFO - PROCESS | 4705 | 19:00:35 INFO - PROCESS | 4705 | Cycle 1(1): loaded http://localhost:41108/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41108/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:00:35 INFO - PROCESS | 4705 | RSS: Main: 175591424 19:00:35 INFO - PROCESS | 4705 | 19:00:37 INFO - PROCESS | 4705 | Cycle 1(1): loaded http://localhost:41108/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41108/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:00:38 INFO - PROCESS | 4705 | RSS: Main: 173137920 19:00:38 INFO - PROCESS | 4705 | 19:00:40 INFO - PROCESS | 4705 | Cycle 1(1): loaded http://localhost:41108/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41108/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:00:40 INFO - PROCESS | 4705 | RSS: Main: 182681600 19:00:40 INFO - PROCESS | 4705 | 19:00:43 INFO - PROCESS | 4705 | Cycle 1(1): loaded http://localhost:41108/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41108/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:00:43 INFO - PROCESS | 4705 | RSS: Main: 192561152 19:00:43 INFO - PROCESS | 4705 | 19:00:46 INFO - PROCESS | 4705 | Cycle 1(1): loaded http://localhost:41108/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41108/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:00:47 INFO - PROCESS | 4705 | RSS: Main: 191004672 19:00:47 INFO - PROCESS | 4705 | 19:00:48 INFO - PROCESS | 4705 | Cycle 1(1): loaded http://localhost:41108/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41108/tests/kraken/driver.html?testName=ai-astar) 19:00:48 INFO - PROCESS | 4705 | RSS: Main: 250597376 19:00:48 INFO - PROCESS | 4705 | 19:00:48 INFO - PROCESS | 4705 | __start_tp_report 19:00:48 INFO - PROCESS | 4705 | _x_x_mozilla_page_load 19:00:48 INFO - PROCESS | 4705 | _x_x_mozilla_page_load_details 19:00:48 INFO - PROCESS | 4705 | |i|pagename|runs| 19:00:48 INFO - PROCESS | 4705 | |0;ai-astar;110;111;106;112;111;112;101;111;111;112 19:00:48 INFO - PROCESS | 4705 | |1;audio-beat-detection;151;127;147;175;128;151;155;131;176;125 19:00:48 INFO - PROCESS | 4705 | |2;audio-dft;162;160;161;161;162;160;160;162;162;162 19:00:48 INFO - PROCESS | 4705 | |3;audio-fft;73;75;87;93;72;71;71;104;93;70 19:00:48 INFO - PROCESS | 4705 | |4;audio-oscillator;95;96;143;95;98;95;147;94;95;141 19:00:48 INFO - PROCESS | 4705 | |5;imaging-gaussian-blur;110;109;109;110;109;110;110;110;110;110 19:00:48 INFO - PROCESS | 4705 | |6;imaging-darkroom;146;146;147;146;146;146;145;145;145;145 19:00:48 INFO - PROCESS | 4705 | |7;imaging-desaturate;96;96;95;96;96;95;100;96;95;96 19:00:48 INFO - PROCESS | 4705 | |8;json-parse-financial;55;55;56;55;56;55;55;63;53;52 19:00:48 INFO - PROCESS | 4705 | |9;json-stringify-tinderbox;66;68;66;68;67;67;67;67;68;67 19:00:48 INFO - PROCESS | 4705 | |10;stanford-crypto-aes;83;92;84;85;84;87;84;85;85;84 19:00:48 INFO - PROCESS | 4705 | |11;stanford-crypto-ccm;122;117;118;120;126;124;116;115;120;119 19:00:48 INFO - PROCESS | 4705 | |12;stanford-crypto-pbkdf2;141;151;160;149;151;141;151;156;149;141 19:00:48 INFO - PROCESS | 4705 | |13;stanford-crypto-sha256-iterative;70;68;69;67;69;66;67;72;68;67 19:00:48 INFO - PROCESS | 4705 | __end_tp_report 19:00:48 INFO - PROCESS | 4705 | __start_cc_report 19:00:48 INFO - PROCESS | 4705 | _x_x_mozilla_cycle_collect,967 19:00:48 INFO - PROCESS | 4705 | __end_cc_report 19:00:48 INFO - PROCESS | 4705 | __startTimestamp1452308448971__endTimestamp 19:00:48 INFO - PROCESS | 4705 | 19:00:48 INFO - PROCESS | 4705 | ------- Summary: start ------- 19:00:48 INFO - PROCESS | 4705 | Number of tests: 14 19:00:48 INFO - PROCESS | 4705 | 19:00:48 INFO - PROCESS | 4705 | [#0] ai-astar Cycles:10 Average:109.70 Median:111.00 stddev:3.53 (3.2%) stddev-sans-first:3.74 19:00:48 INFO - PROCESS | 4705 | Values: 110.0 111.0 106.0 112.0 111.0 112.0 101.0 111.0 111.0 112.0 19:00:48 INFO - PROCESS | 4705 | 19:00:48 INFO - PROCESS | 4705 | [#1] audio-beat-detection Cycles:10 Average:146.60 Median:151.00 stddev:18.92 (12.5%) stddev-sans-first:20.00 19:00:48 INFO - PROCESS | 4705 | Values: 151.0 127.0 147.0 175.0 128.0 151.0 155.0 131.0 176.0 125.0 19:00:48 INFO - PROCESS | 4705 | 19:00:48 INFO - PROCESS | 4705 | [#2] audio-dft Cycles:10 Average:161.20 Median:162.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 19:00:48 INFO - PROCESS | 4705 | Values: 162.0 160.0 161.0 161.0 162.0 160.0 160.0 162.0 162.0 162.0 19:00:48 INFO - PROCESS | 4705 | 19:00:48 INFO - PROCESS | 4705 | [#3] audio-fft Cycles:10 Average:80.90 Median:74.00 stddev:12.27 (16.6%) stddev-sans-first:12.68 19:00:48 INFO - PROCESS | 4705 | Values: 73.0 75.0 87.0 93.0 72.0 71.0 71.0 104.0 93.0 70.0 19:00:48 INFO - PROCESS | 4705 | 19:00:48 INFO - PROCESS | 4705 | [#4] audio-oscillator Cycles:10 Average:109.90 Median:95.00 stddev:23.37 (24.6%) stddev-sans-first:24.16 19:00:48 INFO - PROCESS | 4705 | Values: 95.0 96.0 143.0 95.0 98.0 95.0 147.0 94.0 95.0 141.0 19:00:48 INFO - PROCESS | 4705 | 19:00:48 INFO - PROCESS | 4705 | [#5] imaging-gaussian-blur Cycles:10 Average:109.70 Median:110.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 19:00:48 INFO - PROCESS | 4705 | Values: 110.0 109.0 109.0 110.0 109.0 110.0 110.0 110.0 110.0 110.0 19:00:48 INFO - PROCESS | 4705 | 19:00:48 INFO - PROCESS | 4705 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 19:00:48 INFO - PROCESS | 4705 | Values: 146.0 146.0 147.0 146.0 146.0 146.0 145.0 145.0 145.0 145.0 19:00:48 INFO - PROCESS | 4705 | 19:00:48 INFO - PROCESS | 4705 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:1.45 (1.5%) stddev-sans-first:1.54 19:00:48 INFO - PROCESS | 4705 | Values: 96.0 96.0 95.0 96.0 96.0 95.0 100.0 96.0 95.0 96.0 19:00:48 INFO - PROCESS | 4705 | 19:00:48 INFO - PROCESS | 4705 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:55.00 stddev:2.92 (5.3%) stddev-sans-first:3.09 19:00:48 INFO - PROCESS | 4705 | Values: 55.0 55.0 56.0 55.0 56.0 55.0 55.0 63.0 53.0 52.0 19:00:48 INFO - PROCESS | 4705 | 19:00:48 INFO - PROCESS | 4705 | [#9] json-stringify-tinderbox Cycles:10 Average:67.10 Median:67.00 stddev:0.74 (1.1%) stddev-sans-first:0.67 19:00:48 INFO - PROCESS | 4705 | Values: 66.0 68.0 66.0 68.0 67.0 67.0 67.0 67.0 68.0 67.0 19:00:48 INFO - PROCESS | 4705 | 19:00:48 INFO - PROCESS | 4705 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:85.00 stddev:2.58 (3.0%) stddev-sans-first:2.60 19:00:48 INFO - PROCESS | 4705 | Values: 83.0 92.0 84.0 85.0 84.0 87.0 84.0 85.0 85.0 84.0 19:00:48 INFO - PROCESS | 4705 | 19:00:48 INFO - PROCESS | 4705 | [#11] stanford-crypto-ccm Cycles:10 Average:119.70 Median:120.00 stddev:3.50 (2.9%) stddev-sans-first:3.61 19:00:48 INFO - PROCESS | 4705 | Values: 122.0 117.0 118.0 120.0 126.0 124.0 116.0 115.0 120.0 119.0 19:00:48 INFO - PROCESS | 4705 | 19:00:48 INFO - PROCESS | 4705 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.00 Median:151.00 stddev:6.45 (4.3%) stddev-sans-first:6.15 19:00:48 INFO - PROCESS | 4705 | Values: 141.0 151.0 160.0 149.0 151.0 141.0 151.0 156.0 149.0 141.0 19:00:48 INFO - PROCESS | 4705 | 19:00:48 INFO - PROCESS | 4705 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.50 stddev:1.77 (2.6%) stddev-sans-first:1.76 19:00:48 INFO - PROCESS | 4705 | Values: 70.0 68.0 69.0 67.0 69.0 66.0 67.0 72.0 68.0 67.0 19:00:48 INFO - PROCESS | 4705 | -------- Summary: end -------- 19:00:48 INFO - PROCESS | 4705 | 19:00:49 INFO - TEST-INFO | 4705: exit 0 19:00:49 INFO - TEST-OK | kraken | took 58421ms 19:00:49 INFO - TEST-START | v8_7 19:00:49 INFO - operating with platform_type : linux_ 19:00:49 INFO - Initialising browser for v8_7 test... 19:00:49 INFO - TEST-INFO | started process 4953 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCi23fx/profile http://localhost:41108/getInfo.html) 19:00:53 INFO - PROCESS | 4953 | __metrics Screen width/height:1600/1200 19:00:53 INFO - PROCESS | 4953 | colorDepth:24 19:00:53 INFO - PROCESS | 4953 | Browser inner width/height: 1024/697 19:00:53 INFO - PROCESS | 4953 | __metrics 19:00:53 INFO - PROCESS | 4953 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 19:00:53 INFO - PROCESS | 4953 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:00:54 INFO - TEST-INFO | 4953: exit 0 19:00:54 INFO - Browser initialized. 19:00:54 INFO - Running cycle 1/1 for v8_7 test... 19:00:54 INFO - TEST-INFO | started process 5033 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCi23fx/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:01:06 INFO - PROCESS | 5033 | RSS: Main: 148733952 19:01:06 INFO - PROCESS | 5033 | 19:01:28 INFO - PROCESS | 5033 | Cycle 1(1): loaded http://localhost:41108/tests/v8_7/run.html (next: http://localhost:41108/tests/v8_7/run.html) 19:01:29 INFO - PROCESS | 5033 | RSS: Main: 537042944 19:01:29 INFO - PROCESS | 5033 | 19:01:29 INFO - PROCESS | 5033 | __start_tp_report 19:01:29 INFO - PROCESS | 5033 | _x_x_mozilla_page_load 19:01:29 INFO - PROCESS | 5033 | _x_x_mozilla_page_load_details 19:01:29 INFO - PROCESS | 5033 | |i|pagename|runs| 19:01:29 INFO - PROCESS | 5033 | |0;Richards;167.7289500167729 19:01:29 INFO - PROCESS | 5033 | |1;DeltaBlue;196.6955153422502 19:01:29 INFO - PROCESS | 5033 | |2;Encrypt;283.2058906825262 19:01:29 INFO - PROCESS | 5033 | |3;Decrypt;5164.948453608247 19:01:29 INFO - PROCESS | 5033 | |4;RayTrace;1966.6011787819255 19:01:29 INFO - PROCESS | 5033 | |5;Earley;790.5138339920949 19:01:29 INFO - PROCESS | 5033 | |6;Boyer;12207.317073170732 19:01:29 INFO - PROCESS | 5033 | |7;RegExp;42604.166666666664 19:01:29 INFO - PROCESS | 5033 | |8;Splay;600.2400960384153 19:01:29 INFO - PROCESS | 5033 | |9;NavierStokes;4835.748792270531 19:01:29 INFO - PROCESS | 5033 | __end_tp_report 19:01:29 INFO - PROCESS | 5033 | __startTimestamp1452308489163__endTimestamp 19:01:29 INFO - PROCESS | 5033 | 19:01:29 INFO - PROCESS | 5033 | ------- Summary: start ------- 19:01:29 INFO - PROCESS | 5033 | Number of tests: 10 19:01:29 INFO - PROCESS | 5033 | 19:01:29 INFO - PROCESS | 5033 | [#0] Richards Cycles:1 Average:167.73 Median:167.73 stddev:0.00 (0.0%) 19:01:29 INFO - PROCESS | 5033 | Values: 167.7 19:01:29 INFO - PROCESS | 5033 | 19:01:29 INFO - PROCESS | 5033 | [#1] DeltaBlue Cycles:1 Average:196.70 Median:196.70 stddev:0.00 (0.0%) 19:01:29 INFO - PROCESS | 5033 | Values: 196.7 19:01:29 INFO - PROCESS | 5033 | 19:01:29 INFO - PROCESS | 5033 | [#2] Encrypt Cycles:1 Average:283.21 Median:283.21 stddev:0.00 (0.0%) 19:01:29 INFO - PROCESS | 5033 | Values: 283.2 19:01:29 INFO - PROCESS | 5033 | 19:01:29 INFO - PROCESS | 5033 | [#3] Decrypt Cycles:1 Average:5164.95 Median:5164.95 stddev:0.00 (0.0%) 19:01:29 INFO - PROCESS | 5033 | Values: 5164.9 19:01:29 INFO - PROCESS | 5033 | 19:01:29 INFO - PROCESS | 5033 | [#4] RayTrace Cycles:1 Average:1966.60 Median:1966.60 stddev:0.00 (0.0%) 19:01:29 INFO - PROCESS | 5033 | Values: 1966.6 19:01:29 INFO - PROCESS | 5033 | 19:01:29 INFO - PROCESS | 5033 | [#5] Earley Cycles:1 Average:790.51 Median:790.51 stddev:0.00 (0.0%) 19:01:29 INFO - PROCESS | 5033 | Values: 790.5 19:01:29 INFO - PROCESS | 5033 | 19:01:29 INFO - PROCESS | 5033 | [#6] Boyer Cycles:1 Average:12207.32 Median:12207.32 stddev:0.00 (0.0%) 19:01:29 INFO - PROCESS | 5033 | Values: 12207.3 19:01:29 INFO - PROCESS | 5033 | 19:01:29 INFO - PROCESS | 5033 | [#7] RegExp Cycles:1 Average:42604.17 Median:42604.17 stddev:0.00 (0.0%) 19:01:29 INFO - PROCESS | 5033 | Values: 42604.2 19:01:29 INFO - PROCESS | 5033 | 19:01:29 INFO - PROCESS | 5033 | [#8] Splay Cycles:1 Average:600.24 Median:600.24 stddev:0.00 (0.0%) 19:01:29 INFO - PROCESS | 5033 | Values: 600.2 19:01:29 INFO - PROCESS | 5033 | 19:01:29 INFO - PROCESS | 5033 | [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 19:01:29 INFO - PROCESS | 5033 | Values: 4835.7 19:01:29 INFO - PROCESS | 5033 | -------- Summary: end -------- 19:01:29 INFO - PROCESS | 5033 | 19:01:30 INFO - TEST-INFO | 5033: exit 0 19:01:30 INFO - TEST-OK | v8_7 | took 40260ms 19:01:30 INFO - SUITE-END | took 1211s 19:01:30 INFO - Completed test suite (00:20:11) 19:01:30 INFO - Working with test: dromaeo_css 19:01:30 INFO - Generating results file: dromaeo_css 19:01:30 INFO - Working with test: kraken 19:01:30 INFO - Generating results file: kraken 19:01:30 INFO - javascript benchmark 19:01:30 INFO - Working with test: v8_7 19:01:30 INFO - Generating results file: v8_7 19:01:30 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:01:30 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 19:01:30 INFO - process_Request line: dromaeo_css 6692.95 graph.html#tests=[[72,1,35]] 19:01:30 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:01:30 INFO - process_Request line: kraken 1504.70 graph.html#tests=[[232,1,35]] 19:01:30 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:01:30 INFO - process_Request line: v8_7 18820.84 graph.html#tests=[[230,1,35]] 19:01:30 INFO - RETURN: dromaeo_css: 6692.95 19:01:30 INFO - RETURN: kraken: 1504.70 19:01:30 INFO - RETURN: v8_7: 18820.84 19:01:30 INFO - javascript benchmark 19:01:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3036.802607830491, 3063.5098384258495, 3095.3809238152376, 3118.36073604911, 3153.069844395903, 4995.075910956476, 5096.821611243608, 5140.331756871607, 5148.304978827857, 5182.896342073156, 5142.460041695625, 5187.325647665798, 5223.2165175223645, 5244.79020839167, 5245.554127899133, 4647.302904564325, 4655.34825124482, 4728.527147285278, 4797.6401769867225, 4797.90404191916, 4636.327732478789, 4753.714777113367, 4769.427668679763, 4811.639127065484, 4812.133815913135, 4699.107169637775, 4717.858464246078, 4805.471398146201, 4807.086653535817, 4822.252550854623, 4729.0541891621815, 4729.456112547066, 4730.976345118287, 4734.0, 4766.73782941938, 4706.670533062687, 4707.388039554836, 4733.0, 4735.692180008323, 4739.9526004739855, 4975.179095449246, 5022.4977502249485, 5057.848264552069, 5109.335786347751, 5149.227615857614, 5586.525145362649, 5587.608867379285, 5593.328800543961, 5597.3003374578175, 5610.719464026783, 5534.225208470817, 5625.746841392147, 5633.211350410905, 5638.097904335327, 5641.605087643826, 5165.431802501722, 5171.818986335498, 5211.609129315354, 5227.895442091137, 5268.894622107537, 5680.8863822723315, 5686.061799803069, 5688.77244910199, 5692.7722891083895, 5737.913931291034, 5378.838634840961, 5468.4531546845, 5478.0, 5481.890362192734, 5491.368492623399, 5480.095784195642, 5492.0, 5534.169874518774, 5548.0845660466375, 5598.076317407582, 5521.0, 5521.420250873601, 5555.138953462219, 5555.694436806015, 5557.3886872444, 5427.402985671573, 5520.641158324696, 5531.197976293495, 5537.529310008695, 5617.831465056055, 4312.698111132253, 4323.48118225815, 4364.08354245612, 4367.737935723867, 4408.603225709701, 3856.8842934712, 3858.575556688762, 3865.594112618191, 3873.0, 3891.805409729502, 4999.600031997432, 5031.6729412588065, 5057.266696329012, 5104.69371837691, 5106.744662766847, 5446.91829622556, 5550.361708403585, 5623.831285061455, 5652.830415087554, 5658.094704847204, 5066.290719299227, 5071.644984851025, 5081.415637201695, 5083.440821509631, 5123.948760512421, 4620.122176786467, 4677.625789936865, 4693.389859318335, 4694.155052090657, 4695.107929493453, 5675.432456754292, 5687.488126068673, 5727.198192253088, 5730.398308177665, 5765.538756899438, 5439.972800135974, 5443.918341224806, 5444.210589464585, 5448.373437054789, 5458.61789674719, 5547.362053363915, 5584.050711379156, 5587.660953778049, 5600.579956503197, 5601.495865372136, 5467.097928841697, 5506.669599824023, 5533.889322213453, 5567.972160139173, 5576.553875689854, 5510.448955104618, 5551.528120109836, 5556.138798486241, 5565.471280228372, 5568.276124103841, 5293.179557168645, 5390.05674007055, 5431.076716958048, 5440.809571665131, 5487.122060470306, 5255.973720131375, 5308.840734777963, 5331.493508116722, 5363.436839131991, 5418.674879507243, 5362.222477740784, 5365.704886231295, 5369.1140961740875, 5377.516023557899, 5380.488853558905, 5274.077036518517, 5425.620206585501, 5452.536534394462, 5479.0, 5540.141278101901, 5283.815066472655, 5344.518993290622, 5349.384820745664, 5352.063388907003, 5400.108982017923], "unit": "score", "name": "dojo.html", "value": 5107.588483930209}, {"lowerIsBetter": false, "replicates": [545.1196317946518, 550.961432699711, 558.3606770248066, 563.5153767759726, 585.7305639405873, 28076.438471230587, 28580.42839143216, 28640.427191456158, 28701.425971480585, 36164.2767144657, 42811.35782963258, 43489.0, 43562.56437435625, 43589.7820510897, 43639.78180109103, 8583.313334933222, 8874.42316249445, 8876.778080547974, 8885.866711999293, 8887.777805554848, 7445.702171913117, 7638.159802421757, 8484.745457636282, 9954.104130628242, 10237.641682541112, 9933.205343572497, 10040.548175332127, 10040.89959100407, 10042.799144017115, 10049.447280399576, 7531.887021694679, 9949.95025024874, 10030.548625311878, 10063.094321511098, 10075.143612792921, 7474.701011959515, 9972.70081897537, 10321.483925803755, 10323.48382580868, 10325.84511232332, 35949.64050359489, 36514.452283215775, 36536.634633653855, 36637.08407289813, 36651.0, 3353.7149342305934, 3429.331280400323, 3607.080194550399, 3620.9591509612405, 3694.612065733086, 29301.413971720445, 30295.394092118255, 30981.225469363446, 31092.689073109206, 31160.844195779104, 3902.2195560887653, 3974.2647610192207, 4027.0939038716347, 4040.8181631826346, 4165.958340416587, 276187.0, 276609.0, 277620.0, 278626.0, 279063.99999999796, 51864.99822523986, 52646.47353526492, 58643.4135658638, 58827.70586147042, 59991.0, 54867.725661371434, 54962.45037549653, 55086.0, 55154.0, 55330.446695533334, 15496.612584685248, 15665.373385064699, 15695.136767477898, 15705.764413533807, 15730.0, 11687.474063667156, 11723.237989530653, 11776.52893884252, 11794.705132371759, 11829.822552661717, 18890.3388381406, 18913.527161821065, 19370.322038728576, 19377.51556211106, 19477.610447790965, 7353.522021068614, 7498.925010749932, 7614.162442131361, 7687.692492300245, 7724.420668449889, 939.6711151097061, 947.9383840050375, 1052.552665117322, 1060.2313322840878, 1063.31416236527, 28008.719912801018, 29209.0, 29403.705962940097, 30011.549826752616, 31238.375232495222, 1007.9798404031878, 1040.401768982838, 1073.6617965340893, 1110.4003837927428, 1166.7958107331183, 13537.39081741324, 13778.173309601256, 15188.240587970557, 15611.3755449783, 15755.763663545056, 243067.0, 245337.0, 245850.0, 246963.0, 247023.0, 48049.519504805205, 48184.0, 48205.0, 48206.035879282215, 48230.0, 1349.925754083535, 1358.130796290375, 1377.2700468751577, 1385.06225306781, 1391.839938407096, 22298.442538936655, 28068.71931280661, 28962.565561516174, 29616.259593509472, 30254.54618180685, 47229.0, 47339.289910652056, 47552.52447475481, 47669.0, 47776.283355748994, 246278.0, 248589.0, 248868.0, 249059.0, 249630.0, 27711.722882770915, 29341.0, 30501.23746906345, 32156.678433216304, 32202.0, 37579.0, 38159.618403815606, 38451.230975380895, 38647.22705545817, 38667.80666096652, 31681.841590791897, 32021.519677205328, 32179.195520112185, 32839.34321313608, 32935.34129317448, 28296.85851570729, 33089.172770679965, 33286.0, 33343.33313333671, 33541.32917341688, 32186.35627287488, 32712.34575308433, 32894.50658240176, 32935.67064329326, 33090.33819323648, 27826.304342391602, 28753.0, 29998.0, 32206.67793322037, 32910.34179316448, 24084.27747167588, 24320.391990200387, 24445.0, 24612.753872461046, 24641.876790615934], "unit": "score", "name": "ext.html", "value": 17966.559535072614}, {"lowerIsBetter": false, "replicates": [353.5253227408143, 355.3692196351476, 357.33000623830316, 358.9461580762885, 362.3984186250824, 13326.666833329171, 13685.704575895075, 16122.274497647604, 16601.83398166018, 17818.732719009226, 14480.42078316866, 15469.767953480707, 15825.287862046207, 15831.366745330173, 16447.67104657906, 3626.202235508197, 3760.9435858462143, 3765.7175711821724, 3767.0, 3767.171222331083, 4006.699497537682, 4184.686148538856, 5436.429174936634, 5509.944900551003, 5751.741171647286, 5712.571557133211, 5892.204552385416, 5922.230110085683, 5931.88136237275, 5936.376680448556, 5930.229070220866, 5997.4002599740115, 5998.070299103668, 6000.699965001732, 6000.8499787505225, 5179.249008893727, 5193.506616871391, 5252.080885844962, 5285.207218917193, 5352.57179425645, 19549.120289587, 19709.211631534723, 19908.502287442785, 19925.302614408425, 19967.900160499248, 2194.7695491973273, 2293.1056887813766, 2305.0779688124717, 2308.4228942764307, 2316.4787922717255, 8627.568621568897, 9065.320100992452, 12634.936825315815, 12927.87072129276, 13181.275029873354, 1763.7883453985603, 1826.6711991841464, 1855.6381505606412, 1856.4801855480487, 1859.665260253154, 54432.45567544353, 55180.44819551834, 55424.69238873321, 55630.99999999959, 56447.99999999959, 9442.48066356357, 9446.0, 9469.242460603135, 9476.620935162517, 9488.478133702574, 11932.582359617372, 11977.760444791056, 12001.699957501132, 12002.519899203933, 12002.759944801055, 7129.429645628337, 7193.640317984185, 7222.602756848424, 7285.562866228043, 7376.188619251878, 7139.32176443237, 7224.385927196142, 7471.0, 7559.28186822251, 7563.432742544332, 3961.148353104039, 4006.8397264109694, 4039.9596004040172, 4071.816768245436, 4248.851290204828, 1588.4361051826666, 1623.7320842060928, 1643.9342426303053, 1676.807167175766, 1692.4753326468588, 880.4277219807177, 951.7715748220377, 976.998576459135, 978.716406456343, 992.6972273456521, 15572.143532105616, 15712.842871571367, 15807.13060781645, 16098.436554720529, 16147.757783633255, 901.1169054326797, 909.5770466733001, 927.3971918253054, 973.3867663372031, 980.6812785844629, 8844.292456603456, 8978.281737460988, 9176.082391760903, 9308.488033158106, 9310.860337094813, 500572.0, 509470.0, 517384.0, 519438.0, 530243.0, 42354.57645423568, 50708.23937640939, 68013.65993170002, 70900.0, 71335.64332178306, 1151.7581307925261, 1165.4289398194992, 1167.649705088487, 1168.7195073182374, 1178.293024185482, 14152.929235353757, 14170.149791012573, 14199.43202271939, 14555.563333100024, 14728.55814325572, 28397.432051358443, 29187.0, 29387.265318367212, 30188.396232075676, 31328.0, 38795.0, 39204.607953920095, 39279.21441571095, 39969.60030399659, 40598.59401406066, 6913.619750913748, 7102.5028248022145, 7234.63826808668, 7312.81717957034, 7328.1938986712485, 7978.441509094308, 10116.393016419039, 11020.393878336768, 11280.605178818701, 11625.825612615987, 8742.56287185651, 8845.51349675774, 8918.10818918103, 9116.498592577207, 9146.451212927246, 9371.625134994523, 9939.30424870281, 10171.389716617026, 10229.386236825814, 10522.368657880552, 6925.861482770417, 8935.955320223356, 9266.536673166449, 9316.767080823034, 9774.462404567817, 7251.456140789356, 7251.746188883364, 7252.709891604277, 7255.4921155520715, 7421.998030265845, 8662.653493860174, 9118.042605526323, 9185.173334399937, 9188.540572971191, 9300.488473133772], "unit": "score", "name": "jquery.html", "value": 7891.424143936633}, {"lowerIsBetter": false, "replicates": [1320.2474589483998, 1453.6002599285196, 1458.9562313130607, 1598.768178614101, 1767.5669460982053, 6774.356436138565, 7258.237885022076, 7418.888716669254, 7436.776896693101, 7441.069866266716, 7024.157101147856, 7093.326134017273, 7153.713851445936, 7168.713251469935, 7187.389071928892, 2202.658587918871, 2204.614192516311, 2210.1159536185573, 2224.810891074261, 2228.2646726580188, 2138.219549864296, 2167.3606286145623, 2170.696102545637, 2175.0212404418, 2180.6728990651372, 2166.480044789255, 2189.540196558725, 2192.8136108430804, 2193.2323686709606, 2196.9450763730956, 2196.615592271354, 2200.9449763755874, 2205.073868975034, 2205.4817117977263, 2206.051397898903, 2171.706819579352, 2204.371754050088, 2217.1020736601704, 2219.1123550579737, 2220.189630784752, 7223.0, 7253.202147763742, 7254.746083887038, 7261.2012678605315, 7266.200717921024, 4445.844395446144, 4536.88657783558, 4541.137183935075, 4573.062522182944, 4575.15359658461, 2886.956695649567, 2889.2343528964843, 2945.661248956376, 2962.3482833776534, 2962.9407411851644, 920.1028996728203, 931.1619542411815, 941.6610020392653, 962.6341990043738, 978.515634760798, 9846.458444785534, 10023.198144148451, 10025.0, 10033.949830250802, 10050.748731281703, 3932.4297976793205, 3988.980055099706, 4016.2971479990883, 4031.113155105872, 4042.7776472293717, 8392.370572206986, 8405.873911891327, 8409.663613455516, 8410.453320534145, 8423.578821059045, 4384.693071484965, 4410.404595379583, 4417.845375411845, 4422.358757980075, 4443.044745379759, 3694.2057457646733, 3790.298794722985, 4070.2673518766314, 4082.4488694026522, 4085.20338533983, 3470.8958731238104, 3494.7553671243277, 3516.4373700208357, 3528.4354503279865, 3546.060294022086, 1049.58541376156, 1049.9790004199874, 1053.262716098734, 1057.5663977769077, 1062.2298833345762, 1317.5454468208452, 1320.577415227118, 1332.1540821578367, 1348.298884580013, 1364.433759989613, 6878.793636190922, 6955.061066755923, 6985.93014069863, 6987.8602427951155, 7178.892316615255, 1113.5434471866495, 1118.7538741476862, 1132.213111887247, 1133.7675776465805, 1157.7163594919298, 5521.613487055867, 5580.469855363734, 5588.30146231721, 5588.692621905753, 5596.97201513998, 8778.166074222938, 8861.069587693328, 9117.95441022804, 9209.677661281823, 9228.26173906086, 8986.730398088068, 9076.183143517115, 9098.954505227432, 9106.453612783254, 9124.270058395312, 6925.168979722465, 7003.509754317251, 7125.216226215216, 7133.215346311901, 7148.356647901714, 6804.795856124324, 6853.211880633791, 6854.5201835873, 6890.896636550356, 6897.275786042594, 8004.079530854026, 8075.434719569574, 8085.151059138706, 8204.753857384288, 8228.382871284557, 8168.918310816815, 8416.3266938646, 8579.356548259031, 8582.485050896716, 8583.442076265022, 6552.63960482178, 6598.241202261609, 6707.496937729592, 6766.15423072116, 6768.492363072691, 7207.783766486804, 7234.819129521804, 7235.963820180866, 7236.529625574321, 7249.927500724925, 6686.53194276396, 6788.422984046411, 6807.251202367835, 6901.378875901193, 6917.861642767016, 6701.530892837454, 6721.529492935643, 6758.93241067583, 6809.455243580601, 6824.556403833735, 6532.640704761284, 6655.500837437308, 6691.76578819739, 6694.497912656668, 6704.363085506869, 6300.117983482224, 6312.7159277833525, 6327.2407311121115, 6338.936610633835, 6348.111264422892, 6310.810675679737, 6314.58955167931, 6335.841603959938, 6335.873282534416, 6340.619562826245], "unit": "score", "name": "mootools.html", "value": 4321.307360378456}, {"lowerIsBetter": false, "replicates": [322.8643969532796, 333.00929734041233, 339.69088129801884, 342.53757427472914, 348.22346168045243, 5666.150077488369, 5809.2738407699035, 5860.50185734212, 5881.088431293151, 5903.26209223847, 5655.519280861132, 5799.246098007253, 5806.216160818289, 5816.243888294526, 5825.650460972344, 4535.501094879561, 4554.795034223468, 4556.3621093047, 4558.133954548642, 4559.498455169929, 4338.132373525292, 4437.689361744679, 4447.710898791584, 4450.755208463534, 4564.338170965208, 4333.804978775946, 4416.050549131936, 4451.2655411857, 4456.064226512436, 4492.932606010912, 4448.0, 4450.043240703264, 4466.240739074349, 4469.843555475542, 4485.865424037284, 3929.8231579578987, 3931.7247792654525, 3936.1340505088833, 3936.2914675358425, 3937.7243592948507, 5361.329833770778, 5486.478784515504, 5527.751251193706, 5556.555475561945, 5565.415631358689, 5097.745112744348, 5104.61715371345, 5106.463821298785, 5107.463716309771, 5110.514501122388, 5886.234789477383, 6123.142760013601, 6124.571280010401, 6125.173101631267, 6133.509319254449, 4132.318167502359, 4195.853145139906, 4287.48550173978, 4288.16380805743, 4293.0340673348555, 6371.808845734636, 6407.135036770023, 6420.711068001951, 6423.807285781388, 6443.355664433566, 4363.258246098171, 4364.8254069837485, 4367.759773212504, 4370.978145109318, 4377.277749171351, 4349.108432771274, 4413.536578659258, 4420.58004489573, 4428.047969686533, 4464.352668862996, 5138.434772175057, 5180.300659411007, 5187.792488300426, 5190.870228244248, 5193.039287731782, 6287.842803929847, 6294.370562943761, 6296.968515157395, 6298.307186209513, 6308.716107775161, 1563.4137198550543, 1596.22583047222, 1597.2413103775846, 1597.9280932358072, 1597.9280932358072, 1066.903978641926, 1067.114295135036, 1081.2269227502375, 1083.285031878956, 1098.7912296663608, 5842.737076831468, 5846.619969701956, 5848.853778655568, 5855.2973643163095, 5857.4435428634215, 6393.1688880445245, 6396.168498095218, 6403.167588213502, 6403.822700281833, 6412.422881940554, 5850.063989761703, 5851.824445266649, 5885.676287804126, 5901.940980590225, 5914.172015917775, 3379.729621630264, 3430.9828450857585, 3481.164520515109, 3486.7210623150086, 3489.3195826813526, 6588.439982601532, 6598.241202261705, 6601.570897891622, 6601.70292336846, 6610.537262391586, 6541.771038013647, 6600.86798264032, 6608.372204640552, 6616.007598860209, 6623.138991931018, 6478.481721462272, 6599.406053455212, 6615.86768264632, 6618.338166183439, 6623.867522649616, 6213.409726076015, 6331.113644089924, 6425.650613371245, 6427.0, 6430.48556115519, 6279.591826531444, 6289.056641503811, 6408.29508754046, 6411.807645770635, 6432.00303952888, 6593.406593406617, 6609.933900660932, 6619.801405957829, 6627.072209890523, 6627.635480048643, 4385.035292235703, 4430.712003719748, 4431.892026993251, 4434.667399945081, 4469.351943968041, 6470.773522926675, 6475.514336424692, 6476.417122459001, 6476.5466417350335, 6485.805425837232, 6300.558960872878, 6303.810885673437, 6304.3380445054445, 6309.179806625109, 6312.179416675986, 6169.629822210682, 6279.246490421178, 6292.968535157295, 6299.055141728777, 6305.085762564311, 6174.845628859135, 6184.381561843779, 6185.474234690102, 6188.319284878749, 6199.0, 6314.842128946813, 6322.525810564134, 6323.968380158254, 6325.051242313506, 6329.841753956004, 6039.939600604113, 6140.4473597376455, 6144.907826382697, 6149.108379285068, 6158.753649853956], "unit": "score", "name": "prototype.html", "value": 4687.878973123149}, {"lowerIsBetter": false, "replicates": [749.1684230504139, 811.5495899775624, 843.9662413503456, 852.8422241885252, 861.685484798049, 12296.0, 12632.11575189737, 12951.935240323784, 12959.352032398378, 12990.48038078477, 12343.13598048137, 12873.163244389109, 12900.93549532251, 12924.612261632143, 12950.35248237589, 672.3209558346057, 681.7920534237056, 806.1656185847648, 807.3016840433013, 823.0247157118833, 810.8135128920352, 813.9389545784061, 816.6692489541745, 819.5246756880995, 819.5779173725506, 792.9801754956114, 800.3397197312212, 800.8198155415014, 802.574635443216, 804.264098350009, 708.3943228539576, 718.3534818663193, 826.1490664615426, 850.2900078434515, 857.9485230886166, 625.6621424430801, 630.2122347066168, 630.6090224061065, 631.1384959530254, 632.4908448698795, 12289.754204915851, 12398.504059837596, 12420.627381178489, 12467.314297713621, 12576.81134782979, 12615.180013299107, 12636.30500322482, 13020.153690010118, 13066.216027038408, 13246.345775542239, 10563.366198028067, 10607.893921060844, 10641.946790266076, 10674.199435042403, 10727.83908241377, 3633.218857945554, 3675.5589329280656, 3707.3141468828085, 3827.3684842001035, 3830.9616903830884, 44279.55720442787, 45062.54937450616, 45495.54504454913, 45712.77143614294, 45767.771161144316, 9105.81788364229, 9143.679971200976, 9340.019297973751, 9397.953010234905, 9489.383190092622, 16723.83276167247, 16736.581585460463, 16744.58138546522, 16771.496855094367, 16789.24448399825, 7230.566166030055, 7287.817804554823, 7559.659815308324, 7654.961725191339, 7655.425843061793, 4025.0742329264435, 4054.71616986814, 4172.937405938914, 4177.874663760092, 4189.308764053958, 2926.4878646236825, 2952.143878275319, 2956.654722101439, 2960.4967155583604, 2964.9110526683803, 542.8371488553419, 554.9142112538619, 559.9384067752544, 566.59205372132, 571.2573654249542, 589.949854262384, 632.3234139470723, 668.9295636054566, 672.579637726421, 736.7568702328326, 8234.300084492765, 8310.54292013945, 8401.873971890427, 8581.0, 8613.310935125175, 562.1085048810722, 597.5667640960269, 622.3714048810643, 639.7377075399064, 674.1909708349999, 3889.9805500972316, 4007.338789099824, 4064.7154699171365, 4079.224947260011, 4137.172565486914, 51211.23183152256, 52408.21387679188, 52436.0, 53801.730991345576, 54709.45290547043, 46802.0, 47145.5285447148, 47151.0, 47393.28910066352, 47542.28686569704, 39021.0, 40193.59806401957, 40221.79889100594, 42511.149777004284, 43453.13093738107, 7857.174996625501, 8133.75598732039, 8143.75568732939, 8214.794630134056, 8370.707025254087, 33267.334653306316, 33750.0, 34017.0, 34077.82961085179, 34455.65544344525, 40121.39817902792, 41808.1638367237, 41986.0, 42466.938326542084, 43144.0, 10957.178211634, 11008.944955275492, 11078.667639970814, 11232.66302010941, 11301.15241356885, 11385.259958102697, 11716.297022178696, 12180.208286461706, 12637.052221083639, 12936.288504132363, 10902.509387077442, 11432.085433165494, 11463.254888432226, 11529.308241505536, 11714.062874970152, 11009.0, 11124.833127502932, 11301.37842418642, 11833.11251656112, 11945.4027298633, 11337.319760814378, 11486.310821350746, 11603.825942610698, 11660.708482287671, 11673.416329183678, 8785.12148785142, 8954.731358059269, 9052.502112383881, 9080.727578172666, 9230.584623692084, 8647.697330593399, 8839.204471597586, 9681.128698417177, 9780.559874805793, 9919.20646348276], "unit": "score", "name": "yui.html", "value": 6126.8715356124585}], "name": "dromaeo_css", "value": 6692.949913133333}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 111.0, 106.0, 112.0, 111.0, 112.0, 101.0, 111.0, 111.0, 112.0], "unit": "score", "name": "ai-astar", "value": 109.7}, {"lowerIsBetter": true, "replicates": [151.0, 127.0, 147.0, 175.0, 128.0, 151.0, 155.0, 131.0, 176.0, 125.0], "unit": "score", "name": "audio-beat-detection", "value": 146.6}, {"lowerIsBetter": true, "replicates": [162.0, 160.0, 161.0, 161.0, 162.0, 160.0, 160.0, 162.0, 162.0, 162.0], "unit": "score", "name": "audio-dft", "value": 161.2}, {"lowerIsBetter": true, "replicates": [73.0, 75.0, 87.0, 93.0, 72.0, 71.0, 71.0, 104.0, 93.0, 70.0], "unit": "score", "name": "audio-fft", "value": 80.9}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 143.0, 95.0, 98.0, 95.0, 147.0, 94.0, 95.0, 141.0], "unit": "score", "name": "audio-oscillator", "value": 109.9}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 110.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.7}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0], "unit": "score", "name": "imaging-darkroom", "value": 145.7}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 95.0, 96.0, 96.0, 95.0, 100.0, 96.0, 95.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.1}, {"lowerIsBetter": true, "replicates": [55.0, 55.0, 56.0, 55.0, 56.0, 55.0, 55.0, 63.0, 53.0, 52.0], "unit": "score", "name": "json-parse-financial", "value": 55.5}, {"lowerIsBetter": true, "replicates": [66.0, 68.0, 66.0, 68.0, 67.0, 67.0, 67.0, 67.0, 68.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.1}, {"lowerIsBetter": true, "replicates": [83.0, 92.0, 84.0, 85.0, 84.0, 87.0, 84.0, 85.0, 85.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.3}, {"lowerIsBetter": true, "replicates": [122.0, 117.0, 118.0, 120.0, 126.0, 124.0, 116.0, 115.0, 120.0, 119.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 119.7}, {"lowerIsBetter": true, "replicates": [141.0, 151.0, 160.0, 149.0, 151.0, 141.0, 151.0, 156.0, 149.0, 141.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 149.0}, {"lowerIsBetter": true, "replicates": [70.0, 68.0, 69.0, 67.0, 69.0, 66.0, 67.0, 72.0, 68.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.3}], "name": "kraken", "value": 1504.7}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.7289500167729], "unit": "score", "name": "Richards", "value": 210.47052400000004}, {"lowerIsBetter": false, "replicates": [196.6955153422502], "unit": "score", "name": "DeltaBlue", "value": 336.14391200000006}, {"lowerIsBetter": false, "replicates": [283.2058906825262], "unit": "score", "name": "Encrypt", "value": 939.8851109999996}, {"lowerIsBetter": false, "replicates": [5164.948453608247], "unit": "score", "name": "Decrypt", "value": 51.53604191616765}, {"lowerIsBetter": false, "replicates": [1966.6011787819255], "unit": "score", "name": "RayTrace", "value": 376.2781228771229}, {"lowerIsBetter": false, "replicates": [790.5138339920949], "unit": "score", "name": "Earley", "value": 843.0756950000003}, {"lowerIsBetter": false, "replicates": [12207.317073170732], "unit": "score", "name": "Boyer", "value": 54.595370629370635}, {"lowerIsBetter": false, "replicates": [42604.166666666664], "unit": "score", "name": "RegExp", "value": 21.382533007334946}, {"lowerIsBetter": false, "replicates": [600.2400960384153], "unit": "score", "name": "Splay", "value": 135.764006}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 18820.843169981254}]} 19:01:30 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "18820.84"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1504.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6692.95"}}} 19:01:30 INFO - Return code: 0 19:01:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 19:01:30 INFO - # TBPL SUCCESS # 19:01:30 INFO - Running post-action listener: _resource_record_post_action 19:01:30 INFO - Running post-run listener: _resource_record_post_run 19:01:31 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 0; Write bytes: 566353920; Read time: 0; Write time: 579652 19:01:31 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 254210048; Read time: 0; Write time: 374828 19:01:31 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 0; Write bytes: 311267328; Read time: 0; Write time: 204776 19:01:31 INFO - Running post-run listener: _upload_blobber_files 19:01:31 INFO - Blob upload gear active. 19:01:31 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 19:01:31 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 19:01:31 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'] 19:01:31 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 19:01:32 INFO - (blobuploader) - INFO - Open directory for files ... 19:01:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 19:01:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:01:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:01:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:01:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:01:33 INFO - (blobuploader) - INFO - Done attempting. 19:01:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:01:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:01:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:01:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:01:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:01:33 INFO - (blobuploader) - INFO - Done attempting. 19:01:33 INFO - (blobuploader) - INFO - Iteration through files over. 19:01:33 INFO - Return code: 0 19:01:33 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 19:01:33 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 19:01:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8631208d673eab2e721d45bc101215251fb18c97ce57e1b7ace8d22341be3ee0fa3174e74bc47bf82005016e58bec30aedaa1e4959a558170c716ec215e82432", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c495cbbc73c20f6fba657479c19b127a4259b3657af4f3192ef8fccf18a5de978ef83d483968ab402d28b38616f22de088fe37a56b2a133d7426a9216e62adb8"} 19:01:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 19:01:33 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 19:01:33 INFO - Contents: 19:01:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8631208d673eab2e721d45bc101215251fb18c97ce57e1b7ace8d22341be3ee0fa3174e74bc47bf82005016e58bec30aedaa1e4959a558170c716ec215e82432", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c495cbbc73c20f6fba657479c19b127a4259b3657af4f3192ef8fccf18a5de978ef83d483968ab402d28b38616f22de088fe37a56b2a133d7426a9216e62adb8"} 19:01:33 INFO - Copying logs to upload dir... 19:01:33 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1267.687941 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-01-08 19:01:33.296121) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-08 19:01:33.300247) ========= 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=ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409 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/8631208d673eab2e721d45bc101215251fb18c97ce57e1b7ace8d22341be3ee0fa3174e74bc47bf82005016e58bec30aedaa1e4959a558170c716ec215e82432", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c495cbbc73c20f6fba657479c19b127a4259b3657af4f3192ef8fccf18a5de978ef83d483968ab402d28b38616f22de088fe37a56b2a133d7426a9216e62adb8"} build_url:https://queue.taskcluster.net/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011418 build_url: 'https://queue.taskcluster.net/v1/task/LcoPNZDDSrm2Ju3_G4kvYA/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/8631208d673eab2e721d45bc101215251fb18c97ce57e1b7ace8d22341be3ee0fa3174e74bc47bf82005016e58bec30aedaa1e4959a558170c716ec215e82432", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c495cbbc73c20f6fba657479c19b127a4259b3657af4f3192ef8fccf18a5de978ef83d483968ab402d28b38616f22de088fe37a56b2a133d7426a9216e62adb8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-08 19:01:33.325369) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 19:01:33.325742) ========= 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=ed14279e38b05397cf4aa391000001a4-1452306852.214512-1616281409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004471 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 19:01:33.409579) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-08 19:01:33.410201) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-08 19:01:33.410724) ========= ========= Total master_lag: 0.27 =========