builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-003 starttime: 1453800395.15 results: success (0) buildid: 20160125070233 builduid: eb2ea0af6ac2494ca810680a4890723f revision: 3bc504ea394f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-26 01:26:35.145877) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-26 01:26:35.146288) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-26 01:26:35.146583) ========= 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=7de08804244eb36560b44971000001c3-1453800394.442181-513517803 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006176 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-26 01:26:35.168558) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-26 01:26:35.168837) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-26 01:26:35.185894) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:26:35.186149) ========= 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=7de08804244eb36560b44971000001c3-1453800394.442181-513517803 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020625 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:26:35.235106) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-26 01:26:35.235396) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-26 01:26:35.235749) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:26:35.236022) ========= 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=7de08804244eb36560b44971000001c3-1453800394.442181-513517803 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-26 01:26:35-- 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% 64.5M=0s 2016-01-26 01:26:35 (64.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.399123 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:26:35.647219) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:26:35.647563) ========= 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=7de08804244eb36560b44971000001c3-1453800394.442181-513517803 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.032160 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:26:35.697208) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-26 01:26:35.697572) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3bc504ea394f --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3bc504ea394f --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=7de08804244eb36560b44971000001c3-1453800394.442181-513517803 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-26 01:26:35,784 Setting DEBUG logging. 2016-01-26 01:26:35,784 attempt 1/10 2016-01-26 01:26:35,784 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/3bc504ea394f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-26 01:26:36,944 unpacking tar archive at: mozilla-beta-3bc504ea394f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.464667 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-26 01:26:37.174868) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-26 01:26:37.175219) ========= echo 3bc504ea394f in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'3bc504ea394f'] 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=7de08804244eb36560b44971000001c3-1453800394.442181-513517803 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 3bc504ea394f program finished with exit code 0 elapsedTime=0.004713 script_repo_revision: '3bc504ea394f' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-26 01:26:37.225913) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-26 01:26:37.226202) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-26 01:26:37.236014) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-01-26 01:26:37.236280) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=7de08804244eb36560b44971000001c3-1453800394.442181-513517803 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:26:37 INFO - MultiFileLogger online at 20160126 01:26:37 in /builds/slave/test-pgo 01:26:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 01:26:37 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 01:26:37 INFO - {'append_to_log': False, 01:26:37 INFO - 'base_work_dir': '/builds/slave/test-pgo', 01:26:37 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:26:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 01:26:37 INFO - 'branch': 'Mozilla-Beta', 01:26:37 INFO - 'buildbot_json_path': 'buildprops.json', 01:26:37 INFO - 'config_files': ('talos/linux_config.py',), 01:26:37 INFO - 'default_actions': ('clobber', 01:26:37 INFO - 'read-buildbot-config', 01:26:37 INFO - 'download-and-extract', 01:26:37 INFO - 'populate-webroot', 01:26:37 INFO - 'create-virtualenv', 01:26:37 INFO - 'install', 01:26:37 INFO - 'run-tests'), 01:26:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:26:37 INFO - 'download_minidump_stackwalk': True, 01:26:37 INFO - 'download_symbols': 'ondemand', 01:26:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:26:37 INFO - 'tooltool.py': '/tools/tooltool.py', 01:26:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:26:37 INFO - '/tools/misc-python/virtualenv.py')}, 01:26:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:26:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:26:37 INFO - 'installer_path': 'installer.exe', 01:26:37 INFO - 'log_level': 'info', 01:26:37 INFO - 'log_name': 'talos', 01:26:37 INFO - 'log_to_console': True, 01:26:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:26:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:26:37 INFO - 'opt_config_files': (), 01:26:37 INFO - 'pip_index': False, 01:26:37 INFO - 'sps_profile': False, 01:26:37 INFO - 'sps_profile_interval': 0, 01:26:37 INFO - 'suite': 'dromaeojs', 01:26:37 INFO - 'system_bits': '32', 01:26:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:26:37 INFO - 'title': 'talos-linux64-ix-003', 01:26:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:26:37 INFO - 'use_talos_json': True, 01:26:37 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 01:26:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:26:37 INFO - 'work_dir': 'build'} 01:26:37 INFO - ##### 01:26:37 INFO - ##### Running clobber step. 01:26:37 INFO - ##### 01:26:37 INFO - Running pre-action listener: _resource_record_pre_action 01:26:37 INFO - Running main action method: clobber 01:26:37 INFO - rmtree: /builds/slave/test-pgo/build 01:26:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 01:26:38 INFO - Running post-action listener: _resource_record_post_action 01:26:38 INFO - ##### 01:26:38 INFO - ##### Running read-buildbot-config step. 01:26:38 INFO - ##### 01:26:38 INFO - Running pre-action listener: _resource_record_pre_action 01:26:38 INFO - Running main action method: read_buildbot_config 01:26:38 INFO - Using buildbot properties: 01:26:38 INFO - { 01:26:38 INFO - "properties": { 01:26:38 INFO - "buildnumber": 94, 01:26:38 INFO - "product": "firefox", 01:26:38 INFO - "script_repo_revision": "production", 01:26:38 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 01:26:38 INFO - "repository": "", 01:26:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 01:26:38 INFO - "buildid": "20160125070233", 01:26:38 INFO - "slavename": "talos-linux64-ix-003", 01:26:38 INFO - "pgo_build": "True", 01:26:38 INFO - "basedir": "/builds/slave/test-pgo", 01:26:38 INFO - "project": "", 01:26:38 INFO - "platform": "ubuntu64_hw", 01:26:38 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 01:26:38 INFO - "slavebuilddir": "test", 01:26:38 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 01:26:38 INFO - "branch": "mozilla-beta", 01:26:38 INFO - "repo_path": "releases/mozilla-beta", 01:26:38 INFO - "stage_platform": "linux64-pgo", 01:26:38 INFO - "builduid": "eb2ea0af6ac2494ca810680a4890723f", 01:26:38 INFO - "revision": "3bc504ea394f" 01:26:38 INFO - }, 01:26:38 INFO - "sourcestamp": { 01:26:38 INFO - "repository": "", 01:26:38 INFO - "hasPatch": false, 01:26:38 INFO - "project": "", 01:26:38 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 01:26:38 INFO - "changes": [ 01:26:38 INFO - { 01:26:38 INFO - "category": null, 01:26:38 INFO - "files": [ 01:26:38 INFO - { 01:26:38 INFO - "url": null, 01:26:38 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2" 01:26:38 INFO - } 01:26:38 INFO - ], 01:26:38 INFO - "repository": "", 01:26:38 INFO - "rev": "3bc504ea394f", 01:26:38 INFO - "who": "sendchange", 01:26:38 INFO - "when": 1453769833, 01:26:38 INFO - "number": 7514634, 01:26:38 INFO - "comments": "Bug 1241916 - Adds gn locale to browser/locales/shipped-locales. r=delphine a=sylvestre", 01:26:38 INFO - "project": "", 01:26:38 INFO - "at": "Mon 25 Jan 2016 16:57:13", 01:26:38 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 01:26:38 INFO - "revlink": "", 01:26:38 INFO - "properties": [ 01:26:38 INFO - [ 01:26:38 INFO - "buildid", 01:26:38 INFO - "20160125070233", 01:26:38 INFO - "Change" 01:26:38 INFO - ], 01:26:38 INFO - [ 01:26:38 INFO - "builduid", 01:26:38 INFO - "eb2ea0af6ac2494ca810680a4890723f", 01:26:38 INFO - "Change" 01:26:38 INFO - ], 01:26:38 INFO - [ 01:26:38 INFO - "pgo_build", 01:26:38 INFO - "True", 01:26:38 INFO - "Change" 01:26:38 INFO - ] 01:26:38 INFO - ], 01:26:38 INFO - "revision": "3bc504ea394f" 01:26:38 INFO - } 01:26:38 INFO - ], 01:26:38 INFO - "revision": "3bc504ea394f" 01:26:38 INFO - } 01:26:38 INFO - } 01:26:38 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2. 01:26:38 INFO - Running post-action listener: _resource_record_post_action 01:26:38 INFO - ##### 01:26:38 INFO - ##### Running download-and-extract step. 01:26:38 INFO - ##### 01:26:38 INFO - Running pre-action listener: _resource_record_pre_action 01:26:38 INFO - Running main action method: download_and_extract 01:26:38 INFO - mkdir: /builds/slave/test-pgo/build/tests 01:26:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:38 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/test_packages.json 01:26:38 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 01:26:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 01:26:38 INFO - Downloaded 1270 bytes. 01:26:38 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 01:26:38 INFO - Using the following test package requirements: 01:26:38 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 01:26:38 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:26:38 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 01:26:38 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:26:38 INFO - u'jsshell-linux-x86_64.zip'], 01:26:38 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:26:38 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 01:26:38 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 01:26:38 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:26:38 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 01:26:38 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:26:38 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 01:26:38 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:26:38 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 01:26:38 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 01:26:38 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:26:38 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 01:26:38 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:26:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:38 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:26:38 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:26:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:26:39 INFO - Downloaded 21580477 bytes. 01:26:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 01:26:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:26:39 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 01:26:40 INFO - Return code: 0 01:26:40 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 01:26: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')]} 01:26:40 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:26:40 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:26:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:26:40 INFO - Downloaded 21580477 bytes. 01:26:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 01:26:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:26:40 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 01:26:41 INFO - Return code: 0 01:26:41 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')]} 01:26:41 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 01:26:41 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 01:26:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 01:26:42 INFO - Downloaded 11132038 bytes. 01:26:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 01:26:42 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 01:26:42 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 01:26:42 INFO - Return code: 0 01:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:42 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2 01:26:42 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 01:26:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 01:26:43 INFO - Downloaded 57947831 bytes. 01:26:43 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2 01:26:43 INFO - mkdir: /builds/slave/test-pgo/properties 01:26:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 01:26:43 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 01:26:43 INFO - Contents: 01:26:43 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2 01:26:43 INFO - Running post-action listener: _resource_record_post_action 01:26:43 INFO - Running post-action listener: set_extra_try_arguments 01:26:43 INFO - ##### 01:26:43 INFO - ##### Running populate-webroot step. 01:26:43 INFO - ##### 01:26:43 INFO - Running pre-action listener: _resource_record_pre_action 01:26:43 INFO - Running main action method: populate_webroot 01:26:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:26:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:26:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:26:43 INFO - '--tppagecycles', 01:26:43 INFO - '7'], 01:26:43 INFO - 'tests': ['tsvgm']}}, 01:26:43 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 01:26:43 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 01:26:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:26:43 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:26:43 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 01:26:43 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:26:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:26:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:43 INFO - 'talos_options': ['--e10s'], 01:26:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:26:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:43 INFO - 'tests': ['damp', 'tps']}, 01:26:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:43 INFO - 'talos_options': ['--e10s'], 01:26:43 INFO - 'tests': ['damp', 'tps']}, 01:26:43 INFO - 'g3': {'tests': ['dromaeo_dom']}, 01:26:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:26:43 INFO - 'other': {'tests': ['a11yr', 01:26:43 INFO - 'ts_paint', 01:26:43 INFO - 'tpaint', 01:26:43 INFO - 'sessionrestore', 01:26:43 INFO - 'sessionrestore_no_auto_restore']}, 01:26:43 INFO - 'other-e10s': {'talos_options': ['--e10s'], 01:26:43 INFO - 'tests': ['a11yr', 01:26:43 INFO - 'ts_paint', 01:26:43 INFO - 'tpaint', 01:26:43 INFO - 'sessionrestore', 01:26:43 INFO - 'sessionrestore_no_auto_restore']}, 01:26:43 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 01:26:43 INFO - 'tests': ['a11yr', 01:26:43 INFO - 'ts_paint', 01:26:43 INFO - 'tpaint', 01:26:43 INFO - 'sessionrestore', 01:26:43 INFO - 'sessionrestore_no_auto_restore']}, 01:26:43 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 01:26:43 INFO - 'tests': ['a11yr', 01:26:43 INFO - 'ts_paint', 01:26:43 INFO - 'tpaint', 01:26:43 INFO - 'sessionrestore', 01:26:43 INFO - 'sessionrestore_no_auto_restore']}, 01:26:43 INFO - 'other_l64': {'tests': ['a11yr', 01:26:43 INFO - 'ts_paint', 01:26:43 INFO - 'tpaint', 01:26:43 INFO - 'sessionrestore', 01:26:43 INFO - 'sessionrestore_no_auto_restore']}, 01:26:43 INFO - 'other_nol64': {'tests': ['a11yr', 01:26:43 INFO - 'ts_paint', 01:26:43 INFO - 'tpaint', 01:26:43 INFO - 'sessionrestore', 01:26:43 INFO - 'sessionrestore_no_auto_restore']}, 01:26:43 INFO - 'svgr': {'tests': ['tsvgx', 01:26:43 INFO - 'tsvgr_opacity', 01:26:43 INFO - 'tart', 01:26:43 INFO - 'tscrollx', 01:26:43 INFO - 'cart']}, 01:26:43 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 01:26:43 INFO - 'tests': ['tsvgx', 01:26:43 INFO - 'tsvgr_opacity', 01:26:43 INFO - 'tart', 01:26:43 INFO - 'tscrollx', 01:26:43 INFO - 'cart']}, 01:26:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:43 INFO - 'tests': ['tp5o']}, 01:26:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:43 INFO - 'talos_options': ['--e10s'], 01:26:43 INFO - 'tests': ['tp5o']}, 01:26:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:43 INFO - 'talos_options': ['--xperf_path', 01:26:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:26:43 INFO - 'tests': ['tp5n']}, 01:26:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:43 INFO - 'talos_options': ['--e10s', 01:26:43 INFO - '--xperf_path', 01:26:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:26:43 INFO - 'tests': ['tp5n']}}, 01:26:43 INFO - 'talos.zip': {'path': '', 01:26:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:26:43 INFO - Running post-action listener: _resource_record_post_action 01:26:43 INFO - ##### 01:26:43 INFO - ##### Running create-virtualenv step. 01:26:43 INFO - ##### 01:26:43 INFO - Running pre-action listener: _resource_record_pre_action 01:26:43 INFO - Running main action method: create_virtualenv 01:26:43 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 01:26:43 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 01:26:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 01:26:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:26:43 INFO - Using real prefix '/usr' 01:26:43 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 01:26:43 INFO - Installing distribute.............................................................................................................................................................................................done. 01:26:45 INFO - Installing pip.................done. 01:26:45 INFO - Return code: 0 01:26:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 01:26: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')]} 01:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26: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')]} 01:26:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f79c0872f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2f350>, '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 0x1b30250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b306e0>, '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': '7de08804244eb36560b44971000001c3-1453800394.442181-513517803', '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 01:26:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 01:26:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 01:26:45 INFO - Using env: {'DISPLAY': ':0', 01:26:45 INFO - 'HOME': '/home/cltbld', 01:26:45 INFO - 'LANG': 'en_US.UTF-8', 01:26:45 INFO - 'LANGUAGE': 'en_US:en', 01:26:45 INFO - 'LOGNAME': 'cltbld', 01:26:45 INFO - 'MAIL': '/var/mail/cltbld', 01:26:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:45 INFO - 'MOZ_NO_REMOTE': '1', 01:26:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:26:45 INFO - 'NO_EM_RESTART': '1', 01:26:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:26:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:26:45 INFO - 'PWD': '/builds/slave/test-pgo', 01:26:45 INFO - 'SHELL': '/bin/bash', 01:26:45 INFO - 'SHLVL': '1', 01:26:45 INFO - 'TERM': 'linux', 01:26:45 INFO - 'TMOUT': '86400', 01:26:45 INFO - 'USER': 'cltbld', 01:26:45 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1453800394.442181-513517803', 01:26:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:45 INFO - '_': '/tools/buildbot/bin/python'} 01:26:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:45 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 01:26:45 INFO - Cleaning up... 01:26:46 INFO - Return code: 0 01:26:46 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 01:26:46 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')]} 01:26:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:46 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')]} 01:26:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:46 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f79c0872f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2f350>, '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 0x1b30250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b306e0>, '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': '7de08804244eb36560b44971000001c3-1453800394.442181-513517803', '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 01:26:46 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 01:26:46 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 01:26:46 INFO - Using env: {'DISPLAY': ':0', 01:26:46 INFO - 'HOME': '/home/cltbld', 01:26:46 INFO - 'LANG': 'en_US.UTF-8', 01:26:46 INFO - 'LANGUAGE': 'en_US:en', 01:26:46 INFO - 'LOGNAME': 'cltbld', 01:26:46 INFO - 'MAIL': '/var/mail/cltbld', 01:26:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:46 INFO - 'MOZ_NO_REMOTE': '1', 01:26:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:26:46 INFO - 'NO_EM_RESTART': '1', 01:26:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:26:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:26:46 INFO - 'PWD': '/builds/slave/test-pgo', 01:26:46 INFO - 'SHELL': '/bin/bash', 01:26:46 INFO - 'SHLVL': '1', 01:26:46 INFO - 'TERM': 'linux', 01:26:46 INFO - 'TMOUT': '86400', 01:26:46 INFO - 'USER': 'cltbld', 01:26:46 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1453800394.442181-513517803', 01:26:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:46 INFO - '_': '/tools/buildbot/bin/python'} 01:26:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:46 INFO - Downloading/unpacking psutil>=0.7.1 01:26:46 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 01:26:46 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 01:26:46 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 01:26:46 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 01:26:46 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 01:26:46 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 01:26:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 01:26:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:26:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:26:48 INFO - Installing collected packages: psutil 01:26:48 INFO - Running setup.py install for psutil 01:26:48 INFO - building 'psutil._psutil_linux' extension 01:26:48 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 01:26:48 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 01:26:48 INFO - building 'psutil._psutil_posix' extension 01:26:48 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 01:26:48 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 01:26:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:26:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:26:48 INFO - Successfully installed psutil 01:26:48 INFO - Cleaning up... 01:26:48 INFO - Return code: 0 01:26:48 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 01:26: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')]} 01:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26: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')]} 01:26:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:48 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f79c0872f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2f350>, '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 0x1b30250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b306e0>, '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': '7de08804244eb36560b44971000001c3-1453800394.442181-513517803', '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 01:26:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 01:26:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 01:26:48 INFO - Using env: {'DISPLAY': ':0', 01:26:48 INFO - 'HOME': '/home/cltbld', 01:26:48 INFO - 'LANG': 'en_US.UTF-8', 01:26:48 INFO - 'LANGUAGE': 'en_US:en', 01:26:48 INFO - 'LOGNAME': 'cltbld', 01:26:48 INFO - 'MAIL': '/var/mail/cltbld', 01:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:48 INFO - 'MOZ_NO_REMOTE': '1', 01:26:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:26:48 INFO - 'NO_EM_RESTART': '1', 01:26:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:26:48 INFO - 'PWD': '/builds/slave/test-pgo', 01:26:48 INFO - 'SHELL': '/bin/bash', 01:26:48 INFO - 'SHLVL': '1', 01:26:48 INFO - 'TERM': 'linux', 01:26:48 INFO - 'TMOUT': '86400', 01:26:48 INFO - 'USER': 'cltbld', 01:26:48 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1453800394.442181-513517803', 01:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:48 INFO - '_': '/tools/buildbot/bin/python'} 01:26:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:48 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:50 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:26:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:26:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:26:50 INFO - Installing collected packages: mozsystemmonitor 01:26:50 INFO - Running setup.py install for mozsystemmonitor 01:26:50 INFO - Successfully installed mozsystemmonitor 01:26:50 INFO - Cleaning up... 01:26:50 INFO - Return code: 0 01:26:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 01:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:50 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f79c0872f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2f350>, '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 0x1b30250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b306e0>, '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': '7de08804244eb36560b44971000001c3-1453800394.442181-513517803', '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 01:26:50 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 01:26:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 01:26:50 INFO - Using env: {'DISPLAY': ':0', 01:26:50 INFO - 'HOME': '/home/cltbld', 01:26:50 INFO - 'LANG': 'en_US.UTF-8', 01:26:50 INFO - 'LANGUAGE': 'en_US:en', 01:26:50 INFO - 'LOGNAME': 'cltbld', 01:26:50 INFO - 'MAIL': '/var/mail/cltbld', 01:26:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:50 INFO - 'MOZ_NO_REMOTE': '1', 01:26:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:26:50 INFO - 'NO_EM_RESTART': '1', 01:26:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:26:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:26:50 INFO - 'PWD': '/builds/slave/test-pgo', 01:26:50 INFO - 'SHELL': '/bin/bash', 01:26:50 INFO - 'SHLVL': '1', 01:26:50 INFO - 'TERM': 'linux', 01:26:50 INFO - 'TMOUT': '86400', 01:26:50 INFO - 'USER': 'cltbld', 01:26:50 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1453800394.442181-513517803', 01:26:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:50 INFO - '_': '/tools/buildbot/bin/python'} 01:26:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:50 INFO - Downloading/unpacking blobuploader==1.2.4 01:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:52 INFO - Downloading blobuploader-1.2.4.tar.gz 01:26:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:26:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 01:26:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:26: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 01:26: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 01:26: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 01:26: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 01:26: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 01:26: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 01:26:53 INFO - Downloading docopt-0.6.1.tar.gz 01:26:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 01:26:53 INFO - Installing collected packages: blobuploader, requests, docopt 01:26:53 INFO - Running setup.py install for blobuploader 01:26:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:26:53 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 01:26:53 INFO - Running setup.py install for requests 01:26:53 INFO - Running setup.py install for docopt 01:26:54 INFO - Successfully installed blobuploader requests docopt 01:26:54 INFO - Cleaning up... 01:26:54 INFO - Return code: 0 01:26:54 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 0x7f79c0872f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2f350>, '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 0x1b30250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b306e0>, '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': '7de08804244eb36560b44971000001c3-1453800394.442181-513517803', '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 01:26:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 01:26:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --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 01:26:54 INFO - Using env: {'DISPLAY': ':0', 01:26:54 INFO - 'HOME': '/home/cltbld', 01:26:54 INFO - 'LANG': 'en_US.UTF-8', 01:26:54 INFO - 'LANGUAGE': 'en_US:en', 01:26:54 INFO - 'LOGNAME': 'cltbld', 01:26:54 INFO - 'MAIL': '/var/mail/cltbld', 01:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:54 INFO - 'MOZ_NO_REMOTE': '1', 01:26:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:26:54 INFO - 'NO_EM_RESTART': '1', 01:26:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:26:54 INFO - 'PWD': '/builds/slave/test-pgo', 01:26:54 INFO - 'SHELL': '/bin/bash', 01:26:54 INFO - 'SHLVL': '1', 01:26:54 INFO - 'TERM': 'linux', 01:26:54 INFO - 'TMOUT': '86400', 01:26:54 INFO - 'USER': 'cltbld', 01:26:54 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1453800394.442181-513517803', 01:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:54 INFO - '_': '/tools/buildbot/bin/python'} 01:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:26:54 INFO - Running setup.py (path:/tmp/pip-9B9Vs9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:26:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:26:54 INFO - Running setup.py (path:/tmp/pip-jSuFPF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:26:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:26:54 INFO - Running setup.py (path:/tmp/pip-tRgnoy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:26:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:26:54 INFO - Running setup.py (path:/tmp/pip-fXtIN6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:26:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 01:26:54 INFO - Running setup.py (path:/tmp/pip-GdKv2O-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 01:26:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:26:54 INFO - Running setup.py (path:/tmp/pip-_9Hjmg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:26:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:26:54 INFO - Running setup.py (path:/tmp/pip-HlcaMq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:26:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:26:54 INFO - Running setup.py (path:/tmp/pip-4NS_Tf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:26:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 01:26:54 INFO - Running setup.py (path:/tmp/pip-bYJ6tA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 01:26:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 01:26:54 INFO - Running setup.py (path:/tmp/pip-epMSWp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 01:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:26:55 INFO - Running setup.py (path:/tmp/pip-ALsr1e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:26:55 INFO - Running setup.py (path:/tmp/pip-GSGeUk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:26:55 INFO - Running setup.py (path:/tmp/pip-2pFzXc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:26:55 INFO - Running setup.py (path:/tmp/pip-th8CD6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:26:55 INFO - Running setup.py (path:/tmp/pip-WwkG0N-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 01:26:55 INFO - Running setup.py (path:/tmp/pip-KJiJqG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 01:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 01:26:55 INFO - Running setup.py (path:/tmp/pip-b_b3xv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 01:26:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:26:55 INFO - Running setup.py install for manifestparser 01:26:55 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 01:26:55 INFO - Running setup.py install for mozcrash 01:26:55 INFO - Running setup.py install for mozdebug 01:26:55 INFO - Running setup.py install for mozdevice 01:26:55 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 01:26:55 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 01:26:55 INFO - Running setup.py install for mozfile 01:26:56 INFO - Running setup.py install for mozhttpd 01:26:56 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 01:26:56 INFO - Running setup.py install for mozinfo 01:26:56 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 01:26:56 INFO - Running setup.py install for mozInstall 01:26:56 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 01:26:56 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 01:26:56 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 01:26:56 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 01:26:56 INFO - Running setup.py install for mozleak 01:26:56 INFO - Running setup.py install for mozlog 01:26:56 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 01:26:56 INFO - Running setup.py install for moznetwork 01:26:56 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 01:26:56 INFO - Running setup.py install for mozprocess 01:26:56 INFO - Running setup.py install for mozprofile 01:26:56 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 01:26:56 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 01:26:56 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 01:26:56 INFO - Running setup.py install for mozrunner 01:26:56 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 01:26:56 INFO - Running setup.py install for mozscreenshot 01:26:57 INFO - Running setup.py install for moztest 01:26:57 INFO - Running setup.py install for mozversion 01:26:57 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 01:26:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:26:57 INFO - Cleaning up... 01:26:57 INFO - Return code: 0 01:26:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f79c0872f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2f350>, '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 0x1b30250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b306e0>, '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': '7de08804244eb36560b44971000001c3-1453800394.442181-513517803', '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 01:26:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 01:26:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 01:26:57 INFO - Using env: {'DISPLAY': ':0', 01:26:57 INFO - 'HOME': '/home/cltbld', 01:26:57 INFO - 'LANG': 'en_US.UTF-8', 01:26:57 INFO - 'LANGUAGE': 'en_US:en', 01:26:57 INFO - 'LOGNAME': 'cltbld', 01:26:57 INFO - 'MAIL': '/var/mail/cltbld', 01:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:57 INFO - 'MOZ_NO_REMOTE': '1', 01:26:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:26:57 INFO - 'NO_EM_RESTART': '1', 01:26:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:26:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:26:57 INFO - 'PWD': '/builds/slave/test-pgo', 01:26:57 INFO - 'SHELL': '/bin/bash', 01:26:57 INFO - 'SHLVL': '1', 01:26:57 INFO - 'TERM': 'linux', 01:26:57 INFO - 'TMOUT': '86400', 01:26:57 INFO - 'USER': 'cltbld', 01:26:57 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1453800394.442181-513517803', 01:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:57 INFO - '_': '/tools/buildbot/bin/python'} 01:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:26:57 INFO - Running setup.py (path:/tmp/pip-2CKReX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:26:57 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)) 01:26:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:26:57 INFO - Running setup.py (path:/tmp/pip-sm2S1Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:26:57 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)) 01:26:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:26:57 INFO - Running setup.py (path:/tmp/pip-KXANLV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:26:57 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)) 01:26:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:26:57 INFO - Running setup.py (path:/tmp/pip-vzKgvc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:26:57 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)) 01:26:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 01:26:57 INFO - Running setup.py (path:/tmp/pip-NMFpLh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 01:26:57 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)) 01:26:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:26:57 INFO - Running setup.py (path:/tmp/pip-8YQt7W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:26:57 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)) 01:26:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:26:57 INFO - Running setup.py (path:/tmp/pip-VT_9Xh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:26:58 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)) 01:26:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:26:58 INFO - Running setup.py (path:/tmp/pip-m8bsoS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:26:58 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)) 01:26:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 01:26:58 INFO - Running setup.py (path:/tmp/pip-bOnlu1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 01:26:58 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)) 01:26:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 01:26:58 INFO - Running setup.py (path:/tmp/pip-cGIxX0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 01:26:58 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)) 01:26:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:26:58 INFO - Running setup.py (path:/tmp/pip-1tLvoZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:26:58 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)) 01:26:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:26:58 INFO - Running setup.py (path:/tmp/pip-eEcO9L-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:26:58 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)) 01:26:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:26:58 INFO - Running setup.py (path:/tmp/pip-BWaS1J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:26:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 01:26:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:26:58 INFO - Running setup.py (path:/tmp/pip-Sz84Rp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:26:58 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)) 01:26:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:26:58 INFO - Running setup.py (path:/tmp/pip-7lSwkJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:26:58 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)) 01:26:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 01:26:58 INFO - Running setup.py (path:/tmp/pip-acSfUy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 01:26:58 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)) 01:26:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 01:26:58 INFO - Running setup.py (path:/tmp/pip-RxrHt5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 01:26:58 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)) 01:26:58 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)) 01:26:58 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)) 01:26:58 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)) 01:26:58 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)) 01:26:58 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)) 01:26:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 01:26: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 01:26: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 01:26: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 01:26: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 01:26: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 01:26: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 01:27:00 INFO - Downloading blessings-1.6.tar.gz 01:27:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 01:27:00 INFO - Installing collected packages: blessings 01:27:00 INFO - Running setup.py install for blessings 01:27:00 INFO - Successfully installed blessings 01:27:00 INFO - Cleaning up... 01:27:00 INFO - Return code: 0 01:27:00 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 01:27:00 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 01:27:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 01:27:00 INFO - Reading from file tmpfile_stdout 01:27:00 INFO - Current package versions: 01:27:00 INFO - argparse == 1.2.1 01:27:00 INFO - blessings == 1.6 01:27:00 INFO - blobuploader == 1.2.4 01:27:00 INFO - docopt == 0.6.1 01:27:00 INFO - manifestparser == 1.1 01:27:00 INFO - mozInstall == 1.12 01:27:00 INFO - mozcrash == 0.16 01:27:00 INFO - mozdebug == 0.1 01:27:00 INFO - mozdevice == 0.47 01:27:00 INFO - mozfile == 1.2 01:27:00 INFO - mozhttpd == 0.7 01:27:00 INFO - mozinfo == 0.9 01:27:00 INFO - mozleak == 0.1 01:27:00 INFO - mozlog == 3.1 01:27:00 INFO - moznetwork == 0.27 01:27:00 INFO - mozprocess == 0.22 01:27:00 INFO - mozprofile == 0.27 01:27:00 INFO - mozrunner == 6.11 01:27:00 INFO - mozscreenshot == 0.1 01:27:00 INFO - mozsystemmonitor == 0.0 01:27:00 INFO - moztest == 0.7 01:27:00 INFO - mozversion == 1.4 01:27:00 INFO - psutil == 3.1.1 01:27:00 INFO - requests == 1.2.3 01:27:00 INFO - wsgiref == 0.1.2 01:27:00 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:27:00 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')]} 01:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:00 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')]} 01:27:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:00 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f79c0872f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2f350>, '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 0x1b30250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b306e0>, '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': '7de08804244eb36560b44971000001c3-1453800394.442181-513517803', '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 01:27:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 01:27:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 01:27:00 INFO - Using env: {'DISPLAY': ':0', 01:27:00 INFO - 'HOME': '/home/cltbld', 01:27:00 INFO - 'LANG': 'en_US.UTF-8', 01:27:00 INFO - 'LANGUAGE': 'en_US:en', 01:27:00 INFO - 'LOGNAME': 'cltbld', 01:27:00 INFO - 'MAIL': '/var/mail/cltbld', 01:27:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:00 INFO - 'MOZ_NO_REMOTE': '1', 01:27:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:00 INFO - 'NO_EM_RESTART': '1', 01:27:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:27:00 INFO - 'PWD': '/builds/slave/test-pgo', 01:27:00 INFO - 'SHELL': '/bin/bash', 01:27:00 INFO - 'SHLVL': '1', 01:27:00 INFO - 'TERM': 'linux', 01:27:00 INFO - 'TMOUT': '86400', 01:27:00 INFO - 'USER': 'cltbld', 01:27:00 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1453800394.442181-513517803', 01:27:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:00 INFO - '_': '/tools/buildbot/bin/python'} 01:27:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 01:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 01:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 01:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 01:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 01:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 01:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 01:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 01:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 01:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 01:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 01:27:01 INFO - Cleaning up... 01:27:01 INFO - Return code: 0 01:27:01 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 01:27:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:01 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f79c0872f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2f350>, '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 0x1b30250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b306e0>, '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': '7de08804244eb36560b44971000001c3-1453800394.442181-513517803', '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 01:27:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 01:27:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 01:27:01 INFO - Using env: {'DISPLAY': ':0', 01:27:01 INFO - 'HOME': '/home/cltbld', 01:27:01 INFO - 'LANG': 'en_US.UTF-8', 01:27:01 INFO - 'LANGUAGE': 'en_US:en', 01:27:01 INFO - 'LOGNAME': 'cltbld', 01:27:01 INFO - 'MAIL': '/var/mail/cltbld', 01:27:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:01 INFO - 'MOZ_NO_REMOTE': '1', 01:27:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:01 INFO - 'NO_EM_RESTART': '1', 01:27:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:27:01 INFO - 'PWD': '/builds/slave/test-pgo', 01:27:01 INFO - 'SHELL': '/bin/bash', 01:27:01 INFO - 'SHLVL': '1', 01:27:01 INFO - 'TERM': 'linux', 01:27:01 INFO - 'TMOUT': '86400', 01:27:01 INFO - 'USER': 'cltbld', 01:27:01 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1453800394.442181-513517803', 01:27:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:01 INFO - '_': '/tools/buildbot/bin/python'} 01:27:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:01 INFO - Downloading/unpacking jsonschema 01:27:01 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 01:27:01 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 01:27:01 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 01:27:01 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 01:27:01 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 01:27:01 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 01:27:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:27:03 INFO - Downloading/unpacking functools32 (from jsonschema) 01:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:03 INFO - Downloading functools32-3.2.3-2.tar.gz 01:27:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 01:27:03 INFO - warning: no files found matching '*.txt' 01:27:03 INFO - no previously-included directories found matching 'build' 01:27:03 INFO - no previously-included directories found matching 'dist' 01:27:03 INFO - no previously-included directories found matching '.git*' 01:27:03 INFO - Installing collected packages: jsonschema, functools32 01:27:03 INFO - Running setup.py install for functools32 01:27:03 INFO - warning: no files found matching '*.txt' 01:27:03 INFO - no previously-included directories found matching 'build' 01:27:03 INFO - no previously-included directories found matching 'dist' 01:27:03 INFO - no previously-included directories found matching '.git*' 01:27:03 INFO - Successfully installed jsonschema functools32 01:27:03 INFO - Cleaning up... 01:27:03 INFO - Return code: 0 01:27:03 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 01:27:03 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')]} 01:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:03 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')]} 01:27:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f79c0872f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2f350>, '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 0x1b30250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b306e0>, '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': '7de08804244eb36560b44971000001c3-1453800394.442181-513517803', '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 01:27:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 01:27:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 01:27:03 INFO - Using env: {'DISPLAY': ':0', 01:27:03 INFO - 'HOME': '/home/cltbld', 01:27:03 INFO - 'LANG': 'en_US.UTF-8', 01:27:03 INFO - 'LANGUAGE': 'en_US:en', 01:27:03 INFO - 'LOGNAME': 'cltbld', 01:27:03 INFO - 'MAIL': '/var/mail/cltbld', 01:27:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:03 INFO - 'MOZ_NO_REMOTE': '1', 01:27:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:03 INFO - 'NO_EM_RESTART': '1', 01:27:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:27:03 INFO - 'PWD': '/builds/slave/test-pgo', 01:27:03 INFO - 'SHELL': '/bin/bash', 01:27:03 INFO - 'SHLVL': '1', 01:27:03 INFO - 'TERM': 'linux', 01:27:03 INFO - 'TMOUT': '86400', 01:27:03 INFO - 'USER': 'cltbld', 01:27:03 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1453800394.442181-513517803', 01:27:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:03 INFO - '_': '/tools/buildbot/bin/python'} 01:27:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:04 INFO - Downloading/unpacking flake8 01:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:27:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:27:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 01:27:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:27:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:27:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:27:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:27:06 INFO - no previously-included directories found matching 'docs/_build' 01:27:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:27:06 INFO - Running setup.py install for pep8 01:27:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:27:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:27:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:27:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:27:06 INFO - no previously-included directories found matching 'docs/_build' 01:27:06 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 01:27:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:27:06 INFO - Cleaning up... 01:27:06 INFO - Return code: 0 01:27:06 INFO - Running post-action listener: _resource_record_post_action 01:27:06 INFO - Running post-action listener: _start_resource_monitoring 01:27:06 INFO - Starting resource monitoring. 01:27:06 INFO - ##### 01:27:06 INFO - ##### Running install step. 01:27:06 INFO - ##### 01:27:06 INFO - Running pre-action listener: _resource_record_pre_action 01:27:06 INFO - Running main action method: install 01:27:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 01:27:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 01:27:07 INFO - Reading from file tmpfile_stdout 01:27:07 INFO - Detecting whether we're running mozinstall >=1.0... 01:27:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 01:27:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 01:27:07 INFO - Reading from file tmpfile_stdout 01:27:07 INFO - Output received: 01:27:07 INFO - Usage: mozinstall [options] installer 01:27:07 INFO - Options: 01:27:07 INFO - -h, --help show this help message and exit 01:27:07 INFO - -d DEST, --destination=DEST 01:27:07 INFO - Directory to install application into. [default: 01:27:07 INFO - "/builds/slave/test-pgo"] 01:27:07 INFO - --app=APP Application being installed. [default: firefox] 01:27:07 INFO - mkdir: /builds/slave/test-pgo/build/application 01:27:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 01:27:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 01:27:21 INFO - Reading from file tmpfile_stdout 01:27:21 INFO - Output received: 01:27:21 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 01:27:21 INFO - Running post-action listener: _resource_record_post_action 01:27:21 INFO - ##### 01:27:21 INFO - ##### Running run-tests step. 01:27:21 INFO - ##### 01:27:21 INFO - Running pre-action listener: _resource_record_pre_action 01:27:21 INFO - Running main action method: run_tests 01:27:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 01:27:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 01:27:21 INFO - Python 2.7.3 01:27:21 INFO - Return code: 0 01:27:21 INFO - grabbing minidump binary from tooltool 01:27:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:21 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 0x1b2f350>, '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 0x1b30250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b306e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 01:27:21 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 01:27:21 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 01:27:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:27:21 INFO - Return code: 0 01:27:21 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 01:27:21 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 01:27:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 01:27:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 01:27:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 01:27:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 01:27:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 01:27:22 INFO - Return code: 0 01:27:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 01:27:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --webServer localhost 01:27:22 INFO - Using env: {'DISPLAY': ':0', 01:27:22 INFO - 'HOME': '/home/cltbld', 01:27:22 INFO - 'LANG': 'en_US.UTF-8', 01:27:22 INFO - 'LANGUAGE': 'en_US:en', 01:27:22 INFO - 'LOGNAME': 'cltbld', 01:27:22 INFO - 'MAIL': '/var/mail/cltbld', 01:27:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 01:27:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 01:27:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:22 INFO - 'MOZ_NO_REMOTE': '1', 01:27:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 01:27:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:22 INFO - 'NO_EM_RESTART': '1', 01:27:22 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:27:22 INFO - 'PWD': '/builds/slave/test-pgo', 01:27:22 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 01:27:22 INFO - 'SHELL': '/bin/bash', 01:27:22 INFO - 'SHLVL': '1', 01:27:22 INFO - 'TERM': 'linux', 01:27:22 INFO - 'TMOUT': '86400', 01:27:22 INFO - 'USER': 'cltbld', 01:27:22 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1453800394.442181-513517803', 01:27:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:22 INFO - '_': '/tools/buildbot/bin/python'} 01:27:22 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost'] with output_timeout 3600 01:27:23 INFO - mozversion INFO | application_buildid: 20160125070233 01:27:23 INFO - mozversion INFO | application_changeset: 3bc504ea394fe54d1dbb3afc86534b25c36432bd 01:27:23 INFO - mozversion INFO | application_display_name: Nightly 01:27:23 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:27:23 INFO - mozversion INFO | application_name: Firefox 01:27:23 INFO - mozversion INFO | application_remotingname: firefox 01:27:23 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:27:23 INFO - mozversion INFO | application_vendor: Mozilla 01:27:23 INFO - mozversion INFO | application_version: 45.0 01:27:23 INFO - mozversion INFO | platform_buildid: 20160125070233 01:27:23 INFO - mozversion INFO | platform_changeset: 3bc504ea394fe54d1dbb3afc86534b25c36432bd 01:27:23 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:27:23 INFO - mozversion INFO | platform_version: 45.0 01:27:23 INFO - 2016-01-26 01:27:23,007 DEBUG : using testdate: 1453800443 01:27:23 INFO - 2016-01-26 01:27:23,007 DEBUG : actual date: 1453800443 01:27:23 INFO - 2016-01-26 01:27:23,016 INFO : starting webserver on 'localhost:41819' 01:27:23 INFO - 2016-01-26 01:27:23,017 INFO : Starting test suite talos-linux64-ix-003 01:27:23 INFO - 2016-01-26 01:27:23,017 INFO : Starting test dromaeo_css 01:27:23 INFO - 2016-01-26 01:27:23,017 DEBUG : operating with platform_type : linux_ 01:27:23 INFO - 2016-01-26 01:27:23,017 INFO : Initialising browser for dromaeo_css test... 01:27:23 INFO - 2016-01-26 01:27:23,030 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTvz4w0/profile http://localhost:41819/getInfo.html 01:27:27 INFO - 2016-01-26 01:27:27,049 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:27:27 INFO - 2016-01-26 01:27:27,050 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:27:27 INFO - 2016-01-26 01:27:27,050 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:27:27 INFO - 2016-01-26 01:27:27,050 DEBUG : BROWSER_OUTPUT: __metrics 01:27:27 INFO - 2016-01-26 01:27:27,340 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:27:28 INFO - 2016-01-26 01:27:28,299 INFO : Browser initialized. 01:27:28 INFO - 2016-01-26 01:27:28,299 INFO : Running cycle 1/1 for dromaeo_css test... 01:27:28 INFO - 2016-01-26 01:27:28,299 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTvz4w0/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 01:27:40 INFO - 2016-01-26 01:27:40,099 DEBUG : BROWSER_OUTPUT: RSS: Main: 134377472 01:27:40 INFO - 2016-01-26 01:27:40,100 DEBUG : BROWSER_OUTPUT: 01:28:30 INFO - 2016-01-26 01:28:30,890 DEBUG : BROWSER_OUTPUT: 1453800510888 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] 01:30:39 INFO - 2016-01-26 01:30:39,399 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41819/tests/dromaeo/cssquery-ext.html) 01:30:40 INFO - 2016-01-26 01:30:40,625 DEBUG : BROWSER_OUTPUT: RSS: Main: 255139840 01:30:40 INFO - 2016-01-26 01:30:40,625 DEBUG : BROWSER_OUTPUT: 01:33:42 INFO - 2016-01-26 01:33:42,728 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/dromaeo/cssquery-ext.html (next: http://localhost:41819/tests/dromaeo/cssquery-jquery.html) 01:33:43 INFO - 2016-01-26 01:33:43,290 DEBUG : BROWSER_OUTPUT: RSS: Main: 224731136 01:33:43 INFO - 2016-01-26 01:33:43,290 DEBUG : BROWSER_OUTPUT: 01:36:45 INFO - 2016-01-26 01:36:45,049 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41819/tests/dromaeo/cssquery-mootools.html) 01:36:45 INFO - 2016-01-26 01:36:45,615 DEBUG : BROWSER_OUTPUT: RSS: Main: 211177472 01:36:45 INFO - 2016-01-26 01:36:45,615 DEBUG : BROWSER_OUTPUT: 01:39:47 INFO - 2016-01-26 01:39:47,301 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41819/tests/dromaeo/cssquery-prototype.html) 01:39:47 INFO - 2016-01-26 01:39:47,883 DEBUG : BROWSER_OUTPUT: RSS: Main: 202461184 01:39:47 INFO - 2016-01-26 01:39:47,883 DEBUG : BROWSER_OUTPUT: 01:42:50 INFO - 2016-01-26 01:42:50,227 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41819/tests/dromaeo/cssquery-yui.html) 01:42:50 INFO - 2016-01-26 01:42:50,794 DEBUG : BROWSER_OUTPUT: RSS: Main: 200589312 01:42:50 INFO - 2016-01-26 01:42:50,794 DEBUG : BROWSER_OUTPUT: 01:45:53 INFO - 2016-01-26 01:45:53,062 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/dromaeo/cssquery-yui.html (next: http://localhost:41819/tests/dromaeo/cssquery-dojo.html) 01:45:53 INFO - 2016-01-26 01:45:53,310 DEBUG : BROWSER_OUTPUT: RSS: Main: 243564544 01:45:53 INFO - 2016-01-26 01:45:53,310 DEBUG : BROWSER_OUTPUT: 01:45:53 INFO - 2016-01-26 01:45:53,311 DEBUG : BROWSER_OUTPUT: __start_tp_report 01:45:53 INFO - 2016-01-26 01:45:53,312 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 01:45:53 INFO - 2016-01-26 01:45:53,312 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 01:45:53 INFO - 2016-01-26 01:45:53,312 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 01:45:53 INFO - 2016-01-26 01:45:53,313 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3312.205070783012;3395.7622966392364;3416.9658303416973;3420.897373078807;3437.0204491719855;5457.972710136449;5640.8589785255335;5643.6049476536655;5645.5483561315095;5647.09646456567;5642.887142257153;5654.406287339822;5683.403242659523;5692.715364231783;5700.173474846145;5065.4174769901565;5102.821401250957;5135.871603209912;5172.40517340505;5256.8685782855355;5214.061468935609;5229.712365819878;5231.712255825928;5237.266782650432;5237.319148510707;5168.9224661630105;5240.318758561381;5248.475152484759;5249.1338929076655;5249.763760630781;5044.697318160904;5047.217681259411;5142.331496905415;5173.198154286091;5191.273221748958;5136.43499215086;5157.303763991888;5210.817621383271;5219.399769026572;5261.421243663194;5307.920381194285;5434;5482.890342193134;5505.119180931032;5581;6087.847803804896;6132.570720049598;6181.103739957726;6191.040388739752;6193.783217587363;6176.197094377747;6193;6226.159468471744;6239.001759718423;6244.313125556185;5510.256115424447;5545.500904918576;5630.662160270356;5765.798197063083;5776.133579962997;6155.72299246535;6221.657808820468;6237.4698150656795;6241.687915604201;6245.125682404467;6006.099085137177;6015.1278064681255;6025.517958563392;6042.6072305300895;6047.879042419127;6047.516198704093;6087.1173679816175;6115.571909966348;6120.357362476965;6137.438273608292;6099.603525770811;6121.96939015311;6127;6130.111133885562;6134.78528251509;6075.5747097703625;6097.603655762361;6112.297085835097;6119.84700382494;6120.387961203933;4407.118576284756;4539.432570928634;4590.357349971006;4656.254999200111;4695.835645752382;4165.333546632524;4167.06241095751;4177.060161463646;4179.540250572434;4191.434156388909;5680.40355762639;5812.186293918855;5820.097884827858;5820.35976042635;5825.242718446575;5936.347001829881;5956.2852457704485;6013.458788709037;6050.6369617823075;6053.576249662481;5743.655380677173;5789.478946894799;5795.304563452412;5805.88388232233;5811.041178205549;5286.709230992333;5287.894242115136;5290.470952904679;5291.682499050069;5292.285541451854;6036.366181550961;6124.35694252106;6130.673316708229;6233.906491402633;6242.843928901813;5997.190379298739;6080.148779170831;6105.725242364193;6126.754929802758;6148.1699970503405;6066.029435290332;6205.906911396423;6235.127082208403;6288;6298.937010630018;5829.09649004405;5890.234269545103;5925.762969481172;5958.076498142795;5977.491913187428;6087;6156.076588511759;6185.505159587132;6195.19462469876;6196.938030619636;5689;5792.4497172768515;5996.820095397145;6013;6015.608985415934;5773.364929857628;5851.707414629166;5853.473187412983;5858.355580886185;5892.263467066617;5869.002269614261;5891.116332550152;5893.734781934909;5895.380984996514;5895.67573783446;5730.1977723119635;5798.623089499169;5831.067029275296;5999.730012149551;6000.309964353981;5799.82600521985;5855.8536036597725;5863.794767183128;5876.324222714443;5893.351731309638 01:45:53 INFO - 2016-01-26 01:45:53,315 DEBUG : BROWSER_OUTPUT: |1;ext.html;571.3087164530917;608.5527137553897;615.3938370704857;619.2507066449596;630.9872654389708;33075.33849323012;41084.17831643369;41533.96165095871;41538.37692434616;41564;46210.53789462104;47021.29468057982;47054;47078.52921470784;47144.528554714445;7939.960300198491;8046.356291496666;8059.153788852174;8996.95501522493;9141.817163656722;10277.486125693686;10504.159667226604;10509.527071281811;10525.78948421031;10528;10380.792384152312;10458.267921245515;10474.947625261862;10475.633352832652;10480.737981550446;10375.273730838844;10440.47797610124;10450.372977621368;10452.529636166315;10456.581736730484;10255.384676919408;10408.479576021244;10416.062554370068;10720.624778132806;10745.08666763326;42613.57386426158;43023.784881075706;43066.56933430657;43103.56896431027;43105;3236.158598764315;3582.3730847101915;3584.8028358440274;3694.5935947045805;4141.979290103561;32396.190095247573;32508.512372314435;32530;32764.508532372034;32954.17614559631;4146.212219678251;4176.2900306947895;4292.806823692941;4295.978520107411;4307.987622908614;344877;347215;347462.2626886875;348121;348981;57491.42508574944;58228;58514.41485585175;59103.70448147818;62595;59181.112283315786;59450;59675.70162149248;59694.70152649209;59695.40304596898;17039.48881533556;17115.315387384366;17196.31214751421;17202.053887036083;17202.483925482255;13169.53906613264;13202.801957970638;13240.20558766477;13276.867231327564;13283.535076272477;20709.792902071087;21522;21558.461038474165;21589.352319430127;21620;7866.528008319519;8016.31861291785;8022.3983201260135;8066.7579972600915;8089.2315230053055;1043.5564884923876;1058.920580956416;1074.6453670288786;1076.6662334676275;1132.3092913322866;29167.270818229714;29467.85266073656;29962;30252.24369390783;30522.08433746991;1107.213878146525;1143.6683361824978;1169.1640477058943;1201.963941081769;1235.4440501774238;14607;14894.329755161043;16849.91575042117;17495.825041749675;17588.208530616153;424613;431666;431800;432194;432621;57734.71132644394;58192;58315;58554.70722646359;58603.70698146567;1405.3886559346718;1441.43063489923;1444.0252829340154;1452.985470145285;1455.2796365798997;26991.46017079687;33052.33895322032;33150.17124571905;33265.50101748524;33872;51104.23343649923;51695.67369468308;51707.741461292455;51720.741396292775;51720.96558068893;328488;330664;331535.0539483819;331578;332291;29510;29690.703092968793;33806.661933380354;44092.77953610211;44303.55696442995;48320;49563.504364957334;50030.24954625756;50262;51217.23174152465;40726;41361;41502.792486037375;42177.578224218596;42324.36513452239;38667.2266554673;41895.581044189166;42373.576264238196;42944.35583466313;43169.35245971376;40761.59238407578;40913.59086409098;41949;42065.369019465346;42073.579264208194;35526.93419197428;36116;38359.04102397463;40930.386044209954;41312.58687413087;27848.443031139668;28364.14907552694;28452.85773571119;28637.14088577346;28771.424571508032 01:45:53 INFO - 2016-01-26 01:45:53,316 DEBUG : BROWSER_OUTPUT: |2;jquery.html;383.8675656898371;386.9709771767118;390.17867389145846;391.63773509503704;393.6437524040744;15144.242787860618;16414.917925410373;17195.398161064364;17689.203985820637;17704.91147544262;17406.912965435156;17546.561335966577;17672.734908976374;18399.264029438807;18399.90800045998;5557.027520183961;5670.319561652587;5673.092305231164;5678.375378708339;5683.488486036256;4191.517975432834;4293.806778694966;5651.5761317901115;6041.214642096521;6253.405926436982;6101.237345331833;6124.922991315424;6164.753409863622;6195.31851496335;6207.068939659042;6223.844403889893;6283.183186185766;6314.494840412779;6317.3366796486625;6317.905231421532;5509.898020395897;5515.979543784372;5593.161025846156;5599.440055994409;5599.7480113394995;21763;21821.890890545605;22087.337379878467;22115.33653990383;22237.110515579356;2098.7376577927757;2160.60028894655;2262.988685056581;2338.9064437422485;2421.176799888029;13457;13473.865261347359;13789.586312410644;13794.448222071105;14293.356798944073;1805.5666640006434;1836.9357072502398;1872.775266967959;1919.4721451600783;1966.8426525878037;62781.372186278466;63124;63970;66472.33527664661;66978;10250;10534.73663158412;10672.519736611872;10708.19688523364;10759.462026898624;14337.35481903317;14437.278136093153;14480.927595361956;14506.419743210365;14517.346719397652;8246.711365102192;8318.085010648823;8365.163483651586;8500.914990850013;8554.871676924851;8138.064122625974;8154.714584989497;8171.79570510742;8230.09468958414;8240.670373185125;6341.365863413714;6346.428821406095;6383.712732926937;6404.775832845921;6479.157709497735;1739.4520725971531;1823.8358547730768;1841.0978620475871;1871.8970456624745;1882.5952420229717;912.4023764434306;966.8163049020663;990.5245482168599;1014.452195814252;1169.6666450061691;15153.772693409608;17054.74417883733;17325.826741732675;17766;17806.109694515224;1043.9008294212038;1056.4664844253602;1098.9889301842325;1106.2422240765102;1110.0564520157964;10110.494475276208;10364.326318789255;10377.948110259551;10432.478376081166;10538;654803;681452;693427;698763;701273;75865.6206718963;76677.08307292251;81315.5934220337;83975;87464;1162.7151347919983;1169.1582060916128;1221.6457227403953;1240.2124650846777;1254.3790823542222;16180.190990450667;16188.352465901233;16266.67466650684;16270.674586507967;16660.250288736792;36811.07972300714;37538;37705;37811.81094094512;37972.240555189295;44859.327110094026;45874.54125458703;45948.540514594424;46463.53536464727;47299.29051064168;9735.367201131903;9757.80484390294;10030.64892728751;10183.33808302458;10185.032421919906;11703.648890533297;11823.054155667354;13039.478420863059;17729.468115956028;18116.547086322946;14950.775738364151;15183.544493665207;15243.3902643893;15315.234238287818;15655.530334089995;14204.502842400467;14585.489507867174;14730.189839558927;15017.324220410326;15046;11672.766544669228;14986.175760333286;15454.768178477107;15524.456644017406;16007.199640018185;9947.253955953187;10206.081452669296;10294.588216471257;10295.279330447096;10398.532066057196;14766.557003289918;14919;15164.924175379052;15337.539873803804;15503.689926201638 01:45:53 INFO - 2016-01-26 01:45:53,317 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1499.6250937265684;1553.2699631173348;1595.6170519075426;1724.1379310344819;1849.9815001849977;7512.962435187823;8000.359971202304;8005.239502247292;8012.038555373349;8020.438569300137;7184.317489838457;7397.149327827301;7585.848283034336;7808.258215469521;7829.17793631669;2255.2783109404995;2315.69895913531;2336.077249486452;2341.028473183627;2341.894614742332;2032.8780273183656;2329.871857047862;2342.8008619267384;2381.416552944531;2391.80865530758;2068.6276470235352;2296.7358753743283;2331.172433786007;2334.533093381322;2336.1122773346187;2278.7037685100913;2279.0541925101147;2287.839851210416;2323.7327707313707;2339.181286549711;2193.429708275844;2197.351781224527;2197.8571392859412;2217.0245092159557;2217.6119179143748;8084.474509156945;8180.754577362689;8185.181481851767;8187.1812818718245;8194.795130121734;4858.1983972644475;4947.851564453073;4950.727709975949;4958.504149585049;4960.057589058068;3015.7738169637146;3065.6934306569165;3078.599782028344;3133.5926329577237;3230.305484320859;1033.2302434686133;1039.3088596083596;1060.7030031591166;1069.7379142110155;1071.5124618298732;11403.315801051964;11552.711182220428;11558.190926635223;11559.884401155965;11580.6525804226;4210.642095421862;4229.407882896397;4331.436913201263;4338.067315527177;4341.739495630272;9327;9358.4384936904;9390.577424015935;9397.530123493798;9397.90602093984;4797.880052998633;4886.828960986349;4905.23968784839;4927.507249275044;4935.802567897315;4395.824167033346;4403.559644035571;4406.889827754332;4415.492218394861;4471.083427897273;3636.109153257416;3652.123490362347;3655.1775850433974;3670.8531658733887;3686.1337585667347;1042.1818872185297;1055.3931489393628;1075.4031512510476;1078.8543546621263;1091.061686949223;1013.4426065663855;1016.9898301017043;1044.5143008501084;1088.3198001249218;1300.8699130087105;7825.334846538108;7904.644291006918;7908.7627371177805;7909.24862138096;7913.683452661944;661.4675186474838;674.6660403100399;676.3473248315399;678.9490788190906;818.1409520003972;6056.939430605637;6100.786472473442;6108.3280839107665;6121.908171377433;6130.080487926757;9553.570089345858;9777.071178238057;10014.24893133018;10030.147437467751;10032.849507257397;9795.951020244853;9813.067758562926;9915.702528924;9931.751706207402;9932.205423566098;7191.34122159898;7487.7004919803685;7746.612669366622;7762.340201082971;7765.495242809199;7479.252074792477;7589.241075892367;7594.392448603988;7616.504927179715;7620.69517219305;8744.650213991368;8794.780130496789;8805.16350946659;9021.278297736035;9023.954880225558;8756.343274254585;8970.596323165604;9171.31215158879;9176.44941303524;9190.448573085636;7345;7397.334239918433;7411.777646670608;7420.888686669597;7421.109466863795;7881.211878812073;7973.8405231896195;7999.280064794197;8007.119216886254;8077.313428358655;7509;7566.6973321068745;7617.314441700273;7617.542947423172;7705.30652241301;6529.575577587441;7319.963400183178;7374.262573742797;7439.851202975802;7484.214157513383;7495.512791668524;7545.849083018418;7550.811229719301;7587.393008559412;7618.657160427683;6723.327667233433;6752.493562982698;6759.290274521301;6769.458443324424;6769.93230067693;6612.272650008399;6629.668516574248;6636.502262330441;6636.966815165893;6640.90038649411 01:45:53 INFO - 2016-01-26 01:45:53,319 DEBUG : BROWSER_OUTPUT: |4;prototype.html;352.70020482589797;354.6683850599688;355.135245676777;359.98560057597695;361.54987041133774;6092.969535152323;6141.969290153548;6151.138840562314;6155.6306621602735;6187.4431301182885;6068.514518838493;6073.726682299296;6085.269767627891;6095.451409373156;6096.969515152418;4672.836450724225;4713.717176969376;4738.668293219476;4748.643851711118;4754.762261886909;4717.952820471785;4720.268358404435;4722.645801564897;4723.291506274052;4724.645651576128;4660.231061874787;4691.202495575761;4698.624110071186;4699.459562150362;4708.529147085298;4514.6388288936805;4612.561806628348;4617.792199351037;4700.459447163552;4701.858944231679;4132.91734165315;4151.83392664293;4154.293770059097;4160.7087503874745;4160.750354978711;5598.272224610815;5713.714314284269;5782.88434231313;5794.043982742843;5801.883962320772;5372.274742909696;5393.1370980643305;5505.174223866412;5510.91733623992;5521.585881058937;6428.903566446507;6437.742490300382;6444.677766111722;6445.613263204223;6458.128152699373;4620.722756634613;4621;4623.953760462386;4623.953760462386;4636.976815115903;6490.318516555741;6647.60114393138;6716;6716.83207919801;6721.966390168018;4614.423197100363;4641.071785642885;4641.327007583882;4648.209804333272;4649.418822647169;4670.089332580114;4702.553257440538;4703.05938812232;4704.200285951397;4714.316424118484;5285.207218917193;5402.729863506809;5412.431694672003;5413.891722165535;5453.836384908459;6521.250056243498;6522.119513865662;6525.2920038184575;6525.412712855771;6531.0856480092825;1634.8610368118843;1667.9166041697868;1669.2404955745108;1669.3906724045637;1670.9222551454486;1106.9667909962714;1113.5100555755441;1116.6761639124725;1134.8241022641503;1144.4277861069465;6310.6213627182515;6311.116443697886;6312.210973628296;6317.652529110943;6319.304876463585;6682.665866706645;6683.732650693918;6686.398224159849;6695.296993815677;6700.195976482853;6176.7838125666285;6250.124982502453;6270.6864656766975;6273.686315684197;6281.152044474029;3598.5501812273465;3620.3664358737115;3625.148090198801;3625.3474374612297;3626.8911932642063;6881.346272104142;6882.070920425678;6889.311068893071;6891.689873955684;6892.310768923167;6680.866382672417;6757.831054223684;6798.184217893884;6937.895931561031;6961.095057642474;6955.61744104069;6967.860642787217;6973.616451095137;6975.930240697731;6996.370326670624;6528.543001989815;6737.86524269502;6739.393454589111;6750.0212469192675;6753.763618273337;6655.633940133339;6682;6693.163354580677;6704.597724136567;6705.161854768154;6965.651717414008;6974.372306492237;6974.860502790018;6977.406920411822;6985.301469852974;4603.93094103582;4656.62746980234;4691.9061618766755;4692.507286734844;4693.366395536559;6758;6767.729290828312;6772.966135169293;6774.8645027100165;6776.15298087739;6518.869622607616;6569.244536878129;6571.769988050395;6571.901421478586;6571.967140164268;6444.323346048597;6542.443892269211;6547.181602299713;6552.868942621026;6559.180102487189;6463.062855885776;6467.902981455188;6480.25477070143;6482.092507048923;6482.902756458562;6595.340465953366;6596.736130554724;6604.636744979072;6611.239707433707;6614.536982411185;6292.244930608356;6391.680415979089;6416.486681065597;6422.518311126591;6462.870742585216 01:45:53 INFO - 2016-01-26 01:45:53,319 DEBUG : BROWSER_OUTPUT: |5;yui.html;809.1180613131685;829.746927187208;886.2555453419127;910.4400793511988;966.1304825656912;14101.294935253234;14763.630909227275;14794.408223671067;14800.6299842504;14808.481703140393;13169.144005639651;13246.735065298688;13284.269365184911;13305.467781288737;13315.267660278705;754.8716718157926;757.746155038063;886.2599729226104;890.3144578674417;902.1114202510522;889.5018789477881;901.8647202919582;906.2478143141177;907.8093600343935;908.2733812949647;794.6741835847304;930.9348345615809;944.032366824004;944.4286206844863;944.7307517357555;777.4091690315373;924.1220840201802;926.8980412154658;927.9628814847398;928.5496534180951;679.2697849811473;680.0988689985788;683.9110915580943;683.9179298484164;684.7534887440568;13492.797608035888;13740.519081832188;13814.93092534541;13880;13955.511557095453;12799.872001280055;13133.671658208621;13167.078304518687;13314.134581252187;13669.316534173251;10470.685879423629;10528.473576321154;10647.893521064845;10953.397563134024;10955;3907.8046097695287;3911.3546264866263;3921.4706014687936;3926.5288165420334;3926.9410958835642;49872.75063624695;50305.24542131871;51406;51710.48289517094;51807.22289165665;11002.50488728009;11050.502727377287;11229.55081796719;11363.715907102223;11491.54033838637;20087.799122008593;20124.39626811198;20128.19487220495;20164.495887602632;20166.19335226604;7495.962520187364;7608.467407281437;7686.116096648845;7696.6536505857375;8010.078840933367;4157.168566286755;4236.703430759879;4313.051128751732;4336.956630433719;4345.760983145958;3042.1329920972416;3075.815451072943;3083.768717346208;3084.9383012339626;3090.3510262844607;534.6311045378676;554.6062295769967;554.9667019978814;559.5216090242865;566.3684991234744;621.6922623301494;686.6944209826579;701.9578825270501;706.2972342519226;717.8851383778571;8529.701460448854;9051.321150913707;9060.682876099305;9408.388454750419;9552;623.3112410786107;646.8997305417668;682.2938258902012;705.3616477088141;720.8342081321325;4166.250474459119;4206.600372964564;4322.438083049184;4353.956460435355;4401.559844015573;62038;62792.686036569525;62847.68576157181;63077;64040.67979660072;56243.43756562382;57069.714651426475;57341.713291433276;57407.85184296374;57640;50597.24104138441;50718.23922641237;51088.489115109114;51617.225741613904;51960.48039519632;8794.296456283353;8822.51476168817;8899.955500222457;9402.811943761224;9485.430874147298;36850.8157459211;37681.81159094187;38022;38278.61721382751;38445;49176.2623560654;49808.25287620761;50514;50549;50995.490045099075;11567.421628918688;11874.940625296818;11879.524819007143;12103.576374826838;12134.393280335771;12356.25862448256;12453.501859925502;12738.299393533445;12765.93617031909;13605;10632;10852.240343175901;11232.213745038096;11412.543498259976;11414.315141091562;10619.36283822973;10798.892011079788;10822.188335874998;11124.388158651353;11582.131340149353;9806.705798826046;9875.901240987498;10266.435346056038;10788.784224315626;11018.834717479083;9141.680041198526;9445.716628501155;9593.472359020321;9866.260030497884;9906.603735850485;9649.807003860024;9766.56050477716;9884.357516761387;10126.544305506122;10230.232732545237 01:45:53 INFO - 2016-01-26 01:45:53,319 DEBUG : BROWSER_OUTPUT: __end_tp_report 01:45:53 INFO - 2016-01-26 01:45:53,319 DEBUG : BROWSER_OUTPUT: __start_cc_report 01:45:53 INFO - 2016-01-26 01:45:53,319 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,993 01:45:53 INFO - 2016-01-26 01:45:53,319 DEBUG : BROWSER_OUTPUT: __end_cc_report 01:45:53 INFO - 2016-01-26 01:45:53,319 DEBUG : BROWSER_OUTPUT: __startTimestamp1453801553310__endTimestamp 01:45:53 INFO - 2016-01-26 01:45:53,320 DEBUG : BROWSER_OUTPUT: 01:45:53 INFO - 2016-01-26 01:45:53,320 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 01:45:53 INFO - 2016-01-26 01:45:53,320 DEBUG : BROWSER_OUTPUT: Number of tests: 6 01:45:53 INFO - 2016-01-26 01:45:53,320 DEBUG : BROWSER_OUTPUT: 01:45:53 INFO - 2016-01-26 01:45:53,320 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5642.36 Median:5831.07 stddev:628.89 (10.8%) stddev-sans-first:603.66 01:45:53 INFO - 2016-01-26 01:45:53,320 DEBUG : BROWSER_OUTPUT: Values: 3312.2 3395.8 3417.0 3420.9 3437.0 5458.0 5640.9 5643.6 5645.5 5647.1 5642.9 5654.4 5683.4 5692.7 5700.2 5065.4 5102.8 5135.9 5172.4 5256.9 5214.1 5229.7 5231.7 5237.3 5237.3 5168.9 5240.3 5248.5 5249.1 5249.8 5044.7 5047.2 5142.3 5173.2 5191.3 5136.4 5157.3 5210.8 5219.4 5261.4 5307.9 5434.0 5482.9 5505.1 5581.0 6087.8 6132.6 6181.1 6191.0 6193.8 6176.2 6193.0 6226.2 6239.0 6244.3 5510.3 5545.5 5630.7 5765.8 5776.1 6155.7 6221.7 6237.5 6241.7 6245.1 6006.1 6015.1 6025.5 6042.6 6047.9 6047.5 6087.1 6115.6 6120.4 6137.4 6099.6 6122.0 6127.0 6130.1 6134.8 6075.6 6097.6 6112.3 6119.8 6120.4 4407.1 4539.4 4590.4 4656.3 4695.8 4165.3 4167.1 4177.1 4179.5 4191.4 5680.4 5812.2 5820.1 5820.4 5825.2 5936.3 5956.3 6013.5 6050.6 6053.6 5743.7 5789.5 5795.3 5805.9 5811.0 5286.7 5287.9 5290.5 5291.7 5292.3 6036.4 6124.4 6130.7 6233.9 6242.8 5997.2 6080.1 6105.7 6126.8 6148.2 6066.0 6205.9 6235.1 6288.0 6298.9 5829.1 5890.2 5925.8 5958.1 5977.5 6087.0 6156.1 6185.5 6195.2 6196.9 5689.0 5792.4 5996.8 6013.0 6015.6 5773.4 5851.7 5853.5 5858.4 5892.3 5869.0 5891.1 5893.7 5895.4 5895.7 5730.2 5798.6 5831.1 5999.7 6000.3 5799.8 5855.9 5863.8 5876.3 5893.4 01:45:53 INFO - 2016-01-26 01:45:53,320 DEBUG : BROWSER_OUTPUT: 01:45:53 INFO - 2016-01-26 01:45:53,322 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:54950.40 Median:19554.65 stddev:97703.36 (499.6%) stddev-sans-first:97892.13 01:45:53 INFO - 2016-01-26 01:45:53,322 DEBUG : BROWSER_OUTPUT: Values: 571.3 608.6 615.4 619.3 631.0 33075.3 41084.2 41534.0 41538.4 41564.0 46210.5 47021.3 47054.0 47078.5 47144.5 7940.0 8046.4 8059.2 8997.0 9141.8 10277.5 10504.2 10509.5 10525.8 10528.0 10380.8 10458.3 10474.9 10475.6 10480.7 10375.3 10440.5 10450.4 10452.5 10456.6 10255.4 10408.5 10416.1 10720.6 10745.1 42613.6 43023.8 43066.6 43103.6 43105.0 3236.2 3582.4 3584.8 3694.6 4142.0 32396.2 32508.5 32530.0 32764.5 32954.2 4146.2 4176.3 4292.8 4296.0 4308.0 344877.0 347215.0 347462.3 348121.0 348981.0 57491.4 58228.0 58514.4 59103.7 62595.0 59181.1 59450.0 59675.7 59694.7 59695.4 17039.5 17115.3 17196.3 17202.1 17202.5 13169.5 13202.8 13240.2 13276.9 13283.5 20709.8 21522.0 21558.5 21589.4 21620.0 7866.5 8016.3 8022.4 8066.8 8089.2 1043.6 1058.9 1074.6 1076.7 1132.3 29167.3 29467.9 29962.0 30252.2 30522.1 1107.2 1143.7 1169.2 1202.0 1235.4 14607.0 14894.3 16849.9 17495.8 17588.2 424613.0 431666.0 431800.0 432194.0 432621.0 57734.7 58192.0 58315.0 58554.7 58603.7 1405.4 1441.4 1444.0 1453.0 1455.3 26991.5 33052.3 33150.2 33265.5 33872.0 51104.2 51695.7 51707.7 51720.7 51721.0 328488.0 330664.0 331535.1 331578.0 332291.0 29510.0 29690.7 33806.7 44092.8 44303.6 48320.0 49563.5 50030.2 50262.0 51217.2 40726.0 41361.0 41502.8 42177.6 42324.4 38667.2 41895.6 42373.6 42944.4 43169.4 40761.6 40913.6 41949.0 42065.4 42073.6 35526.9 36116.0 38359.0 40930.4 41312.6 27848.4 28364.1 28452.9 28637.1 28771.4 01:45:53 INFO - 2016-01-26 01:45:53,322 DEBUG : BROWSER_OUTPUT: 01:45:53 INFO - 2016-01-26 01:45:53,322 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:33629.96 Median:1877.69 stddev:111909.60 (5960.0%) stddev-sans-first:112195.69 01:45:53 INFO - 2016-01-26 01:45:53,322 DEBUG : BROWSER_OUTPUT: Values: 383.9 387.0 390.2 391.6 393.6 15144.2 16414.9 17195.4 17689.2 17704.9 17406.9 17546.6 17672.7 18399.3 18399.9 5557.0 5670.3 5673.1 5678.4 5683.5 4191.5 4293.8 5651.6 6041.2 6253.4 6101.2 6124.9 6164.8 6195.3 6207.1 6223.8 6283.2 6314.5 6317.3 6317.9 5509.9 5516.0 5593.2 5599.4 5599.7 21763.0 21821.9 22087.3 22115.3 22237.1 2098.7 2160.6 2263.0 2338.9 2421.2 13457.0 13473.9 13789.6 13794.4 14293.4 1805.6 1836.9 1872.8 1919.5 1966.8 62781.4 63124.0 63970.0 66472.3 66978.0 10250.0 10534.7 10672.5 10708.2 10759.5 14337.4 14437.3 14480.9 14506.4 14517.3 8246.7 8318.1 8365.2 8500.9 8554.9 8138.1 8154.7 8171.8 8230.1 8240.7 6341.4 6346.4 6383.7 6404.8 6479.2 1739.5 1823.8 1841.1 1871.9 1882.6 912.4 966.8 990.5 1014.5 1169.7 15153.8 17054.7 17325.8 17766.0 17806.1 1043.9 1056.5 1099.0 1106.2 1110.1 10110.5 10364.3 10377.9 10432.5 10538.0 654803.0 681452.0 693427.0 698763.0 701273.0 75865.6 76677.1 81315.6 83975.0 87464.0 1162.7 1169.2 1221.6 1240.2 1254.4 16180.2 16188.4 16266.7 16270.7 16660.3 36811.1 37538.0 37705.0 37811.8 37972.2 44859.3 45874.5 45948.5 46463.5 47299.3 9735.4 9757.8 10030.6 10183.3 10185.0 11703.6 11823.1 13039.5 17729.5 18116.5 14950.8 15183.5 15243.4 15315.2 15655.5 14204.5 14585.5 14730.2 15017.3 15046.0 11672.8 14986.2 15454.8 15524.5 16007.2 9947.3 10206.1 10294.6 10295.3 10398.5 14766.6 14919.0 15164.9 15337.5 15503.7 01:45:53 INFO - 2016-01-26 01:45:53,322 DEBUG : BROWSER_OUTPUT: 01:45:53 INFO - 2016-01-26 01:45:53,323 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5689.47 Median:6620.97 stddev:3037.23 (45.9%) stddev-sans-first:3029.43 01:45:53 INFO - 2016-01-26 01:45:53,324 DEBUG : BROWSER_OUTPUT: Values: 1499.6 1553.3 1595.6 1724.1 1850.0 7513.0 8000.4 8005.2 8012.0 8020.4 7184.3 7397.1 7585.8 7808.3 7829.2 2255.3 2315.7 2336.1 2341.0 2341.9 2032.9 2329.9 2342.8 2381.4 2391.8 2068.6 2296.7 2331.2 2334.5 2336.1 2278.7 2279.1 2287.8 2323.7 2339.2 2193.4 2197.4 2197.9 2217.0 2217.6 8084.5 8180.8 8185.2 8187.2 8194.8 4858.2 4947.9 4950.7 4958.5 4960.1 3015.8 3065.7 3078.6 3133.6 3230.3 1033.2 1039.3 1060.7 1069.7 1071.5 11403.3 11552.7 11558.2 11559.9 11580.7 4210.6 4229.4 4331.4 4338.1 4341.7 9327.0 9358.4 9390.6 9397.5 9397.9 4797.9 4886.8 4905.2 4927.5 4935.8 4395.8 4403.6 4406.9 4415.5 4471.1 3636.1 3652.1 3655.2 3670.9 3686.1 1042.2 1055.4 1075.4 1078.9 1091.1 1013.4 1017.0 1044.5 1088.3 1300.9 7825.3 7904.6 7908.8 7909.2 7913.7 661.5 674.7 676.3 678.9 818.1 6056.9 6100.8 6108.3 6121.9 6130.1 9553.6 9777.1 10014.2 10030.1 10032.8 9796.0 9813.1 9915.7 9931.8 9932.2 7191.3 7487.7 7746.6 7762.3 7765.5 7479.3 7589.2 7594.4 7616.5 7620.7 8744.7 8794.8 8805.2 9021.3 9024.0 8756.3 8970.6 9171.3 9176.4 9190.4 7345.0 7397.3 7411.8 7420.9 7421.1 7881.2 7973.8 7999.3 8007.1 8077.3 7509.0 7566.7 7617.3 7617.5 7705.3 6529.6 7320.0 7374.3 7439.9 7484.2 7495.5 7545.8 7550.8 7587.4 7618.7 6723.3 6752.5 6759.3 6769.5 6769.9 6612.3 6629.7 6636.5 6637.0 6640.9 01:45:53 INFO - 2016-01-26 01:45:53,324 DEBUG : BROWSER_OUTPUT: 01:45:53 INFO - 2016-01-26 01:45:53,324 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5455.75 Median:6218.78 stddev:1633.70 (26.3%) stddev-sans-first:1592.75 01:45:53 INFO - 2016-01-26 01:45:53,324 DEBUG : BROWSER_OUTPUT: Values: 352.7 354.7 355.1 360.0 361.5 6093.0 6142.0 6151.1 6155.6 6187.4 6068.5 6073.7 6085.3 6095.5 6097.0 4672.8 4713.7 4738.7 4748.6 4754.8 4718.0 4720.3 4722.6 4723.3 4724.6 4660.2 4691.2 4698.6 4699.5 4708.5 4514.6 4612.6 4617.8 4700.5 4701.9 4132.9 4151.8 4154.3 4160.7 4160.8 5598.3 5713.7 5782.9 5794.0 5801.9 5372.3 5393.1 5505.2 5510.9 5521.6 6428.9 6437.7 6444.7 6445.6 6458.1 4620.7 4621.0 4624.0 4624.0 4637.0 6490.3 6647.6 6716.0 6716.8 6722.0 4614.4 4641.1 4641.3 4648.2 4649.4 4670.1 4702.6 4703.1 4704.2 4714.3 5285.2 5402.7 5412.4 5413.9 5453.8 6521.3 6522.1 6525.3 6525.4 6531.1 1634.9 1667.9 1669.2 1669.4 1670.9 1107.0 1113.5 1116.7 1134.8 1144.4 6310.6 6311.1 6312.2 6317.7 6319.3 6682.7 6683.7 6686.4 6695.3 6700.2 6176.8 6250.1 6270.7 6273.7 6281.2 3598.6 3620.4 3625.1 3625.3 3626.9 6881.3 6882.1 6889.3 6891.7 6892.3 6680.9 6757.8 6798.2 6937.9 6961.1 6955.6 6967.9 6973.6 6975.9 6996.4 6528.5 6737.9 6739.4 6750.0 6753.8 6655.6 6682.0 6693.2 6704.6 6705.2 6965.7 6974.4 6974.9 6977.4 6985.3 4603.9 4656.6 4691.9 4692.5 4693.4 6758.0 6767.7 6773.0 6774.9 6776.2 6518.9 6569.2 6571.8 6571.9 6572.0 6444.3 6542.4 6547.2 6552.9 6559.2 6463.1 6467.9 6480.3 6482.1 6482.9 6595.3 6596.7 6604.6 6611.2 6614.5 6292.2 6391.7 6416.5 6422.5 6462.9 01:45:53 INFO - 2016-01-26 01:45:53,324 DEBUG : BROWSER_OUTPUT: 01:45:53 INFO - 2016-01-26 01:45:53,324 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14792.35 Median:50573.12 stddev:17587.75 (34.8%) stddev-sans-first:17605.74 01:45:53 INFO - 2016-01-26 01:45:53,324 DEBUG : BROWSER_OUTPUT: Values: 809.1 829.7 886.3 910.4 966.1 14101.3 14763.6 14794.4 14800.6 14808.5 13169.1 13246.7 13284.3 13305.5 13315.3 754.9 757.7 886.3 890.3 902.1 889.5 901.9 906.2 907.8 908.3 794.7 930.9 944.0 944.4 944.7 777.4 924.1 926.9 928.0 928.5 679.3 680.1 683.9 683.9 684.8 13492.8 13740.5 13814.9 13880.0 13955.5 12799.9 13133.7 13167.1 13314.1 13669.3 10470.7 10528.5 10647.9 10953.4 10955.0 3907.8 3911.4 3921.5 3926.5 3926.9 49872.8 50305.2 51406.0 51710.5 51807.2 11002.5 11050.5 11229.6 11363.7 11491.5 20087.8 20124.4 20128.2 20164.5 20166.2 7496.0 7608.5 7686.1 7696.7 8010.1 4157.2 4236.7 4313.1 4337.0 4345.8 3042.1 3075.8 3083.8 3084.9 3090.4 534.6 554.6 555.0 559.5 566.4 621.7 686.7 702.0 706.3 717.9 8529.7 9051.3 9060.7 9408.4 9552.0 623.3 646.9 682.3 705.4 720.8 4166.3 4206.6 4322.4 4354.0 4401.6 62038.0 62792.7 62847.7 63077.0 64040.7 56243.4 57069.7 57341.7 57407.9 57640.0 50597.2 50718.2 51088.5 51617.2 51960.5 8794.3 8822.5 8900.0 9402.8 9485.4 36850.8 37681.8 38022.0 38278.6 38445.0 49176.3 49808.3 50514.0 50549.0 50995.5 11567.4 11874.9 11879.5 12103.6 12134.4 12356.3 12453.5 12738.3 12765.9 13605.0 10632.0 10852.2 11232.2 11412.5 11414.3 10619.4 10798.9 10822.2 11124.4 11582.1 9806.7 9875.9 10266.4 10788.8 11018.8 9141.7 9445.7 9593.5 9866.3 9906.6 9649.8 9766.6 9884.4 10126.5 10230.2 01:45:53 INFO - 2016-01-26 01:45:53,325 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 01:45:53 INFO - 2016-01-26 01:45:53,325 DEBUG : BROWSER_OUTPUT: 01:45:53 INFO - 2016-01-26 01:45:53,880 INFO : Browser exited with error code: 0 01:45:53 INFO - 2016-01-26 01:45:53,898 INFO : Completed test dromaeo_css (00:18:30) 01:45:53 INFO - 2016-01-26 01:45:53,898 INFO : Starting test kraken 01:45:53 INFO - 2016-01-26 01:45:53,898 DEBUG : operating with platform_type : linux_ 01:45:53 INFO - 2016-01-26 01:45:53,898 INFO : Initialising browser for kraken test... 01:45:53 INFO - 2016-01-26 01:45:53,904 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzmeLsD/profile http://localhost:41819/getInfo.html 01:45:57 INFO - 2016-01-26 01:45:57,907 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:45:57 INFO - 2016-01-26 01:45:57,907 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:45:57 INFO - 2016-01-26 01:45:57,907 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:45:57 INFO - 2016-01-26 01:45:57,908 DEBUG : BROWSER_OUTPUT: __metrics 01:45:58 INFO - 2016-01-26 01:45:58,117 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:45:59 INFO - 2016-01-26 01:45:59,023 INFO : Browser initialized. 01:45:59 INFO - 2016-01-26 01:45:59,024 INFO : Running cycle 1/1 for kraken test... 01:45:59 INFO - 2016-01-26 01:45:59,024 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzmeLsD/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 01:46:10 INFO - 2016-01-26 01:46:10,714 DEBUG : BROWSER_OUTPUT: RSS: Main: 134647808 01:46:10 INFO - 2016-01-26 01:46:10,715 DEBUG : BROWSER_OUTPUT: 01:46:13 INFO - 2016-01-26 01:46:13,149 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41819/tests/kraken/driver.html?testName=audio-beat-detection) 01:46:13 INFO - 2016-01-26 01:46:13,731 DEBUG : BROWSER_OUTPUT: RSS: Main: 164442112 01:46:13 INFO - 2016-01-26 01:46:13,731 DEBUG : BROWSER_OUTPUT: 01:46:16 INFO - 2016-01-26 01:46:16,349 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41819/tests/kraken/driver.html?testName=audio-dft) 01:46:16 INFO - 2016-01-26 01:46:16,915 DEBUG : BROWSER_OUTPUT: RSS: Main: 164106240 01:46:16 INFO - 2016-01-26 01:46:16,915 DEBUG : BROWSER_OUTPUT: 01:46:19 INFO - 2016-01-26 01:46:19,688 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41819/tests/kraken/driver.html?testName=audio-fft) 01:46:20 INFO - 2016-01-26 01:46:20,251 DEBUG : BROWSER_OUTPUT: RSS: Main: 160505856 01:46:20 INFO - 2016-01-26 01:46:20,251 DEBUG : BROWSER_OUTPUT: 01:46:22 INFO - 2016-01-26 01:46:22,256 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41819/tests/kraken/driver.html?testName=audio-oscillator) 01:46:22 INFO - 2016-01-26 01:46:22,819 DEBUG : BROWSER_OUTPUT: RSS: Main: 160440320 01:46:22 INFO - 2016-01-26 01:46:22,819 DEBUG : BROWSER_OUTPUT: 01:46:24 INFO - 2016-01-26 01:46:24,974 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41819/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:46:25 INFO - 2016-01-26 01:46:25,528 DEBUG : BROWSER_OUTPUT: RSS: Main: 159576064 01:46:25 INFO - 2016-01-26 01:46:25,528 DEBUG : BROWSER_OUTPUT: 01:46:28 INFO - 2016-01-26 01:46:28,409 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41819/tests/kraken/driver.html?testName=imaging-darkroom) 01:46:28 INFO - 2016-01-26 01:46:28,976 DEBUG : BROWSER_OUTPUT: RSS: Main: 159289344 01:46:28 INFO - 2016-01-26 01:46:28,976 DEBUG : BROWSER_OUTPUT: 01:46:32 INFO - 2016-01-26 01:46:32,278 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41819/tests/kraken/driver.html?testName=imaging-desaturate) 01:46:32 INFO - 2016-01-26 01:46:32,839 DEBUG : BROWSER_OUTPUT: RSS: Main: 161103872 01:46:32 INFO - 2016-01-26 01:46:32,839 DEBUG : BROWSER_OUTPUT: 01:46:35 INFO - 2016-01-26 01:46:35,549 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41819/tests/kraken/driver.html?testName=json-parse-financial) 01:46:36 INFO - 2016-01-26 01:46:36,116 DEBUG : BROWSER_OUTPUT: RSS: Main: 160026624 01:46:36 INFO - 2016-01-26 01:46:36,117 DEBUG : BROWSER_OUTPUT: 01:46:37 INFO - 2016-01-26 01:46:37,615 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41819/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:46:38 INFO - 2016-01-26 01:46:38,177 DEBUG : BROWSER_OUTPUT: RSS: Main: 164896768 01:46:38 INFO - 2016-01-26 01:46:38,177 DEBUG : BROWSER_OUTPUT: 01:46:39 INFO - 2016-01-26 01:46:39,933 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41819/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:46:40 INFO - 2016-01-26 01:46:40,522 DEBUG : BROWSER_OUTPUT: RSS: Main: 164175872 01:46:40 INFO - 2016-01-26 01:46:40,523 DEBUG : BROWSER_OUTPUT: 01:46:42 INFO - 2016-01-26 01:46:42,515 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41819/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:46:43 INFO - 2016-01-26 01:46:43,081 DEBUG : BROWSER_OUTPUT: RSS: Main: 167567360 01:46:43 INFO - 2016-01-26 01:46:43,081 DEBUG : BROWSER_OUTPUT: 01:46:45 INFO - 2016-01-26 01:46:45,437 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41819/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:46:46 INFO - 2016-01-26 01:46:46,010 DEBUG : BROWSER_OUTPUT: RSS: Main: 173510656 01:46:46 INFO - 2016-01-26 01:46:46,010 DEBUG : BROWSER_OUTPUT: 01:46:48 INFO - 2016-01-26 01:46:48,547 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41819/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:46:49 INFO - 2016-01-26 01:46:49,108 DEBUG : BROWSER_OUTPUT: RSS: Main: 172032000 01:46:49 INFO - 2016-01-26 01:46:49,109 DEBUG : BROWSER_OUTPUT: 01:46:50 INFO - 2016-01-26 01:46:50,758 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41819/tests/kraken/driver.html?testName=ai-astar) 01:46:51 INFO - 2016-01-26 01:46:51,008 DEBUG : BROWSER_OUTPUT: RSS: Main: 230522880 01:46:51 INFO - 2016-01-26 01:46:51,008 DEBUG : BROWSER_OUTPUT: 01:46:51 INFO - 2016-01-26 01:46:51,012 DEBUG : BROWSER_OUTPUT: __start_tp_report 01:46:51 INFO - 2016-01-26 01:46:51,012 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 01:46:51 INFO - 2016-01-26 01:46:51,012 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 01:46:51 INFO - 2016-01-26 01:46:51,012 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 01:46:51 INFO - 2016-01-26 01:46:51,012 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;111;103;108;111;110;98;110;110;109 01:46:51 INFO - 2016-01-26 01:46:51,012 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;161;123;155;164;128;158;157;122;161;127 01:46:51 INFO - 2016-01-26 01:46:51,012 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;160;160;158;158;160;159;160;158;160 01:46:51 INFO - 2016-01-26 01:46:51,013 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;73;78;160;70;73;71;72;154;73 01:46:51 INFO - 2016-01-26 01:46:51,013 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;91;133;91;91;90;135;91;91;134 01:46:51 INFO - 2016-01-26 01:46:51,013 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;110;110;109;109;110;110;110;110 01:46:51 INFO - 2016-01-26 01:46:51,013 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;145;146;145;146;146;146;145;145;145 01:46:51 INFO - 2016-01-26 01:46:51,013 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;96;95;96;96;95;95 01:46:51 INFO - 2016-01-26 01:46:51,013 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;56;55;57;56;55;57;56;54;55 01:46:51 INFO - 2016-01-26 01:46:51,013 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;65;64;64;64;64;64;64;64;64 01:46:51 INFO - 2016-01-26 01:46:51,013 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;78;79;88;80;80;80;80;80;80;80 01:46:51 INFO - 2016-01-26 01:46:51,014 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;115;109;109;110;114;111;118;118;124;112 01:46:51 INFO - 2016-01-26 01:46:51,014 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;145;141;142;154;144;145;147;148;144;133 01:46:51 INFO - 2016-01-26 01:46:51,014 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;69;66;64;63;65;64;62;63;65;72 01:46:51 INFO - 2016-01-26 01:46:51,014 DEBUG : BROWSER_OUTPUT: __end_tp_report 01:46:51 INFO - 2016-01-26 01:46:51,014 DEBUG : BROWSER_OUTPUT: __start_cc_report 01:46:51 INFO - 2016-01-26 01:46:51,014 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,868 01:46:51 INFO - 2016-01-26 01:46:51,014 DEBUG : BROWSER_OUTPUT: __end_cc_report 01:46:51 INFO - 2016-01-26 01:46:51,014 DEBUG : BROWSER_OUTPUT: __startTimestamp1453801611009__endTimestamp 01:46:51 INFO - 2016-01-26 01:46:51,014 DEBUG : BROWSER_OUTPUT: 01:46:51 INFO - 2016-01-26 01:46:51,015 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 01:46:51 INFO - 2016-01-26 01:46:51,015 DEBUG : BROWSER_OUTPUT: Number of tests: 14 01:46:51 INFO - 2016-01-26 01:46:51,015 DEBUG : BROWSER_OUTPUT: 01:46:51 INFO - 2016-01-26 01:46:51,016 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.10 Median:110.50 stddev:4.28 (3.9%) stddev-sans-first:4.41 01:46:51 INFO - 2016-01-26 01:46:51,016 DEBUG : BROWSER_OUTPUT: Values: 111.0 111.0 103.0 108.0 111.0 110.0 98.0 110.0 110.0 109.0 01:46:51 INFO - 2016-01-26 01:46:51,017 DEBUG : BROWSER_OUTPUT: 01:46:51 INFO - 2016-01-26 01:46:51,017 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.60 Median:157.50 stddev:17.98 (11.4%) stddev-sans-first:18.18 01:46:51 INFO - 2016-01-26 01:46:51,017 DEBUG : BROWSER_OUTPUT: Values: 161.0 123.0 155.0 164.0 128.0 158.0 157.0 122.0 161.0 127.0 01:46:51 INFO - 2016-01-26 01:46:51,017 DEBUG : BROWSER_OUTPUT: 01:46:51 INFO - 2016-01-26 01:46:51,017 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.40 Median:160.00 stddev:1.07 (0.7%) stddev-sans-first:0.97 01:46:51 INFO - 2016-01-26 01:46:51,018 DEBUG : BROWSER_OUTPUT: Values: 161.0 160.0 160.0 158.0 158.0 160.0 159.0 160.0 158.0 160.0 01:46:51 INFO - 2016-01-26 01:46:51,018 DEBUG : BROWSER_OUTPUT: 01:46:51 INFO - 2016-01-26 01:46:51,018 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:89.40 Median:72.50 stddev:35.73 (49.3%) stddev-sans-first:37.20 01:46:51 INFO - 2016-01-26 01:46:51,018 DEBUG : BROWSER_OUTPUT: Values: 70.0 73.0 78.0 160.0 70.0 73.0 71.0 72.0 154.0 73.0 01:46:51 INFO - 2016-01-26 01:46:51,019 DEBUG : BROWSER_OUTPUT: 01:46:51 INFO - 2016-01-26 01:46:51,019 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.90 Median:91.00 stddev:20.78 (22.8%) stddev-sans-first:21.59 01:46:51 INFO - 2016-01-26 01:46:51,019 DEBUG : BROWSER_OUTPUT: Values: 92.0 91.0 133.0 91.0 91.0 90.0 135.0 91.0 91.0 134.0 01:46:51 INFO - 2016-01-26 01:46:51,019 DEBUG : BROWSER_OUTPUT: 01:46:51 INFO - 2016-01-26 01:46:51,020 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.70 Median:110.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 01:46:51 INFO - 2016-01-26 01:46:51,020 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 110.0 110.0 109.0 109.0 110.0 110.0 110.0 110.0 01:46:51 INFO - 2016-01-26 01:46:51,020 DEBUG : BROWSER_OUTPUT: 01:46:51 INFO - 2016-01-26 01:46:51,020 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 01:46:51 INFO - 2016-01-26 01:46:51,020 DEBUG : BROWSER_OUTPUT: Values: 146.0 145.0 146.0 145.0 146.0 146.0 146.0 145.0 145.0 145.0 01:46:51 INFO - 2016-01-26 01:46:51,021 DEBUG : BROWSER_OUTPUT: 01:46:51 INFO - 2016-01-26 01:46:51,021 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 01:46:51 INFO - 2016-01-26 01:46:51,021 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 96.0 95.0 96.0 96.0 95.0 95.0 01:46:51 INFO - 2016-01-26 01:46:51,021 DEBUG : BROWSER_OUTPUT: 01:46:51 INFO - 2016-01-26 01:46:51,021 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.70 Median:56.00 stddev:0.95 (1.7%) stddev-sans-first:1.00 01:46:51 INFO - 2016-01-26 01:46:51,022 DEBUG : BROWSER_OUTPUT: Values: 56.0 56.0 55.0 57.0 56.0 55.0 57.0 56.0 54.0 55.0 01:46:51 INFO - 2016-01-26 01:46:51,022 DEBUG : BROWSER_OUTPUT: 01:46:51 INFO - 2016-01-26 01:46:51,022 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.00 Median:64.00 stddev:0.47 (0.7%) stddev-sans-first:0.33 01:46:51 INFO - 2016-01-26 01:46:51,022 DEBUG : BROWSER_OUTPUT: Values: 63.0 65.0 64.0 64.0 64.0 64.0 64.0 64.0 64.0 64.0 01:46:51 INFO - 2016-01-26 01:46:51,023 DEBUG : BROWSER_OUTPUT: 01:46:51 INFO - 2016-01-26 01:46:51,023 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:80.00 stddev:2.72 (3.4%) stddev-sans-first:2.73 01:46:51 INFO - 2016-01-26 01:46:51,023 DEBUG : BROWSER_OUTPUT: Values: 78.0 79.0 88.0 80.0 80.0 80.0 80.0 80.0 80.0 80.0 01:46:51 INFO - 2016-01-26 01:46:51,023 DEBUG : BROWSER_OUTPUT: 01:46:51 INFO - 2016-01-26 01:46:51,023 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:114.00 Median:114.50 stddev:4.85 (4.2%) stddev-sans-first:5.13 01:46:51 INFO - 2016-01-26 01:46:51,024 DEBUG : BROWSER_OUTPUT: Values: 115.0 109.0 109.0 110.0 114.0 111.0 118.0 118.0 124.0 112.0 01:46:51 INFO - 2016-01-26 01:46:51,024 DEBUG : BROWSER_OUTPUT: 01:46:51 INFO - 2016-01-26 01:46:51,024 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:144.30 Median:145.00 stddev:5.38 (3.7%) stddev-sans-first:5.70 01:46:51 INFO - 2016-01-26 01:46:51,024 DEBUG : BROWSER_OUTPUT: Values: 145.0 141.0 142.0 154.0 144.0 145.0 147.0 148.0 144.0 133.0 01:46:51 INFO - 2016-01-26 01:46:51,024 DEBUG : BROWSER_OUTPUT: 01:46:51 INFO - 2016-01-26 01:46:51,024 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.00 stddev:3.06 (4.7%) stddev-sans-first:2.93 01:46:51 INFO - 2016-01-26 01:46:51,024 DEBUG : BROWSER_OUTPUT: Values: 69.0 66.0 64.0 63.0 65.0 64.0 62.0 63.0 65.0 72.0 01:46:51 INFO - 2016-01-26 01:46:51,024 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 01:46:51 INFO - 2016-01-26 01:46:51,024 DEBUG : BROWSER_OUTPUT: 01:46:51 INFO - 2016-01-26 01:46:51,664 INFO : Browser exited with error code: 0 01:46:51 INFO - 2016-01-26 01:46:51,718 INFO : Completed test kraken (00:00:57) 01:46:51 INFO - 2016-01-26 01:46:51,718 INFO : Starting test v8_7 01:46:51 INFO - 2016-01-26 01:46:51,718 DEBUG : operating with platform_type : linux_ 01:46:51 INFO - 2016-01-26 01:46:51,718 INFO : Initialising browser for v8_7 test... 01:46:51 INFO - 2016-01-26 01:46:51,724 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpt2oKmp/profile http://localhost:41819/getInfo.html 01:46:55 INFO - 2016-01-26 01:46:55,627 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:46:55 INFO - 2016-01-26 01:46:55,628 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:46:55 INFO - 2016-01-26 01:46:55,628 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:46:55 INFO - 2016-01-26 01:46:55,628 DEBUG : BROWSER_OUTPUT: __metrics 01:46:55 INFO - 2016-01-26 01:46:55,813 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:46:56 INFO - 2016-01-26 01:46:56,793 INFO : Browser initialized. 01:46:56 INFO - 2016-01-26 01:46:56,793 INFO : Running cycle 1/1 for v8_7 test... 01:46:56 INFO - 2016-01-26 01:46:56,794 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpt2oKmp/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 01:47:08 INFO - 2016-01-26 01:47:08,471 DEBUG : BROWSER_OUTPUT: RSS: Main: 134729728 01:47:08 INFO - 2016-01-26 01:47:08,471 DEBUG : BROWSER_OUTPUT: 01:47:29 INFO - 2016-01-26 01:47:29,832 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41819/tests/v8_7/run.html (next: http://localhost:41819/tests/v8_7/run.html) 01:47:30 INFO - 2016-01-26 01:47:30,075 DEBUG : BROWSER_OUTPUT: RSS: Main: 427474944 01:47:30 INFO - 2016-01-26 01:47:30,075 DEBUG : BROWSER_OUTPUT: 01:47:30 INFO - 2016-01-26 01:47:30,076 DEBUG : BROWSER_OUTPUT: __start_tp_report 01:47:30 INFO - 2016-01-26 01:47:30,077 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 01:47:30 INFO - 2016-01-26 01:47:30,077 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 01:47:30 INFO - 2016-01-26 01:47:30,077 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 01:47:30 INFO - 2016-01-26 01:47:30,077 DEBUG : BROWSER_OUTPUT: |0;Richards;168.01075268817203 01:47:30 INFO - 2016-01-26 01:47:30,077 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;165.15276630883568 01:47:30 INFO - 2016-01-26 01:47:30,077 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.78449958182324 01:47:30 INFO - 2016-01-26 01:47:30,077 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5164.948453608247 01:47:30 INFO - 2016-01-26 01:47:30,077 DEBUG : BROWSER_OUTPUT: |4;RayTrace;959.6928982725528 01:47:30 INFO - 2016-01-26 01:47:30,078 DEBUG : BROWSER_OUTPUT: |5;Earley;540.2485143165857 01:47:30 INFO - 2016-01-26 01:47:30,078 DEBUG : BROWSER_OUTPUT: |6;Boyer;10562.5 01:47:30 INFO - 2016-01-26 01:47:30,078 DEBUG : BROWSER_OUTPUT: |7;RegExp;24190.47619047619 01:47:30 INFO - 2016-01-26 01:47:30,078 DEBUG : BROWSER_OUTPUT: |8;Splay;520.5622071837585 01:47:30 INFO - 2016-01-26 01:47:30,079 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 01:47:30 INFO - 2016-01-26 01:47:30,079 DEBUG : BROWSER_OUTPUT: __end_tp_report 01:47:30 INFO - 2016-01-26 01:47:30,079 DEBUG : BROWSER_OUTPUT: __startTimestamp1453801650076__endTimestamp 01:47:30 INFO - 2016-01-26 01:47:30,079 DEBUG : BROWSER_OUTPUT: 01:47:30 INFO - 2016-01-26 01:47:30,080 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 01:47:30 INFO - 2016-01-26 01:47:30,080 DEBUG : BROWSER_OUTPUT: Number of tests: 10 01:47:30 INFO - 2016-01-26 01:47:30,081 DEBUG : BROWSER_OUTPUT: 01:47:30 INFO - 2016-01-26 01:47:30,081 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:168.01 Median:168.01 stddev:0.00 (0.0%) 01:47:30 INFO - 2016-01-26 01:47:30,081 DEBUG : BROWSER_OUTPUT: Values: 168.0 01:47:30 INFO - 2016-01-26 01:47:30,082 DEBUG : BROWSER_OUTPUT: 01:47:30 INFO - 2016-01-26 01:47:30,082 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:165.15 Median:165.15 stddev:0.00 (0.0%) 01:47:30 INFO - 2016-01-26 01:47:30,082 DEBUG : BROWSER_OUTPUT: Values: 165.2 01:47:30 INFO - 2016-01-26 01:47:30,082 DEBUG : BROWSER_OUTPUT: 01:47:30 INFO - 2016-01-26 01:47:30,082 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.78 Median:278.78 stddev:0.00 (0.0%) 01:47:30 INFO - 2016-01-26 01:47:30,082 DEBUG : BROWSER_OUTPUT: Values: 278.8 01:47:30 INFO - 2016-01-26 01:47:30,082 DEBUG : BROWSER_OUTPUT: 01:47:30 INFO - 2016-01-26 01:47:30,082 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5164.95 Median:5164.95 stddev:0.00 (0.0%) 01:47:30 INFO - 2016-01-26 01:47:30,083 DEBUG : BROWSER_OUTPUT: Values: 5164.9 01:47:30 INFO - 2016-01-26 01:47:30,083 DEBUG : BROWSER_OUTPUT: 01:47:30 INFO - 2016-01-26 01:47:30,083 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:959.69 Median:959.69 stddev:0.00 (0.0%) 01:47:30 INFO - 2016-01-26 01:47:30,083 DEBUG : BROWSER_OUTPUT: Values: 959.7 01:47:30 INFO - 2016-01-26 01:47:30,083 DEBUG : BROWSER_OUTPUT: 01:47:30 INFO - 2016-01-26 01:47:30,083 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:540.25 Median:540.25 stddev:0.00 (0.0%) 01:47:30 INFO - 2016-01-26 01:47:30,083 DEBUG : BROWSER_OUTPUT: Values: 540.2 01:47:30 INFO - 2016-01-26 01:47:30,083 DEBUG : BROWSER_OUTPUT: 01:47:30 INFO - 2016-01-26 01:47:30,083 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10562.50 Median:10562.50 stddev:0.00 (0.0%) 01:47:30 INFO - 2016-01-26 01:47:30,083 DEBUG : BROWSER_OUTPUT: Values: 10562.5 01:47:30 INFO - 2016-01-26 01:47:30,084 DEBUG : BROWSER_OUTPUT: 01:47:30 INFO - 2016-01-26 01:47:30,084 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24190.48 Median:24190.48 stddev:0.00 (0.0%) 01:47:30 INFO - 2016-01-26 01:47:30,084 DEBUG : BROWSER_OUTPUT: Values: 24190.5 01:47:30 INFO - 2016-01-26 01:47:30,084 DEBUG : BROWSER_OUTPUT: 01:47:30 INFO - 2016-01-26 01:47:30,084 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:520.56 Median:520.56 stddev:0.00 (0.0%) 01:47:30 INFO - 2016-01-26 01:47:30,084 DEBUG : BROWSER_OUTPUT: Values: 520.6 01:47:30 INFO - 2016-01-26 01:47:30,084 DEBUG : BROWSER_OUTPUT: 01:47:30 INFO - 2016-01-26 01:47:30,084 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 01:47:30 INFO - 2016-01-26 01:47:30,084 DEBUG : BROWSER_OUTPUT: Values: 4822.1 01:47:30 INFO - 2016-01-26 01:47:30,084 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 01:47:30 INFO - 2016-01-26 01:47:30,084 DEBUG : BROWSER_OUTPUT: 01:47:30 INFO - 2016-01-26 01:47:30,720 INFO : Browser exited with error code: 0 01:47:30 INFO - 2016-01-26 01:47:30,734 INFO : Completed test v8_7 (00:00:39) 01:47:31 INFO - 2016-01-26 01:47:31,125 INFO : Completed test suite (00:20:08) 01:47:31 INFO - 2016-01-26 01:47:31,125 DEBUG : Working with test: dromaeo_css 01:47:31 INFO - 2016-01-26 01:47:31,125 DEBUG : Generating results file: dromaeo_css 01:47:31 INFO - 2016-01-26 01:47:31,126 DEBUG : Working with test: kraken 01:47:31 INFO - 2016-01-26 01:47:31,127 DEBUG : Generating results file: kraken 01:47:31 INFO - 2016-01-26 01:47:31,127 INFO : javascript benchmark 01:47:31 INFO - 2016-01-26 01:47:31,127 DEBUG : Working with test: v8_7 01:47:31 INFO - 2016-01-26 01:47:31,127 DEBUG : Generating results file: v8_7 01:47:31 INFO - 2016-01-26 01:47:31,128 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:47:31 INFO - 2016-01-26 01:47:31,178 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 01:47:31 INFO - 2016-01-26 01:47:31,178 DEBUG : process_Request line: dromaeo_css 7408.55 graph.html#tests=[[72,53,35]] 01:47:31 INFO - 2016-01-26 01:47:31,179 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:47:31 INFO - 2016-01-26 01:47:31,211 DEBUG : process_Request line: kraken 1480.70 graph.html#tests=[[232,53,35]] 01:47:31 INFO - 2016-01-26 01:47:31,211 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:47:31 INFO - 2016-01-26 01:47:31,240 DEBUG : process_Request line: v8_7 23283.78 graph.html#tests=[[230,53,35]] 01:47:31 INFO - 2016-01-26 01:47:31,242 INFO : javascript benchmark 01:47:31 INFO - 2016-01-26 01:47:31,245 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3312.205070783012, 3395.7622966392364, 3416.9658303416973, 3420.897373078807, 3437.0204491719855, 5457.972710136449, 5640.8589785255335, 5643.6049476536655, 5645.5483561315095, 5647.09646456567, 5642.887142257153, 5654.406287339822, 5683.403242659523, 5692.715364231783, 5700.173474846145, 5065.4174769901565, 5102.821401250957, 5135.871603209912, 5172.40517340505, 5256.8685782855355, 5214.061468935609, 5229.712365819878, 5231.712255825928, 5237.266782650432, 5237.319148510707, 5168.9224661630105, 5240.318758561381, 5248.475152484759, 5249.1338929076655, 5249.763760630781, 5044.697318160904, 5047.217681259411, 5142.331496905415, 5173.198154286091, 5191.273221748958, 5136.43499215086, 5157.303763991888, 5210.817621383271, 5219.399769026572, 5261.421243663194, 5307.920381194285, 5434.0, 5482.890342193134, 5505.119180931032, 5581.0, 6087.847803804896, 6132.570720049598, 6181.103739957726, 6191.040388739752, 6193.783217587363, 6176.197094377747, 6193.0, 6226.159468471744, 6239.001759718423, 6244.313125556185, 5510.256115424447, 5545.500904918576, 5630.662160270356, 5765.798197063083, 5776.133579962997, 6155.72299246535, 6221.657808820468, 6237.4698150656795, 6241.687915604201, 6245.125682404467, 6006.099085137177, 6015.1278064681255, 6025.517958563392, 6042.6072305300895, 6047.879042419127, 6047.516198704093, 6087.1173679816175, 6115.571909966348, 6120.357362476965, 6137.438273608292, 6099.603525770811, 6121.96939015311, 6127.0, 6130.111133885562, 6134.78528251509, 6075.5747097703625, 6097.603655762361, 6112.297085835097, 6119.84700382494, 6120.387961203933, 4407.118576284756, 4539.432570928634, 4590.357349971006, 4656.254999200111, 4695.835645752382, 4165.333546632524, 4167.06241095751, 4177.060161463646, 4179.540250572434, 4191.434156388909, 5680.40355762639, 5812.186293918855, 5820.097884827858, 5820.35976042635, 5825.242718446575, 5936.347001829881, 5956.2852457704485, 6013.458788709037, 6050.6369617823075, 6053.576249662481, 5743.655380677173, 5789.478946894799, 5795.304563452412, 5805.88388232233, 5811.041178205549, 5286.709230992333, 5287.894242115136, 5290.470952904679, 5291.682499050069, 5292.285541451854, 6036.366181550961, 6124.35694252106, 6130.673316708229, 6233.906491402633, 6242.843928901813, 5997.190379298739, 6080.148779170831, 6105.725242364193, 6126.754929802758, 6148.1699970503405, 6066.029435290332, 6205.906911396423, 6235.127082208403, 6288.0, 6298.937010630018, 5829.09649004405, 5890.234269545103, 5925.762969481172, 5958.076498142795, 5977.491913187428, 6087.0, 6156.076588511759, 6185.505159587132, 6195.19462469876, 6196.938030619636, 5689.0, 5792.4497172768515, 5996.820095397145, 6013.0, 6015.608985415934, 5773.364929857628, 5851.707414629166, 5853.473187412983, 5858.355580886185, 5892.263467066617, 5869.002269614261, 5891.116332550152, 5893.734781934909, 5895.380984996514, 5895.67573783446, 5730.1977723119635, 5798.623089499169, 5831.067029275296, 5999.730012149551, 6000.309964353981, 5799.82600521985, 5855.8536036597725, 5863.794767183128, 5876.324222714443, 5893.351731309638], "unit": "ms", "name": "dojo.html", "value": 5601.404673449911}, {"lowerIsBetter": false, "replicates": [571.3087164530917, 608.5527137553897, 615.3938370704857, 619.2507066449596, 630.9872654389708, 33075.33849323012, 41084.17831643369, 41533.96165095871, 41538.37692434616, 41564.0, 46210.53789462104, 47021.29468057982, 47054.0, 47078.52921470784, 47144.528554714445, 7939.960300198491, 8046.356291496666, 8059.153788852174, 8996.95501522493, 9141.817163656722, 10277.486125693686, 10504.159667226604, 10509.527071281811, 10525.78948421031, 10528.0, 10380.792384152312, 10458.267921245515, 10474.947625261862, 10475.633352832652, 10480.737981550446, 10375.273730838844, 10440.47797610124, 10450.372977621368, 10452.529636166315, 10456.581736730484, 10255.384676919408, 10408.479576021244, 10416.062554370068, 10720.624778132806, 10745.08666763326, 42613.57386426158, 43023.784881075706, 43066.56933430657, 43103.56896431027, 43105.0, 3236.158598764315, 3582.3730847101915, 3584.8028358440274, 3694.5935947045805, 4141.979290103561, 32396.190095247573, 32508.512372314435, 32530.0, 32764.508532372034, 32954.17614559631, 4146.212219678251, 4176.2900306947895, 4292.806823692941, 4295.978520107411, 4307.987622908614, 344877.0, 347215.0, 347462.2626886875, 348121.0, 348981.0, 57491.42508574944, 58228.0, 58514.41485585175, 59103.70448147818, 62595.0, 59181.112283315786, 59450.0, 59675.70162149248, 59694.70152649209, 59695.40304596898, 17039.48881533556, 17115.315387384366, 17196.31214751421, 17202.053887036083, 17202.483925482255, 13169.53906613264, 13202.801957970638, 13240.20558766477, 13276.867231327564, 13283.535076272477, 20709.792902071087, 21522.0, 21558.461038474165, 21589.352319430127, 21620.0, 7866.528008319519, 8016.31861291785, 8022.3983201260135, 8066.7579972600915, 8089.2315230053055, 1043.5564884923876, 1058.920580956416, 1074.6453670288786, 1076.6662334676275, 1132.3092913322866, 29167.270818229714, 29467.85266073656, 29962.0, 30252.24369390783, 30522.08433746991, 1107.213878146525, 1143.6683361824978, 1169.1640477058943, 1201.963941081769, 1235.4440501774238, 14607.0, 14894.329755161043, 16849.91575042117, 17495.825041749675, 17588.208530616153, 424613.0, 431666.0, 431800.0, 432194.0, 432621.0, 57734.71132644394, 58192.0, 58315.0, 58554.70722646359, 58603.70698146567, 1405.3886559346718, 1441.43063489923, 1444.0252829340154, 1452.985470145285, 1455.2796365798997, 26991.46017079687, 33052.33895322032, 33150.17124571905, 33265.50101748524, 33872.0, 51104.23343649923, 51695.67369468308, 51707.741461292455, 51720.741396292775, 51720.96558068893, 328488.0, 330664.0, 331535.0539483819, 331578.0, 332291.0, 29510.0, 29690.703092968793, 33806.661933380354, 44092.77953610211, 44303.55696442995, 48320.0, 49563.504364957334, 50030.24954625756, 50262.0, 51217.23174152465, 40726.0, 41361.0, 41502.792486037375, 42177.578224218596, 42324.36513452239, 38667.2266554673, 41895.581044189166, 42373.576264238196, 42944.35583466313, 43169.35245971376, 40761.59238407578, 40913.59086409098, 41949.0, 42065.369019465346, 42073.579264208194, 35526.93419197428, 36116.0, 38359.04102397463, 40930.386044209954, 41312.58687413087, 27848.443031139668, 28364.14907552694, 28452.85773571119, 28637.14088577346, 28771.424571508032], "unit": "ms", "name": "ext.html", "value": 20623.179373585976}, {"lowerIsBetter": false, "replicates": [383.8675656898371, 386.9709771767118, 390.17867389145846, 391.63773509503704, 393.6437524040744, 15144.242787860618, 16414.917925410373, 17195.398161064364, 17689.203985820637, 17704.91147544262, 17406.912965435156, 17546.561335966577, 17672.734908976374, 18399.264029438807, 18399.90800045998, 5557.027520183961, 5670.319561652587, 5673.092305231164, 5678.375378708339, 5683.488486036256, 4191.517975432834, 4293.806778694966, 5651.5761317901115, 6041.214642096521, 6253.405926436982, 6101.237345331833, 6124.922991315424, 6164.753409863622, 6195.31851496335, 6207.068939659042, 6223.844403889893, 6283.183186185766, 6314.494840412779, 6317.3366796486625, 6317.905231421532, 5509.898020395897, 5515.979543784372, 5593.161025846156, 5599.440055994409, 5599.7480113394995, 21763.0, 21821.890890545605, 22087.337379878467, 22115.33653990383, 22237.110515579356, 2098.7376577927757, 2160.60028894655, 2262.988685056581, 2338.9064437422485, 2421.176799888029, 13457.0, 13473.865261347359, 13789.586312410644, 13794.448222071105, 14293.356798944073, 1805.5666640006434, 1836.9357072502398, 1872.775266967959, 1919.4721451600783, 1966.8426525878037, 62781.372186278466, 63124.0, 63970.0, 66472.33527664661, 66978.0, 10250.0, 10534.73663158412, 10672.519736611872, 10708.19688523364, 10759.462026898624, 14337.35481903317, 14437.278136093153, 14480.927595361956, 14506.419743210365, 14517.346719397652, 8246.711365102192, 8318.085010648823, 8365.163483651586, 8500.914990850013, 8554.871676924851, 8138.064122625974, 8154.714584989497, 8171.79570510742, 8230.09468958414, 8240.670373185125, 6341.365863413714, 6346.428821406095, 6383.712732926937, 6404.775832845921, 6479.157709497735, 1739.4520725971531, 1823.8358547730768, 1841.0978620475871, 1871.8970456624745, 1882.5952420229717, 912.4023764434306, 966.8163049020663, 990.5245482168599, 1014.452195814252, 1169.6666450061691, 15153.772693409608, 17054.74417883733, 17325.826741732675, 17766.0, 17806.109694515224, 1043.9008294212038, 1056.4664844253602, 1098.9889301842325, 1106.2422240765102, 1110.0564520157964, 10110.494475276208, 10364.326318789255, 10377.948110259551, 10432.478376081166, 10538.0, 654803.0, 681452.0, 693427.0, 698763.0, 701273.0, 75865.6206718963, 76677.08307292251, 81315.5934220337, 83975.0, 87464.0, 1162.7151347919983, 1169.1582060916128, 1221.6457227403953, 1240.2124650846777, 1254.3790823542222, 16180.190990450667, 16188.352465901233, 16266.67466650684, 16270.674586507967, 16660.250288736792, 36811.07972300714, 37538.0, 37705.0, 37811.81094094512, 37972.240555189295, 44859.327110094026, 45874.54125458703, 45948.540514594424, 46463.53536464727, 47299.29051064168, 9735.367201131903, 9757.80484390294, 10030.64892728751, 10183.33808302458, 10185.032421919906, 11703.648890533297, 11823.054155667354, 13039.478420863059, 17729.468115956028, 18116.547086322946, 14950.775738364151, 15183.544493665207, 15243.3902643893, 15315.234238287818, 15655.530334089995, 14204.502842400467, 14585.489507867174, 14730.189839558927, 15017.324220410326, 15046.0, 11672.766544669228, 14986.175760333286, 15454.768178477107, 15524.456644017406, 16007.199640018185, 9947.253955953187, 10206.081452669296, 10294.588216471257, 10295.279330447096, 10398.532066057196, 14766.557003289918, 14919.0, 15164.924175379052, 15337.539873803804, 15503.689926201638], "unit": "ms", "name": "jquery.html", "value": 9577.050896971916}, {"lowerIsBetter": false, "replicates": [1499.6250937265684, 1553.2699631173348, 1595.6170519075426, 1724.1379310344819, 1849.9815001849977, 7512.962435187823, 8000.359971202304, 8005.239502247292, 8012.038555373349, 8020.438569300137, 7184.317489838457, 7397.149327827301, 7585.848283034336, 7808.258215469521, 7829.17793631669, 2255.2783109404995, 2315.69895913531, 2336.077249486452, 2341.028473183627, 2341.894614742332, 2032.8780273183656, 2329.871857047862, 2342.8008619267384, 2381.416552944531, 2391.80865530758, 2068.6276470235352, 2296.7358753743283, 2331.172433786007, 2334.533093381322, 2336.1122773346187, 2278.7037685100913, 2279.0541925101147, 2287.839851210416, 2323.7327707313707, 2339.181286549711, 2193.429708275844, 2197.351781224527, 2197.8571392859412, 2217.0245092159557, 2217.6119179143748, 8084.474509156945, 8180.754577362689, 8185.181481851767, 8187.1812818718245, 8194.795130121734, 4858.1983972644475, 4947.851564453073, 4950.727709975949, 4958.504149585049, 4960.057589058068, 3015.7738169637146, 3065.6934306569165, 3078.599782028344, 3133.5926329577237, 3230.305484320859, 1033.2302434686133, 1039.3088596083596, 1060.7030031591166, 1069.7379142110155, 1071.5124618298732, 11403.315801051964, 11552.711182220428, 11558.190926635223, 11559.884401155965, 11580.6525804226, 4210.642095421862, 4229.407882896397, 4331.436913201263, 4338.067315527177, 4341.739495630272, 9327.0, 9358.4384936904, 9390.577424015935, 9397.530123493798, 9397.90602093984, 4797.880052998633, 4886.828960986349, 4905.23968784839, 4927.507249275044, 4935.802567897315, 4395.824167033346, 4403.559644035571, 4406.889827754332, 4415.492218394861, 4471.083427897273, 3636.109153257416, 3652.123490362347, 3655.1775850433974, 3670.8531658733887, 3686.1337585667347, 1042.1818872185297, 1055.3931489393628, 1075.4031512510476, 1078.8543546621263, 1091.061686949223, 1013.4426065663855, 1016.9898301017043, 1044.5143008501084, 1088.3198001249218, 1300.8699130087105, 7825.334846538108, 7904.644291006918, 7908.7627371177805, 7909.24862138096, 7913.683452661944, 661.4675186474838, 674.6660403100399, 676.3473248315399, 678.9490788190906, 818.1409520003972, 6056.939430605637, 6100.786472473442, 6108.3280839107665, 6121.908171377433, 6130.080487926757, 9553.570089345858, 9777.071178238057, 10014.24893133018, 10030.147437467751, 10032.849507257397, 9795.951020244853, 9813.067758562926, 9915.702528924, 9931.751706207402, 9932.205423566098, 7191.34122159898, 7487.7004919803685, 7746.612669366622, 7762.340201082971, 7765.495242809199, 7479.252074792477, 7589.241075892367, 7594.392448603988, 7616.504927179715, 7620.69517219305, 8744.650213991368, 8794.780130496789, 8805.16350946659, 9021.278297736035, 9023.954880225558, 8756.343274254585, 8970.596323165604, 9171.31215158879, 9176.44941303524, 9190.448573085636, 7345.0, 7397.334239918433, 7411.777646670608, 7420.888686669597, 7421.109466863795, 7881.211878812073, 7973.8405231896195, 7999.280064794197, 8007.119216886254, 8077.313428358655, 7509.0, 7566.6973321068745, 7617.314441700273, 7617.542947423172, 7705.30652241301, 6529.575577587441, 7319.963400183178, 7374.262573742797, 7439.851202975802, 7484.214157513383, 7495.512791668524, 7545.849083018418, 7550.811229719301, 7587.393008559412, 7618.657160427683, 6723.327667233433, 6752.493562982698, 6759.290274521301, 6769.458443324424, 6769.93230067693, 6612.272650008399, 6629.668516574248, 6636.502262330441, 6636.966815165893, 6640.90038649411], "unit": "ms", "name": "mootools.html", "value": 4570.789033306432}, {"lowerIsBetter": false, "replicates": [352.70020482589797, 354.6683850599688, 355.135245676777, 359.98560057597695, 361.54987041133774, 6092.969535152323, 6141.969290153548, 6151.138840562314, 6155.6306621602735, 6187.4431301182885, 6068.514518838493, 6073.726682299296, 6085.269767627891, 6095.451409373156, 6096.969515152418, 4672.836450724225, 4713.717176969376, 4738.668293219476, 4748.643851711118, 4754.762261886909, 4717.952820471785, 4720.268358404435, 4722.645801564897, 4723.291506274052, 4724.645651576128, 4660.231061874787, 4691.202495575761, 4698.624110071186, 4699.459562150362, 4708.529147085298, 4514.6388288936805, 4612.561806628348, 4617.792199351037, 4700.459447163552, 4701.858944231679, 4132.91734165315, 4151.83392664293, 4154.293770059097, 4160.7087503874745, 4160.750354978711, 5598.272224610815, 5713.714314284269, 5782.88434231313, 5794.043982742843, 5801.883962320772, 5372.274742909696, 5393.1370980643305, 5505.174223866412, 5510.91733623992, 5521.585881058937, 6428.903566446507, 6437.742490300382, 6444.677766111722, 6445.613263204223, 6458.128152699373, 4620.722756634613, 4621.0, 4623.953760462386, 4623.953760462386, 4636.976815115903, 6490.318516555741, 6647.60114393138, 6716.0, 6716.83207919801, 6721.966390168018, 4614.423197100363, 4641.071785642885, 4641.327007583882, 4648.209804333272, 4649.418822647169, 4670.089332580114, 4702.553257440538, 4703.05938812232, 4704.200285951397, 4714.316424118484, 5285.207218917193, 5402.729863506809, 5412.431694672003, 5413.891722165535, 5453.836384908459, 6521.250056243498, 6522.119513865662, 6525.2920038184575, 6525.412712855771, 6531.0856480092825, 1634.8610368118843, 1667.9166041697868, 1669.2404955745108, 1669.3906724045637, 1670.9222551454486, 1106.9667909962714, 1113.5100555755441, 1116.6761639124725, 1134.8241022641503, 1144.4277861069465, 6310.6213627182515, 6311.116443697886, 6312.210973628296, 6317.652529110943, 6319.304876463585, 6682.665866706645, 6683.732650693918, 6686.398224159849, 6695.296993815677, 6700.195976482853, 6176.7838125666285, 6250.124982502453, 6270.6864656766975, 6273.686315684197, 6281.152044474029, 3598.5501812273465, 3620.3664358737115, 3625.148090198801, 3625.3474374612297, 3626.8911932642063, 6881.346272104142, 6882.070920425678, 6889.311068893071, 6891.689873955684, 6892.310768923167, 6680.866382672417, 6757.831054223684, 6798.184217893884, 6937.895931561031, 6961.095057642474, 6955.61744104069, 6967.860642787217, 6973.616451095137, 6975.930240697731, 6996.370326670624, 6528.543001989815, 6737.86524269502, 6739.393454589111, 6750.0212469192675, 6753.763618273337, 6655.633940133339, 6682.0, 6693.163354580677, 6704.597724136567, 6705.161854768154, 6965.651717414008, 6974.372306492237, 6974.860502790018, 6977.406920411822, 6985.301469852974, 4603.93094103582, 4656.62746980234, 4691.9061618766755, 4692.507286734844, 4693.366395536559, 6758.0, 6767.729290828312, 6772.966135169293, 6774.8645027100165, 6776.15298087739, 6518.869622607616, 6569.244536878129, 6571.769988050395, 6571.901421478586, 6571.967140164268, 6444.323346048597, 6542.443892269211, 6547.181602299713, 6552.868942621026, 6559.180102487189, 6463.062855885776, 6467.902981455188, 6480.25477070143, 6482.092507048923, 6482.902756458562, 6595.340465953366, 6596.736130554724, 6604.636744979072, 6611.239707433707, 6614.536982411185, 6292.244930608356, 6391.680415979089, 6416.486681065597, 6422.518311126591, 6462.870742585216], "unit": "ms", "name": "prototype.html", "value": 4929.067043758312}, {"lowerIsBetter": false, "replicates": [809.1180613131685, 829.746927187208, 886.2555453419127, 910.4400793511988, 966.1304825656912, 14101.294935253234, 14763.630909227275, 14794.408223671067, 14800.6299842504, 14808.481703140393, 13169.144005639651, 13246.735065298688, 13284.269365184911, 13305.467781288737, 13315.267660278705, 754.8716718157926, 757.746155038063, 886.2599729226104, 890.3144578674417, 902.1114202510522, 889.5018789477881, 901.8647202919582, 906.2478143141177, 907.8093600343935, 908.2733812949647, 794.6741835847304, 930.9348345615809, 944.032366824004, 944.4286206844863, 944.7307517357555, 777.4091690315373, 924.1220840201802, 926.8980412154658, 927.9628814847398, 928.5496534180951, 679.2697849811473, 680.0988689985788, 683.9110915580943, 683.9179298484164, 684.7534887440568, 13492.797608035888, 13740.519081832188, 13814.93092534541, 13880.0, 13955.511557095453, 12799.872001280055, 13133.671658208621, 13167.078304518687, 13314.134581252187, 13669.316534173251, 10470.685879423629, 10528.473576321154, 10647.893521064845, 10953.397563134024, 10955.0, 3907.8046097695287, 3911.3546264866263, 3921.4706014687936, 3926.5288165420334, 3926.9410958835642, 49872.75063624695, 50305.24542131871, 51406.0, 51710.48289517094, 51807.22289165665, 11002.50488728009, 11050.502727377287, 11229.55081796719, 11363.715907102223, 11491.54033838637, 20087.799122008593, 20124.39626811198, 20128.19487220495, 20164.495887602632, 20166.19335226604, 7495.962520187364, 7608.467407281437, 7686.116096648845, 7696.6536505857375, 8010.078840933367, 4157.168566286755, 4236.703430759879, 4313.051128751732, 4336.956630433719, 4345.760983145958, 3042.1329920972416, 3075.815451072943, 3083.768717346208, 3084.9383012339626, 3090.3510262844607, 534.6311045378676, 554.6062295769967, 554.9667019978814, 559.5216090242865, 566.3684991234744, 621.6922623301494, 686.6944209826579, 701.9578825270501, 706.2972342519226, 717.8851383778571, 8529.701460448854, 9051.321150913707, 9060.682876099305, 9408.388454750419, 9552.0, 623.3112410786107, 646.8997305417668, 682.2938258902012, 705.3616477088141, 720.8342081321325, 4166.250474459119, 4206.600372964564, 4322.438083049184, 4353.956460435355, 4401.559844015573, 62038.0, 62792.686036569525, 62847.68576157181, 63077.0, 64040.67979660072, 56243.43756562382, 57069.714651426475, 57341.713291433276, 57407.85184296374, 57640.0, 50597.24104138441, 50718.23922641237, 51088.489115109114, 51617.225741613904, 51960.48039519632, 8794.296456283353, 8822.51476168817, 8899.955500222457, 9402.811943761224, 9485.430874147298, 36850.8157459211, 37681.81159094187, 38022.0, 38278.61721382751, 38445.0, 49176.2623560654, 49808.25287620761, 50514.0, 50549.0, 50995.490045099075, 11567.421628918688, 11874.940625296818, 11879.524819007143, 12103.576374826838, 12134.393280335771, 12356.25862448256, 12453.501859925502, 12738.299393533445, 12765.93617031909, 13605.0, 10632.0, 10852.240343175901, 11232.213745038096, 11412.543498259976, 11414.315141091562, 10619.36283822973, 10798.892011079788, 10822.188335874998, 11124.388158651353, 11582.131340149353, 9806.705798826046, 9875.901240987498, 10266.435346056038, 10788.784224315626, 11018.834717479083, 9141.680041198526, 9445.716628501155, 9593.472359020321, 9866.260030497884, 9906.603735850485, 9649.807003860024, 9766.56050477716, 9884.357516761387, 10126.544305506122, 10230.232732545237], "unit": "ms", "name": "yui.html", "value": 6633.10649150028}], "name": "dromaeo_css", "value": 7408.54641886276}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 111.0, 103.0, 108.0, 111.0, 110.0, 98.0, 110.0, 110.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 108.1}, {"lowerIsBetter": true, "replicates": [161.0, 123.0, 155.0, 164.0, 128.0, 158.0, 157.0, 122.0, 161.0, 127.0], "unit": "ms", "name": "audio-beat-detection", "value": 145.6}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 160.0, 158.0, 158.0, 160.0, 159.0, 160.0, 158.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 159.4}, {"lowerIsBetter": true, "replicates": [70.0, 73.0, 78.0, 160.0, 70.0, 73.0, 71.0, 72.0, 154.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 89.4}, {"lowerIsBetter": true, "replicates": [92.0, 91.0, 133.0, 91.0, 91.0, 90.0, 135.0, 91.0, 91.0, 134.0], "unit": "ms", "name": "audio-oscillator", "value": 103.9}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 110.0, 110.0, 109.0, 109.0, 110.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.7}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.5}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [56.0, 56.0, 55.0, 57.0, 56.0, 55.0, 57.0, 56.0, 54.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 55.7}, {"lowerIsBetter": true, "replicates": [63.0, 65.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.0}, {"lowerIsBetter": true, "replicates": [78.0, 79.0, 88.0, 80.0, 80.0, 80.0, 80.0, 80.0, 80.0, 80.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 80.5}, {"lowerIsBetter": true, "replicates": [115.0, 109.0, 109.0, 110.0, 114.0, 111.0, 118.0, 118.0, 124.0, 112.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 114.0}, {"lowerIsBetter": true, "replicates": [145.0, 141.0, 142.0, 154.0, 144.0, 145.0, 147.0, 148.0, 144.0, 133.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 144.3}, {"lowerIsBetter": true, "replicates": [69.0, 66.0, 64.0, 63.0, 65.0, 64.0, 62.0, 63.0, 65.0, 72.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.3}], "name": "kraken", "value": 1480.6999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.01075268817203], "unit": "score", "name": "Richards", "value": 210.11750400000008}, {"lowerIsBetter": false, "replicates": [165.15276630883568], "unit": "score", "name": "DeltaBlue", "value": 400.34449000000006}, {"lowerIsBetter": false, "replicates": [278.78449958182324], "unit": "score", "name": "Encrypt", "value": 954.7912470000005}, {"lowerIsBetter": false, "replicates": [5164.948453608247], "unit": "score", "name": "Decrypt", "value": 51.53604191616765}, {"lowerIsBetter": false, "replicates": [959.6928982725528], "unit": "score", "name": "RayTrace", "value": 771.0685379999996}, {"lowerIsBetter": false, "replicates": [540.2485143165857], "unit": "score", "name": "Earley", "value": 1233.6230130000004}, {"lowerIsBetter": false, "replicates": [10562.5], "unit": "score", "name": "Boyer", "value": 63.09708875739642}, {"lowerIsBetter": false, "replicates": [24190.47619047619], "unit": "score", "name": "RegExp", "value": 37.65882874015749}, {"lowerIsBetter": false, "replicates": [520.5622071837585], "unit": "score", "name": "Splay", "value": 156.54421100000005}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 23283.778193805207}]} 01:47:31 INFO - RETURN: dromaeo_css: 7408.55 01:47:31 INFO - RETURN: kraken: 1480.70 01:47:31 INFO - RETURN: v8_7: 23283.78 01:47:31 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23283.78"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1480.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7408.55"}}} 01:47:31 INFO - Return code: 0 01:47:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 01:47:31 INFO - # TBPL SUCCESS # 01:47:31 INFO - Running post-action listener: _resource_record_post_action 01:47:31 INFO - Running post-run listener: _resource_record_post_run 01:47:32 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 20480; Write bytes: 503541760; Read time: 40; Write time: 384892 01:47:32 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 238968832; Read time: 0; Write time: 242516 01:47:32 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 20480; Write bytes: 264073216; Read time: 40; Write time: 142340 01:47:32 INFO - Running post-run listener: _upload_blobber_files 01:47:32 INFO - Blob upload gear active. 01:47:32 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 01:47:32 INFO - Copying logs to upload dir... 01:47:32 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1255.225444 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-01-26 01:47:32.480224) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-26 01:47:32.483867) ========= 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=7de08804244eb36560b44971000001c3-1453800394.442181-513517803 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009921 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-26 01:47:32.534288) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:47:32.534645) ========= 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=7de08804244eb36560b44971000001c3-1453800394.442181-513517803 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004519 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:47:32.584232) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-26 01:47:32.584574) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-26 01:47:32.584862) ========= ========= Total master_lag: 0.24 =========