builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-033 starttime: 1454342805.23 results: success (0) buildid: 20160201054227 builduid: f3985991ef6e468cab66d89f5ba7d94d revision: aaa48192f8c5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:45.235005) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:45.235493) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:45.235813) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006193 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:45.259325) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:45.259734) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:45.280092) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:45.280488) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017080 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:45.329714) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:45.330075) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:45.330461) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:45.330730) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-01 08:06:45-- 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% 42.6M=0s 2016-02-01 08:06:45 (42.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.184849 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:45.527913) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:45.528258) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.063780 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:45.605372) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:45.606048) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev aaa48192f8c5 --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 aaa48192f8c5 --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=edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-01 08:06:45,600 Setting DEBUG logging. 2016-02-01 08:06:45,600 attempt 1/10 2016-02-01 08:06:45,600 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/aaa48192f8c5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-01 08:06:45,947 unpacking tar archive at: mozilla-beta-aaa48192f8c5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.637402 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:46.256540) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:46.256913) ========= echo aaa48192f8c5 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'aaa48192f8c5'] 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=edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False aaa48192f8c5 program finished with exit code 0 elapsedTime=0.003814 script_repo_revision: 'aaa48192f8c5' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:46.304525) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:46.304845) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-01 08:06:46.315088) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-02-01 08:06:46.315480) ========= /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=edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:06:46 INFO - MultiFileLogger online at 20160201 08:06:46 in /builds/slave/test-pgo 08:06:46 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 08:06:46 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 08:06:46 INFO - {'append_to_log': False, 08:06:46 INFO - 'base_work_dir': '/builds/slave/test-pgo', 08:06:46 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:06:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 08:06:46 INFO - 'branch': 'Mozilla-Beta', 08:06:46 INFO - 'buildbot_json_path': 'buildprops.json', 08:06:46 INFO - 'config_files': ('talos/linux_config.py',), 08:06:46 INFO - 'default_actions': ('clobber', 08:06:46 INFO - 'read-buildbot-config', 08:06:46 INFO - 'download-and-extract', 08:06:46 INFO - 'populate-webroot', 08:06:46 INFO - 'create-virtualenv', 08:06:46 INFO - 'install', 08:06:46 INFO - 'run-tests'), 08:06:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:06:46 INFO - 'download_minidump_stackwalk': True, 08:06:46 INFO - 'download_symbols': 'ondemand', 08:06:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:06:46 INFO - 'tooltool.py': '/tools/tooltool.py', 08:06:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:06:46 INFO - '/tools/misc-python/virtualenv.py')}, 08:06:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:06:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:06:46 INFO - 'installer_path': 'installer.exe', 08:06:46 INFO - 'log_level': 'info', 08:06:46 INFO - 'log_name': 'talos', 08:06:46 INFO - 'log_to_console': True, 08:06:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:06:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:06:46 INFO - 'opt_config_files': (), 08:06:46 INFO - 'pip_index': False, 08:06:46 INFO - 'sps_profile': False, 08:06:46 INFO - 'sps_profile_interval': 0, 08:06:46 INFO - 'suite': 'dromaeojs', 08:06:46 INFO - 'system_bits': '32', 08:06:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:06:46 INFO - 'title': 'talos-linux64-ix-033', 08:06:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:06:46 INFO - 'use_talos_json': True, 08:06:46 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 08:06:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:06:46 INFO - 'work_dir': 'build'} 08:06:46 INFO - ##### 08:06:46 INFO - ##### Running clobber step. 08:06:46 INFO - ##### 08:06:46 INFO - Running pre-action listener: _resource_record_pre_action 08:06:46 INFO - Running main action method: clobber 08:06:46 INFO - rmtree: /builds/slave/test-pgo/build 08:06:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 08:06:47 INFO - Running post-action listener: _resource_record_post_action 08:06:47 INFO - ##### 08:06:47 INFO - ##### Running read-buildbot-config step. 08:06:47 INFO - ##### 08:06:47 INFO - Running pre-action listener: _resource_record_pre_action 08:06:47 INFO - Running main action method: read_buildbot_config 08:06:47 INFO - Using buildbot properties: 08:06:47 INFO - { 08:06:47 INFO - "properties": { 08:06:47 INFO - "buildnumber": 95, 08:06:47 INFO - "product": "firefox", 08:06:47 INFO - "script_repo_revision": "production", 08:06:47 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 08:06:47 INFO - "repository": "", 08:06:47 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 08:06:47 INFO - "buildid": "20160201054227", 08:06:47 INFO - "slavename": "talos-linux64-ix-033", 08:06:47 INFO - "pgo_build": "True", 08:06:47 INFO - "basedir": "/builds/slave/test-pgo", 08:06:47 INFO - "project": "", 08:06:47 INFO - "platform": "ubuntu64_hw", 08:06:47 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:06:47 INFO - "slavebuilddir": "test", 08:06:47 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 08:06:47 INFO - "branch": "mozilla-beta", 08:06:47 INFO - "repo_path": "releases/mozilla-beta", 08:06:47 INFO - "stage_platform": "linux64-pgo", 08:06:47 INFO - "builduid": "f3985991ef6e468cab66d89f5ba7d94d", 08:06:47 INFO - "revision": "aaa48192f8c5" 08:06:47 INFO - }, 08:06:47 INFO - "sourcestamp": { 08:06:47 INFO - "repository": "", 08:06:47 INFO - "hasPatch": false, 08:06:47 INFO - "project": "", 08:06:47 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 08:06:47 INFO - "changes": [ 08:06:47 INFO - { 08:06:47 INFO - "category": null, 08:06:47 INFO - "files": [ 08:06:47 INFO - { 08:06:47 INFO - "url": null, 08:06:47 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/firefox-45.0.en-US.linux-x86_64.tar.bz2" 08:06:47 INFO - } 08:06:47 INFO - ], 08:06:47 INFO - "repository": "", 08:06:47 INFO - "rev": "aaa48192f8c5", 08:06:47 INFO - "who": "sendchange", 08:06:47 INFO - "when": 1454342800, 08:06:47 INFO - "number": 7543465, 08:06:47 INFO - "comments": "Bug 1238753 - Don_t skip the call to StartRemoteDrawing in from BasicCompositor::BeginFrame if the invalid region is empty. r=mattwoodrow, a=sylvestre", 08:06:47 INFO - "project": "", 08:06:47 INFO - "at": "Mon 01 Feb 2016 08:06:40", 08:06:47 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 08:06:47 INFO - "revlink": "", 08:06:47 INFO - "properties": [ 08:06:47 INFO - [ 08:06:47 INFO - "buildid", 08:06:47 INFO - "20160201054227", 08:06:47 INFO - "Change" 08:06:47 INFO - ], 08:06:47 INFO - [ 08:06:47 INFO - "builduid", 08:06:47 INFO - "f3985991ef6e468cab66d89f5ba7d94d", 08:06:47 INFO - "Change" 08:06:47 INFO - ], 08:06:47 INFO - [ 08:06:47 INFO - "pgo_build", 08:06:47 INFO - "True", 08:06:47 INFO - "Change" 08:06:47 INFO - ] 08:06:47 INFO - ], 08:06:47 INFO - "revision": "aaa48192f8c5" 08:06:47 INFO - } 08:06:47 INFO - ], 08:06:47 INFO - "revision": "aaa48192f8c5" 08:06:47 INFO - } 08:06:47 INFO - } 08:06:47 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/firefox-45.0.en-US.linux-x86_64.tar.bz2. 08:06:47 INFO - Running post-action listener: _resource_record_post_action 08:06:47 INFO - ##### 08:06:47 INFO - ##### Running download-and-extract step. 08:06:47 INFO - ##### 08:06:47 INFO - Running pre-action listener: _resource_record_pre_action 08:06:47 INFO - Running main action method: download_and_extract 08:06:47 INFO - mkdir: /builds/slave/test-pgo/build/tests 08:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:06:47 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/test_packages.json 08:06:47 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 08:06:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 08:06:47 INFO - Downloaded 1270 bytes. 08:06:47 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 08:06:47 INFO - Using the following test package requirements: 08:06:47 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:06:47 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:06:47 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 08:06:47 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:06:47 INFO - u'jsshell-linux-x86_64.zip'], 08:06:47 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:06:47 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 08:06:47 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:06:47 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:06:47 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 08:06:47 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:06:47 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 08:06:47 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:06:47 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 08:06:47 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:06:47 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:06:47 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 08:06:47 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:06:47 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:06:47 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/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 08:06:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/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 08:06:52 INFO - Downloaded 21581188 bytes. 08:06:52 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 08:06:52 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:06:52 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 08:06:53 INFO - Return code: 0 08:06:53 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 08:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:06:53 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:06:53 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/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 08:06:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/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 08:06:53 INFO - Downloaded 21581188 bytes. 08:06:53 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 08:06:53 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:06:53 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 08:06:55 INFO - Return code: 0 08:06:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:06:55 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 08:06:55 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/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 08:06:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/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 08:06:58 INFO - Downloaded 11132038 bytes. 08:06:58 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 08:06:58 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 08:06:58 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 08:06:58 INFO - Return code: 0 08:06:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:06:58 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:06:58 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/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 08:06:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/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 08:07:08 INFO - Downloaded 57943010 bytes. 08:07:08 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:07:08 INFO - mkdir: /builds/slave/test-pgo/properties 08:07:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 08:07:08 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 08:07:08 INFO - Contents: 08:07:08 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:07:08 INFO - Running post-action listener: _resource_record_post_action 08:07:08 INFO - Running post-action listener: set_extra_try_arguments 08:07:08 INFO - ##### 08:07:08 INFO - ##### Running populate-webroot step. 08:07:08 INFO - ##### 08:07:08 INFO - Running pre-action listener: _resource_record_pre_action 08:07:08 INFO - Running main action method: populate_webroot 08:07:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:07:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:07:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:07:08 INFO - '--tppagecycles', 08:07:08 INFO - '7'], 08:07:08 INFO - 'tests': ['tsvgm']}}, 08:07:08 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 08:07:08 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 08:07:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:07:08 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:07:08 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 08:07:08 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:07:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:07:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:08 INFO - 'talos_options': ['--e10s'], 08:07:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:07:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:08 INFO - 'tests': ['damp', 'tps']}, 08:07:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:08 INFO - 'talos_options': ['--e10s'], 08:07:08 INFO - 'tests': ['damp', 'tps']}, 08:07:08 INFO - 'g3': {'tests': ['dromaeo_dom']}, 08:07:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:07:08 INFO - 'other': {'tests': ['a11yr', 08:07:08 INFO - 'ts_paint', 08:07:08 INFO - 'tpaint', 08:07:08 INFO - 'sessionrestore', 08:07:08 INFO - 'sessionrestore_no_auto_restore']}, 08:07:08 INFO - 'other-e10s': {'talos_options': ['--e10s'], 08:07:08 INFO - 'tests': ['a11yr', 08:07:08 INFO - 'ts_paint', 08:07:08 INFO - 'tpaint', 08:07:08 INFO - 'sessionrestore', 08:07:08 INFO - 'sessionrestore_no_auto_restore']}, 08:07:08 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 08:07:08 INFO - 'tests': ['a11yr', 08:07:08 INFO - 'ts_paint', 08:07:08 INFO - 'tpaint', 08:07:08 INFO - 'sessionrestore', 08:07:08 INFO - 'sessionrestore_no_auto_restore']}, 08:07:08 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 08:07:08 INFO - 'tests': ['a11yr', 08:07:08 INFO - 'ts_paint', 08:07:08 INFO - 'tpaint', 08:07:08 INFO - 'sessionrestore', 08:07:08 INFO - 'sessionrestore_no_auto_restore']}, 08:07:08 INFO - 'other_l64': {'tests': ['a11yr', 08:07:08 INFO - 'ts_paint', 08:07:08 INFO - 'tpaint', 08:07:08 INFO - 'sessionrestore', 08:07:08 INFO - 'sessionrestore_no_auto_restore']}, 08:07:08 INFO - 'other_nol64': {'tests': ['a11yr', 08:07:08 INFO - 'ts_paint', 08:07:08 INFO - 'tpaint', 08:07:08 INFO - 'sessionrestore', 08:07:08 INFO - 'sessionrestore_no_auto_restore']}, 08:07:08 INFO - 'svgr': {'tests': ['tsvgx', 08:07:08 INFO - 'tsvgr_opacity', 08:07:08 INFO - 'tart', 08:07:08 INFO - 'tscrollx', 08:07:08 INFO - 'cart']}, 08:07:08 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 08:07:08 INFO - 'tests': ['tsvgx', 08:07:08 INFO - 'tsvgr_opacity', 08:07:08 INFO - 'tart', 08:07:08 INFO - 'tscrollx', 08:07:08 INFO - 'cart']}, 08:07:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:08 INFO - 'tests': ['tp5o']}, 08:07:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:08 INFO - 'talos_options': ['--e10s'], 08:07:08 INFO - 'tests': ['tp5o']}, 08:07:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:08 INFO - 'talos_options': ['--xperf_path', 08:07:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:07:08 INFO - 'tests': ['tp5n']}, 08:07:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:08 INFO - 'talos_options': ['--e10s', 08:07:08 INFO - '--xperf_path', 08:07:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:07:08 INFO - 'tests': ['tp5n']}}, 08:07:08 INFO - 'talos.zip': {'path': '', 08:07:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:07:08 INFO - Running post-action listener: _resource_record_post_action 08:07:08 INFO - ##### 08:07:08 INFO - ##### Running create-virtualenv step. 08:07:08 INFO - ##### 08:07:08 INFO - Running pre-action listener: _resource_record_pre_action 08:07:08 INFO - Running main action method: create_virtualenv 08:07:08 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 08:07:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 08:07:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 08:07:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:07:08 INFO - Using real prefix '/usr' 08:07:08 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 08:07:08 INFO - Installing distribute.............................................................................................................................................................................................done. 08:07:10 INFO - Installing pip.................done. 08:07:10 INFO - Return code: 0 08:07:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 08:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:10 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 0x7f887d211f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xede8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfab600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a350>, '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 0x103b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b6e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:07:10 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 08:07:10 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 08:07:10 INFO - Using env: {'DISPLAY': ':0', 08:07:10 INFO - 'HOME': '/home/cltbld', 08:07:10 INFO - 'LANG': 'en_US.UTF-8', 08:07:10 INFO - 'LANGUAGE': 'en_US:en', 08:07:10 INFO - 'LOGNAME': 'cltbld', 08:07:10 INFO - 'MAIL': '/var/mail/cltbld', 08:07:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:10 INFO - 'MOZ_NO_REMOTE': '1', 08:07:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:10 INFO - 'NO_EM_RESTART': '1', 08:07:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:07:10 INFO - 'PWD': '/builds/slave/test-pgo', 08:07:10 INFO - 'SHELL': '/bin/bash', 08:07:10 INFO - 'SHLVL': '1', 08:07:10 INFO - 'TERM': 'linux', 08:07:10 INFO - 'TMOUT': '86400', 08:07:10 INFO - 'USER': 'cltbld', 08:07:10 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607', 08:07:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:10 INFO - '_': '/tools/buildbot/bin/python'} 08:07:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:10 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:07:10 INFO - Cleaning up... 08:07:10 INFO - Return code: 0 08:07:10 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 08:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:10 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 0x7f887d211f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xede8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfab600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a350>, '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 0x103b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b6e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:07:10 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 08:07:10 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 08:07:10 INFO - Using env: {'DISPLAY': ':0', 08:07:10 INFO - 'HOME': '/home/cltbld', 08:07:10 INFO - 'LANG': 'en_US.UTF-8', 08:07:10 INFO - 'LANGUAGE': 'en_US:en', 08:07:10 INFO - 'LOGNAME': 'cltbld', 08:07:10 INFO - 'MAIL': '/var/mail/cltbld', 08:07:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:10 INFO - 'MOZ_NO_REMOTE': '1', 08:07:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:10 INFO - 'NO_EM_RESTART': '1', 08:07:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:07:10 INFO - 'PWD': '/builds/slave/test-pgo', 08:07:10 INFO - 'SHELL': '/bin/bash', 08:07:10 INFO - 'SHLVL': '1', 08:07:10 INFO - 'TERM': 'linux', 08:07:10 INFO - 'TMOUT': '86400', 08:07:10 INFO - 'USER': 'cltbld', 08:07:10 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607', 08:07:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:10 INFO - '_': '/tools/buildbot/bin/python'} 08:07:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:11 INFO - Downloading/unpacking psutil>=0.7.1 08:07:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 08:07:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:07:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:07:12 INFO - Installing collected packages: psutil 08:07:12 INFO - Running setup.py install for psutil 08:07:13 INFO - building 'psutil._psutil_linux' extension 08:07:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:07:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:07:13 INFO - building 'psutil._psutil_posix' extension 08:07:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:07:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:07:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:07:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:07:13 INFO - Successfully installed psutil 08:07:13 INFO - Cleaning up... 08:07:13 INFO - Return code: 0 08:07:13 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 08:07:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:13 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 0x7f887d211f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xede8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfab600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a350>, '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 0x103b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b6e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:07:13 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 08:07:13 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 08:07:13 INFO - Using env: {'DISPLAY': ':0', 08:07:13 INFO - 'HOME': '/home/cltbld', 08:07:13 INFO - 'LANG': 'en_US.UTF-8', 08:07:13 INFO - 'LANGUAGE': 'en_US:en', 08:07:13 INFO - 'LOGNAME': 'cltbld', 08:07:13 INFO - 'MAIL': '/var/mail/cltbld', 08:07:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:13 INFO - 'MOZ_NO_REMOTE': '1', 08:07:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:13 INFO - 'NO_EM_RESTART': '1', 08:07:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:07:13 INFO - 'PWD': '/builds/slave/test-pgo', 08:07:13 INFO - 'SHELL': '/bin/bash', 08:07:13 INFO - 'SHLVL': '1', 08:07:13 INFO - 'TERM': 'linux', 08:07:13 INFO - 'TMOUT': '86400', 08:07:13 INFO - 'USER': 'cltbld', 08:07:13 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607', 08:07:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:13 INFO - '_': '/tools/buildbot/bin/python'} 08:07:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:13 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:07:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:15 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:07:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:07:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:07:15 INFO - Installing collected packages: mozsystemmonitor 08:07:15 INFO - Running setup.py install for mozsystemmonitor 08:07:15 INFO - Successfully installed mozsystemmonitor 08:07:15 INFO - Cleaning up... 08:07:15 INFO - Return code: 0 08:07:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 08:07:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:15 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 0x7f887d211f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xede8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfab600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a350>, '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 0x103b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b6e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:07:15 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 08:07:15 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 08:07:15 INFO - Using env: {'DISPLAY': ':0', 08:07:15 INFO - 'HOME': '/home/cltbld', 08:07:15 INFO - 'LANG': 'en_US.UTF-8', 08:07:15 INFO - 'LANGUAGE': 'en_US:en', 08:07:15 INFO - 'LOGNAME': 'cltbld', 08:07:15 INFO - 'MAIL': '/var/mail/cltbld', 08:07:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:15 INFO - 'MOZ_NO_REMOTE': '1', 08:07:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:15 INFO - 'NO_EM_RESTART': '1', 08:07:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:07:15 INFO - 'PWD': '/builds/slave/test-pgo', 08:07:15 INFO - 'SHELL': '/bin/bash', 08:07:15 INFO - 'SHLVL': '1', 08:07:15 INFO - 'TERM': 'linux', 08:07:15 INFO - 'TMOUT': '86400', 08:07:15 INFO - 'USER': 'cltbld', 08:07:15 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607', 08:07:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:15 INFO - '_': '/tools/buildbot/bin/python'} 08:07:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:15 INFO - Downloading/unpacking blobuploader==1.2.4 08:07:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:17 INFO - Downloading blobuploader-1.2.4.tar.gz 08:07:17 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:07:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:17 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 08:07:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:07:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:18 INFO - Downloading docopt-0.6.1.tar.gz 08:07:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 08:07:18 INFO - Installing collected packages: blobuploader, requests, docopt 08:07:18 INFO - Running setup.py install for blobuploader 08:07:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:07:18 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 08:07:18 INFO - Running setup.py install for requests 08:07:18 INFO - Running setup.py install for docopt 08:07:18 INFO - Successfully installed blobuploader requests docopt 08:07:18 INFO - Cleaning up... 08:07:18 INFO - Return code: 0 08:07:18 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:07:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:18 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 0x7f887d211f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xede8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfab600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a350>, '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 0x103b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b6e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:07:18 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 08:07:18 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 08:07:18 INFO - Using env: {'DISPLAY': ':0', 08:07:18 INFO - 'HOME': '/home/cltbld', 08:07:18 INFO - 'LANG': 'en_US.UTF-8', 08:07:18 INFO - 'LANGUAGE': 'en_US:en', 08:07:18 INFO - 'LOGNAME': 'cltbld', 08:07:18 INFO - 'MAIL': '/var/mail/cltbld', 08:07:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:18 INFO - 'MOZ_NO_REMOTE': '1', 08:07:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:18 INFO - 'NO_EM_RESTART': '1', 08:07:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:07:18 INFO - 'PWD': '/builds/slave/test-pgo', 08:07:18 INFO - 'SHELL': '/bin/bash', 08:07:18 INFO - 'SHLVL': '1', 08:07:18 INFO - 'TERM': 'linux', 08:07:18 INFO - 'TMOUT': '86400', 08:07:18 INFO - 'USER': 'cltbld', 08:07:18 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607', 08:07:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:18 INFO - '_': '/tools/buildbot/bin/python'} 08:07:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:07:19 INFO - Running setup.py (path:/tmp/pip-zb0TIZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:07:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:07:19 INFO - Running setup.py (path:/tmp/pip-37oqGz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:07:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:07:19 INFO - Running setup.py (path:/tmp/pip-oHdE3p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:07:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:07:19 INFO - Running setup.py (path:/tmp/pip-SdCGA8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:07:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:07:19 INFO - Running setup.py (path:/tmp/pip-cK1Z1b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:07:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:07:19 INFO - Running setup.py (path:/tmp/pip-5qMk60-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:07:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:07:19 INFO - Running setup.py (path:/tmp/pip-K_CAYz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:07:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:07:19 INFO - Running setup.py (path:/tmp/pip-SVQmEd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:07:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:07:19 INFO - Running setup.py (path:/tmp/pip-Jbk_7R-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:07:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:07:19 INFO - Running setup.py (path:/tmp/pip-wMZyXK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:07:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:07:19 INFO - Running setup.py (path:/tmp/pip-vRejRS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:07:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:07:19 INFO - Running setup.py (path:/tmp/pip-Kxj3Fc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:07:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:07:20 INFO - Running setup.py (path:/tmp/pip-Z0VBvA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:07:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:07:20 INFO - Running setup.py (path:/tmp/pip-WuIjWv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:07:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:07:20 INFO - Running setup.py (path:/tmp/pip-t_GlhH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:07:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:07:20 INFO - Running setup.py (path:/tmp/pip-Um4i6l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:07:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:07:20 INFO - Running setup.py (path:/tmp/pip-phDQFp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:07:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:07:20 INFO - Running setup.py install for manifestparser 08:07:20 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 08:07:20 INFO - Running setup.py install for mozcrash 08:07:20 INFO - Running setup.py install for mozdebug 08:07:20 INFO - Running setup.py install for mozdevice 08:07:20 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 08:07:20 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 08:07:20 INFO - Running setup.py install for mozfile 08:07:20 INFO - Running setup.py install for mozhttpd 08:07:21 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 08:07:21 INFO - Running setup.py install for mozinfo 08:07:21 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 08:07:21 INFO - Running setup.py install for mozInstall 08:07:21 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 08:07:21 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 08:07:21 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 08:07:21 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 08:07:21 INFO - Running setup.py install for mozleak 08:07:21 INFO - Running setup.py install for mozlog 08:07:21 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 08:07:21 INFO - Running setup.py install for moznetwork 08:07:21 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 08:07:21 INFO - Running setup.py install for mozprocess 08:07:21 INFO - Running setup.py install for mozprofile 08:07:21 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 08:07:21 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 08:07:21 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 08:07:21 INFO - Running setup.py install for mozrunner 08:07:21 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 08:07:21 INFO - Running setup.py install for mozscreenshot 08:07:21 INFO - Running setup.py install for moztest 08:07:22 INFO - Running setup.py install for mozversion 08:07:22 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 08:07:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:07:22 INFO - Cleaning up... 08:07:22 INFO - Return code: 0 08:07:22 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:22 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 0x7f887d211f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xede8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfab600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a350>, '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 0x103b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b6e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:07:22 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 08:07:22 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 08:07:22 INFO - Using env: {'DISPLAY': ':0', 08:07:22 INFO - 'HOME': '/home/cltbld', 08:07:22 INFO - 'LANG': 'en_US.UTF-8', 08:07:22 INFO - 'LANGUAGE': 'en_US:en', 08:07:22 INFO - 'LOGNAME': 'cltbld', 08:07:22 INFO - 'MAIL': '/var/mail/cltbld', 08:07:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:22 INFO - 'MOZ_NO_REMOTE': '1', 08:07:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:22 INFO - 'NO_EM_RESTART': '1', 08:07:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:07:22 INFO - 'PWD': '/builds/slave/test-pgo', 08:07:22 INFO - 'SHELL': '/bin/bash', 08:07:22 INFO - 'SHLVL': '1', 08:07:22 INFO - 'TERM': 'linux', 08:07:22 INFO - 'TMOUT': '86400', 08:07:22 INFO - 'USER': 'cltbld', 08:07:22 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607', 08:07:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:22 INFO - '_': '/tools/buildbot/bin/python'} 08:07:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:07:22 INFO - Running setup.py (path:/tmp/pip-NyOqM5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 08:07:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:07:22 INFO - Running setup.py (path:/tmp/pip-FXRfO1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 08:07:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:07:22 INFO - Running setup.py (path:/tmp/pip-uIUcim-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 08:07:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:07:22 INFO - Running setup.py (path:/tmp/pip-KMVncs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 08:07:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:07:22 INFO - Running setup.py (path:/tmp/pip-GPXXB3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 08:07:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:07:22 INFO - Running setup.py (path:/tmp/pip-HXfOHq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 08:07:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:07:22 INFO - Running setup.py (path:/tmp/pip-YhpOuO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 08:07:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:07:22 INFO - Running setup.py (path:/tmp/pip-l3HtO5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 08:07:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:07:23 INFO - Running setup.py (path:/tmp/pip-0XWCrm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 08:07:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:07:23 INFO - Running setup.py (path:/tmp/pip-JdHMRr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 08:07:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:07:23 INFO - Running setup.py (path:/tmp/pip-zlXULy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 08:07:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:07:23 INFO - Running setup.py (path:/tmp/pip-NoU_Y4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 08:07:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:07:23 INFO - Running setup.py (path:/tmp/pip-ExoHK6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:07:23 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)) 08:07:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:07:23 INFO - Running setup.py (path:/tmp/pip-yfFqj9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 08:07:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:07:23 INFO - Running setup.py (path:/tmp/pip-o41kW2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 08:07:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:07:23 INFO - Running setup.py (path:/tmp/pip-Px2ywe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 08:07:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:07:23 INFO - Running setup.py (path:/tmp/pip-TH_0eX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 08:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 08:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 08:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 08:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 08:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 08:07:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 08:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:25 INFO - Downloading blessings-1.6.tar.gz 08:07:25 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 08:07:25 INFO - Installing collected packages: blessings 08:07:25 INFO - Running setup.py install for blessings 08:07:25 INFO - Successfully installed blessings 08:07:25 INFO - Cleaning up... 08:07:25 INFO - Return code: 0 08:07:25 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 08:07:25 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:07:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:07:25 INFO - Reading from file tmpfile_stdout 08:07:25 INFO - Current package versions: 08:07:25 INFO - argparse == 1.2.1 08:07:25 INFO - blessings == 1.6 08:07:25 INFO - blobuploader == 1.2.4 08:07:25 INFO - docopt == 0.6.1 08:07:25 INFO - manifestparser == 1.1 08:07:25 INFO - mozInstall == 1.12 08:07:25 INFO - mozcrash == 0.16 08:07:25 INFO - mozdebug == 0.1 08:07:25 INFO - mozdevice == 0.47 08:07:25 INFO - mozfile == 1.2 08:07:25 INFO - mozhttpd == 0.7 08:07:25 INFO - mozinfo == 0.9 08:07:25 INFO - mozleak == 0.1 08:07:25 INFO - mozlog == 3.1 08:07:25 INFO - moznetwork == 0.27 08:07:25 INFO - mozprocess == 0.22 08:07:25 INFO - mozprofile == 0.27 08:07:25 INFO - mozrunner == 6.11 08:07:25 INFO - mozscreenshot == 0.1 08:07:25 INFO - mozsystemmonitor == 0.0 08:07:25 INFO - moztest == 0.7 08:07:25 INFO - mozversion == 1.4 08:07:25 INFO - psutil == 3.1.1 08:07:25 INFO - requests == 1.2.3 08:07:25 INFO - wsgiref == 0.1.2 08:07:25 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:25 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 0x7f887d211f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xede8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfab600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a350>, '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 0x103b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b6e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:07:25 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 08:07:25 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 08:07:25 INFO - Using env: {'DISPLAY': ':0', 08:07:25 INFO - 'HOME': '/home/cltbld', 08:07:25 INFO - 'LANG': 'en_US.UTF-8', 08:07:25 INFO - 'LANGUAGE': 'en_US:en', 08:07:25 INFO - 'LOGNAME': 'cltbld', 08:07:25 INFO - 'MAIL': '/var/mail/cltbld', 08:07:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:25 INFO - 'MOZ_NO_REMOTE': '1', 08:07:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:25 INFO - 'NO_EM_RESTART': '1', 08:07:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:07:25 INFO - 'PWD': '/builds/slave/test-pgo', 08:07:25 INFO - 'SHELL': '/bin/bash', 08:07:25 INFO - 'SHLVL': '1', 08:07:25 INFO - 'TERM': 'linux', 08:07:25 INFO - 'TMOUT': '86400', 08:07:25 INFO - 'USER': 'cltbld', 08:07:25 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607', 08:07:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:25 INFO - '_': '/tools/buildbot/bin/python'} 08:07:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:26 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)) 08:07:26 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)) 08:07:26 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)) 08:07:26 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)) 08:07:26 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)) 08:07:26 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)) 08:07:26 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)) 08:07:26 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)) 08:07:26 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)) 08:07:26 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)) 08:07:26 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)) 08:07:26 INFO - Cleaning up... 08:07:26 INFO - Return code: 0 08:07:26 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 08:07:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:26 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 0x7f887d211f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xede8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfab600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a350>, '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 0x103b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b6e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:07:26 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 08:07:26 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 08:07:26 INFO - Using env: {'DISPLAY': ':0', 08:07:26 INFO - 'HOME': '/home/cltbld', 08:07:26 INFO - 'LANG': 'en_US.UTF-8', 08:07:26 INFO - 'LANGUAGE': 'en_US:en', 08:07:26 INFO - 'LOGNAME': 'cltbld', 08:07:26 INFO - 'MAIL': '/var/mail/cltbld', 08:07:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:26 INFO - 'MOZ_NO_REMOTE': '1', 08:07:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:26 INFO - 'NO_EM_RESTART': '1', 08:07:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:07:26 INFO - 'PWD': '/builds/slave/test-pgo', 08:07:26 INFO - 'SHELL': '/bin/bash', 08:07:26 INFO - 'SHLVL': '1', 08:07:26 INFO - 'TERM': 'linux', 08:07:26 INFO - 'TMOUT': '86400', 08:07:26 INFO - 'USER': 'cltbld', 08:07:26 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607', 08:07:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:26 INFO - '_': '/tools/buildbot/bin/python'} 08:07:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:26 INFO - Downloading/unpacking jsonschema 08:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:07:28 INFO - Downloading/unpacking functools32 (from jsonschema) 08:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:28 INFO - Downloading functools32-3.2.3-2.tar.gz 08:07:28 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 08:07:28 INFO - warning: no files found matching '*.txt' 08:07:28 INFO - no previously-included directories found matching 'build' 08:07:28 INFO - no previously-included directories found matching 'dist' 08:07:28 INFO - no previously-included directories found matching '.git*' 08:07:28 INFO - Installing collected packages: jsonschema, functools32 08:07:28 INFO - Running setup.py install for functools32 08:07:28 INFO - warning: no files found matching '*.txt' 08:07:28 INFO - no previously-included directories found matching 'build' 08:07:28 INFO - no previously-included directories found matching 'dist' 08:07:28 INFO - no previously-included directories found matching '.git*' 08:07:28 INFO - Successfully installed jsonschema functools32 08:07:28 INFO - Cleaning up... 08:07:28 INFO - Return code: 0 08:07:28 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 08:07:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:28 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 0x7f887d211f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xede8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfab600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a350>, '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 0x103b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b6e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:07:28 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 08:07:28 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 08:07:28 INFO - Using env: {'DISPLAY': ':0', 08:07:28 INFO - 'HOME': '/home/cltbld', 08:07:28 INFO - 'LANG': 'en_US.UTF-8', 08:07:28 INFO - 'LANGUAGE': 'en_US:en', 08:07:28 INFO - 'LOGNAME': 'cltbld', 08:07:28 INFO - 'MAIL': '/var/mail/cltbld', 08:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:28 INFO - 'MOZ_NO_REMOTE': '1', 08:07:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:28 INFO - 'NO_EM_RESTART': '1', 08:07:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:07:28 INFO - 'PWD': '/builds/slave/test-pgo', 08:07:28 INFO - 'SHELL': '/bin/bash', 08:07:28 INFO - 'SHLVL': '1', 08:07:28 INFO - 'TERM': 'linux', 08:07:28 INFO - 'TMOUT': '86400', 08:07:28 INFO - 'USER': 'cltbld', 08:07:28 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607', 08:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:28 INFO - '_': '/tools/buildbot/bin/python'} 08:07:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:28 INFO - Downloading/unpacking flake8 08:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:07:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:31 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:07:31 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:31 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:31 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 08:07:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:07:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:07:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:07:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:07:31 INFO - no previously-included directories found matching 'docs/_build' 08:07:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:07:31 INFO - Running setup.py install for pep8 08:07:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:07:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:07:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:07:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:07:31 INFO - no previously-included directories found matching 'docs/_build' 08:07:31 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 08:07:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:07:31 INFO - Cleaning up... 08:07:32 INFO - Return code: 0 08:07:32 INFO - Running post-action listener: _resource_record_post_action 08:07:32 INFO - Running post-action listener: _start_resource_monitoring 08:07:32 INFO - Starting resource monitoring. 08:07:32 INFO - ##### 08:07:32 INFO - ##### Running install step. 08:07:32 INFO - ##### 08:07:32 INFO - Running pre-action listener: _resource_record_pre_action 08:07:32 INFO - Running main action method: install 08:07:32 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:07:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:07:32 INFO - Reading from file tmpfile_stdout 08:07:32 INFO - Detecting whether we're running mozinstall >=1.0... 08:07:32 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 08:07:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 08:07:32 INFO - Reading from file tmpfile_stdout 08:07:32 INFO - Output received: 08:07:32 INFO - Usage: mozinstall [options] installer 08:07:32 INFO - Options: 08:07:32 INFO - -h, --help show this help message and exit 08:07:32 INFO - -d DEST, --destination=DEST 08:07:32 INFO - Directory to install application into. [default: 08:07:32 INFO - "/builds/slave/test-pgo"] 08:07:32 INFO - --app=APP Application being installed. [default: firefox] 08:07:32 INFO - mkdir: /builds/slave/test-pgo/build/application 08:07:32 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'] 08:07:32 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 08:07:47 INFO - Reading from file tmpfile_stdout 08:07:47 INFO - Output received: 08:07:47 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 08:07:47 INFO - Running post-action listener: _resource_record_post_action 08:07:47 INFO - ##### 08:07:47 INFO - ##### Running run-tests step. 08:07:47 INFO - ##### 08:07:47 INFO - Running pre-action listener: _resource_record_pre_action 08:07:47 INFO - Running main action method: run_tests 08:07:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 08:07:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 08:07:47 INFO - Python 2.7.3 08:07:47 INFO - Return code: 0 08:07:47 INFO - grabbing minidump binary from tooltool 08:07:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:47 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 0x103a350>, '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 0x103b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 08:07:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 08:07:47 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:07:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:07:47 INFO - Return code: 0 08:07:47 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 08:07:47 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 08:07:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 08:07:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 08:07:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 08:07:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 08:07:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 08:07:48 INFO - Return code: 0 08:07:48 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/1454334147/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 08:07:48 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/1454334147/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --webServer localhost 08:07:48 INFO - Using env: {'DISPLAY': ':0', 08:07:48 INFO - 'HOME': '/home/cltbld', 08:07:48 INFO - 'LANG': 'en_US.UTF-8', 08:07:48 INFO - 'LANGUAGE': 'en_US:en', 08:07:48 INFO - 'LOGNAME': 'cltbld', 08:07:48 INFO - 'MAIL': '/var/mail/cltbld', 08:07:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:07:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 08:07:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:48 INFO - 'MOZ_NO_REMOTE': '1', 08:07:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:07:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:48 INFO - 'NO_EM_RESTART': '1', 08:07:48 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:07:48 INFO - 'PWD': '/builds/slave/test-pgo', 08:07:48 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 08:07:48 INFO - 'SHELL': '/bin/bash', 08:07:48 INFO - 'SHLVL': '1', 08:07:48 INFO - 'TERM': 'linux', 08:07:48 INFO - 'TMOUT': '86400', 08:07:48 INFO - 'USER': 'cltbld', 08:07:48 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607', 08:07:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:48 INFO - '_': '/tools/buildbot/bin/python'} 08:07:48 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/1454334147/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost'] with output_timeout 3600 08:07:48 INFO - mozversion INFO | application_buildid: 20160201054227 08:07:48 INFO - mozversion INFO | application_changeset: aaa48192f8c5553a914e7bcb4eb89527a9eec5fe 08:07:48 INFO - mozversion INFO | application_display_name: Nightly 08:07:48 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:07:48 INFO - mozversion INFO | application_name: Firefox 08:07:48 INFO - mozversion INFO | application_remotingname: firefox 08:07:48 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:07:48 INFO - mozversion INFO | application_vendor: Mozilla 08:07:48 INFO - mozversion INFO | application_version: 45.0 08:07:48 INFO - mozversion INFO | platform_buildid: 20160201054227 08:07:48 INFO - mozversion INFO | platform_changeset: aaa48192f8c5553a914e7bcb4eb89527a9eec5fe 08:07:48 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:07:48 INFO - mozversion INFO | platform_version: 45.0 08:07:48 INFO - 2016-02-01 08:07:48,510 DEBUG : using testdate: 1454342868 08:07:48 INFO - 2016-02-01 08:07:48,510 DEBUG : actual date: 1454342868 08:07:48 INFO - 2016-02-01 08:07:48,519 INFO : starting webserver on 'localhost:58603' 08:07:48 INFO - 2016-02-01 08:07:48,520 INFO : Starting test suite talos-linux64-ix-033 08:07:48 INFO - 2016-02-01 08:07:48,520 INFO : Starting test dromaeo_css 08:07:48 INFO - 2016-02-01 08:07:48,520 DEBUG : operating with platform_type : linux_ 08:07:48 INFO - 2016-02-01 08:07:48,521 INFO : Initialising browser for dromaeo_css test... 08:07:48 INFO - 2016-02-01 08:07:48,533 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXWjnwu/profile http://localhost:58603/getInfo.html 08:07:53 INFO - 2016-02-01 08:07:53,622 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:07:53 INFO - 2016-02-01 08:07:53,623 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:07:53 INFO - 2016-02-01 08:07:53,623 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:07:53 INFO - 2016-02-01 08:07:53,623 DEBUG : BROWSER_OUTPUT: __metrics 08:07:54 INFO - 2016-02-01 08:07:54,109 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] 08:07:55 INFO - 2016-02-01 08:07:55,353 INFO : Browser initialized. 08:07:55 INFO - 2016-02-01 08:07:55,354 INFO : Running cycle 1/1 for dromaeo_css test... 08:07:55 INFO - 2016-02-01 08:07:55,354 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXWjnwu/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:08:07 INFO - 2016-02-01 08:08:07,240 DEBUG : BROWSER_OUTPUT: RSS: Main: 134139904 08:08:07 INFO - 2016-02-01 08:08:07,240 DEBUG : BROWSER_OUTPUT: 08:08:58 INFO - 2016-02-01 08:08:58,064 DEBUG : BROWSER_OUTPUT: 1454342938063 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:11:06 INFO - 2016-02-01 08:11:06,479 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58603/tests/dromaeo/cssquery-ext.html) 08:11:07 INFO - 2016-02-01 08:11:07,759 DEBUG : BROWSER_OUTPUT: RSS: Main: 264220672 08:11:07 INFO - 2016-02-01 08:11:07,759 DEBUG : BROWSER_OUTPUT: 08:14:09 INFO - 2016-02-01 08:14:09,858 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/dromaeo/cssquery-ext.html (next: http://localhost:58603/tests/dromaeo/cssquery-jquery.html) 08:14:10 INFO - 2016-02-01 08:14:10,424 DEBUG : BROWSER_OUTPUT: RSS: Main: 215793664 08:14:10 INFO - 2016-02-01 08:14:10,424 DEBUG : BROWSER_OUTPUT: 08:17:12 INFO - 2016-02-01 08:17:12,131 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58603/tests/dromaeo/cssquery-mootools.html) 08:17:12 INFO - 2016-02-01 08:17:12,693 DEBUG : BROWSER_OUTPUT: RSS: Main: 215650304 08:17:12 INFO - 2016-02-01 08:17:12,693 DEBUG : BROWSER_OUTPUT: 08:20:14 INFO - 2016-02-01 08:20:14,382 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58603/tests/dromaeo/cssquery-prototype.html) 08:20:14 INFO - 2016-02-01 08:20:14,956 DEBUG : BROWSER_OUTPUT: RSS: Main: 199131136 08:20:14 INFO - 2016-02-01 08:20:14,957 DEBUG : BROWSER_OUTPUT: 08:23:17 INFO - 2016-02-01 08:23:17,312 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58603/tests/dromaeo/cssquery-yui.html) 08:23:17 INFO - 2016-02-01 08:23:17,886 DEBUG : BROWSER_OUTPUT: RSS: Main: 201240576 08:23:17 INFO - 2016-02-01 08:23:17,886 DEBUG : BROWSER_OUTPUT: 08:26:20 INFO - 2016-02-01 08:26:20,245 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/dromaeo/cssquery-yui.html (next: http://localhost:58603/tests/dromaeo/cssquery-dojo.html) 08:26:20 INFO - 2016-02-01 08:26:20,503 DEBUG : BROWSER_OUTPUT: RSS: Main: 216633344 08:26:20 INFO - 2016-02-01 08:26:20,503 DEBUG : BROWSER_OUTPUT: 08:26:20 INFO - 2016-02-01 08:26:20,504 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:26:20 INFO - 2016-02-01 08:26:20,504 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:26:20 INFO - 2016-02-01 08:26:20,504 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:26:20 INFO - 2016-02-01 08:26:20,504 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:26:20 INFO - 2016-02-01 08:26:20,504 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3323.468245080787;3354.379439803638;3378.8141652209133;3384.8476818543177;3399.082247793096;5495.258140151083;5581.776728930838;5594.328680558328;5594.748236329365;5596.160575913615;5559.444055594438;5617.101263797793;5619.072852979264;5620.91568626471;5625.071863142587;5000.849974500771;5061.898762024757;5085.059264036166;5106.310648062501;5161.225816127573;5077.974610126944;5090.440051594322;5144.228365745131;5147.639665223436;5148.974255128738;5054.949450505503;5060.822871199509;5140.0490909181735;5181.4300426953005;5185;5168.508991645787;5204.193350030713;5206.557442617401;5209;5237.9476205238225;4916.827911023097;5096.566791822699;5116.437191908887;5168.508991645787;5186.273921650972;5289.285946397225;5337.226102215196;5346.545543628797;5349.759260833272;5384.892302153974;6007.36922623127;6019.548533859978;6058.333583305833;6059.575829691923;6071.150038994544;5944.613600115978;5982.73077711504;6098.0852872069545;6098.72555734993;6103.176071230371;5517.503424691797;5592.245046918695;5596.888062238732;5604.411536788579;5633.155026746021;6179.876402472015;6220.471259942865;6220.968895155495;6223.782167624202;6224.221972253468;5760.193572899798;5871.031279838779;5872.530197584183;5995.760169593167;6011.12838638395;5993.101034844808;5994.07091900756;5994.940050599438;5996.730147143302;6045.365236650088;5912.763489460373;5945.048792193228;5957.1362152487645;5960.0761881908375;5969.880602387928;5823.184754134425;5921.230240068763;5964.194833697482;5997.220361352996;6104.572679912363;4649.279361698942;4656.5110663379855;4658.231391820379;4668.486466488684;4684.133435314409;4156.521999969982;4165.625093741578;4181.477315335583;4188.91622167561;4219.430376899079;5614.214010038598;5690.601657883991;5699.914501282484;5704.515116215085;5828.417158284137;6103.667681453349;6180.258368995749;6182.845428864314;6184.938150618526;6185.567010309325;5819.709763059651;5871.3541510433815;5877.206577112121;5886.175935369051;5907.232059832194;5154.587632989352;5193.610479214074;5301.655392399482;5311.097113490639;5333.866653333697;6124.3609037543765;6272.5922815016875;6273.435390814758;6289.088082228142;6291.433770960635;6083.513318934386;6092.055731361469;6099.817005489843;6101.816945491642;6102.298235702951;6104.847378815565;6143.109249158936;6198.6280823150755;6206.627602343874;6274.996000639876;5968.313643931004;5970.9701451492465;5984.521238300838;5991.970040149771;6021.969890150521;6105.8168254952425;6112.633242005494;6120.17377654011;6123.571350005457;6136.32500424944;5527.8894422110525;5623.606347555632;5851.414858514115;5950.434708702839;5955.40445955401;5878.059510478303;5967.850803729942;5973.074173503114;5980.700964951648;5981.282246130492;5840.065589505659;5856.238688970407;5866.413358664099;5866.472017518273;5867.765289388377;5854.356020837789;5866.560007999503;5934.3175534814045;5987;6003.189569408074;5794.275715535558;5797.333306669787;5798.623089499169;5804.21643078179;5817.360090390139 08:26:20 INFO - 2016-02-01 08:26:20,504 DEBUG : BROWSER_OUTPUT: |1;ext.html;589.6904125334199;638.8083574927526;645.071097619428;650.1775254303304;656.346934799874;29193.41613167738;29865.701342986562;29896.70103298969;40627.796861015726;41440.37839432411;43249.35125973105;43666.7816660917;43751.56248437515;43841;43888.780556097256;8981.056989016157;9132.726018219464;9151.084891510862;9160.633574657038;9165.762622046004;8274;8458.238758511732;10530.42082685452;10810.243282970196;10820.13438924884;10590.682279531626;10731.624393146241;10742.086922611586;10746.086582640446;10750.03249707526;10562.26064175508;10709.625163119254;10713.035826775627;10715.19636027301;10723.088537474285;10440.425776582286;10614.73463163427;10629.468526573717;10637.149028077734;10639.680809575726;41771.37342939858;42414.36378454325;42526.57473425257;42553.57446425558;42582;3555.8219092566133;3615.403458429356;3617.0234036810175;3941.960580394188;4049.1901619675887;31555.684443155504;31936.520952185736;31945.201369965704;32065.83967080173;32443.67556324454;4288.335308027261;4329.631981281595;4353.499347575038;4393.29707246839;4414.073044660625;276268;278880;279550;279836;279912;58150;58900;61730;62852.37147628557;62924;56396.43603563994;56569.71715141398;56629.15056274159;56729.71635141798;56792;15635.530934071996;15759.054456732632;15866.286017129258;15870.60323491922;15892.920535397248;11819.586314479124;11911.10666700001;11922.225055371373;12001.939990300167;12002.939985300192;20356;20604.896975515025;20619.381418557467;20693.27573534919;20712.58574828525;7459.664315105833;7560.659770310349;7632.045994250719;7634.770956871303;7678.884816727754;1026.1431704526653;1029.8558201851747;1088.3633074651418;1116.213069785799;1137.323292640894;28551;28939.98822320026;29560.704392956224;29618.555721664194;29751.553726694117;1089.4171618184234;1159.675290918544;1172;1178.4402408855724;1258.3519487463964;13488.797668034988;15044;15620.062796232036;16312.918435407748;16510.917445412695;339769;343672;344365;344439;345760;55375.72312138414;55710.72144639332;55788.442115579135;55827;55924.44075559274;1349.2107117336272;1354.7832346824462;1372.670559065837;1382.1845111384332;1394.532831501438;29800;29950.101496955125;30238.84880575583;30545.389092217585;31602;49613.25580116373;50080.499195007586;50138.247926280404;50166;50251;258902;259271;260222;261541;261927;29047.273818154714;33214.5017824728;33548.83225583954;34357.656423435445;34536.65463345334;39389.212215756095;39558.604413955494;39722.60277397305;40751;40903.795481022404;33806.661933380354;34000.48999264964;34020.829895850366;34288;34495.482567762;31378.843105784326;36659.81670091739;37051;38222;38479.23041539209;37866;38192.42711359276;38480.80759596184;38645.806770965966;39112.217755645295;29092.127236182947;29266;31564.842175788974;32537.349253014334;32678.83660581777;24944.027524994246;25638.230853074438;25760.10224560889;25873.611895821203;25951.87024064943 08:26:20 INFO - 2016-02-01 08:26:20,504 DEBUG : BROWSER_OUTPUT: |2;jquery.html;380.2907577368208;380.4787441205548;381.23181788695786;382.8181613733475;384.2027792330914;14709.558713238594;16095.517134485985;16449.17754112294;17973.370932017366;17977.64044719105;16355.59111022225;16545.586360340967;16563.917180414082;17741.112944352797;17772.200250988702;5585.273914391123;5699.660579763752;5708.600397972144;5713.228714123603;5715.057015592422;4312.374705667677;4433.556644335573;5635.802746903859;6081.239845019372;6306.400891915284;6108.755649774003;6188.44304012641;6235.376462353751;6260.968695156518;6267.4045965633195;5254.448282930275;6303.810885673437;6305.716242769064;6306.180196574439;6308.053791931201;5225.163973764217;5538.584606154514;5542.584306177013;5563.721813909288;5567.220589117527;20007.899960500104;20197.495062623406;20271.391858244275;21341.786582134137;21379.465513362135;2316.247219653619;2334.5564342774987;2357.8821058947156;2448.926532204037;2463.224084413422;9950.054744799232;10053.195744340435;10183.490825458697;11616.941915290454;13925.30373481332;1953.6483432982056;1958.5593241520687;1964.6463636545416;1987.4037788663488;1994.6409646263667;63183.368166318665;64011.359886401005;64379;65161;66296.3370366295;10808.405537695502;10811.18916081297;10818.350898945931;10827.404492752818;10884.945575272232;14404.855951440351;14479.276036198147;14570.125792452487;14586.927065364818;14627;7578.658960346687;7603.201663825329;7615.16233214346;7777.494462859896;7815.609219539;7148.5710857347685;7151.499395042297;7167.1757747859665;7219.278072192844;7225.2774722528375;4645.233536466513;4652.255639097728;4660.627149828006;4710.175719249118;4736.976315118472;1749.309857417982;1793.5336812428861;1874.9625007499774;1970.773361063496;1974.575466274729;962.1340793286098;1069.2408390043156;1078.8327809189589;1102.3496137279044;1126.3692332293942;14612.342444590018;16594;16708;16944.830551694573;17337.306507739802;976;980.2207245240052;1015.4719545836124;1040.2042437535292;1087.3095584304024;7048.964755176191;9549.236061115094;9632.9036709632;9720.125188733047;9764.804703905882;608950;611980;625219;633224;635327;78096.60951695206;78155.60922195467;80179.59910200529;80405;82618;1152.3316476443642;1154.6362895687832;1191.5293459083614;1194.3729541990351;1205.5840734946605;15231.16228607393;15332;15654.608634784221;15742.29159687794;15839.366425343318;35468.137206107946;35620.28759424745;35639.64360356363;35957.460638090975;35986.64013359833;44431;44847;44903.92793724913;45873.311900320856;47411;10098.596056157965;10181.185505159718;10258.897411025795;10364.533595988349;10365;14371.784423233868;18274.543136421697;18815.623687526448;18833.058347082864;18907.43277701671;15601.219939002778;15641.53075407694;15735.763963540327;16055.759163612789;16066.919665401598;15050.172240526876;15614.297356619207;15674.9216253918;15691.843081569039;15727.606809829846;15480.84519154794;15787.842121578637;15845.049297042215;16188.838111618734;16190.514284571482;10207.387556746618;10364.481775911023;10458.529366178387;10526.842097368699;10539.736506587396;10912.127029837753;10998.12015038779;14959.775603366175;15548;16019.439319623758 08:26:20 INFO - 2016-02-01 08:26:20,504 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1577.8027746531684;1598.0890892191455;1631.8776091793115;1771.521689143931;1854.2675643120958;7707.26780955809;7850.646720897559;7851.371890248781;7854.607269636524;7869.409794265424;6931.480138989571;7134.607596582186;7140.857182856353;7595.43034272429;7967.521948683083;2294.1167650454613;2311.2835021143414;2337.649352597107;2337.649352597107;2341.6487526870937;2346.8709220992837;2376.453415714378;2386.7971222446113;2387.3554140382084;2394.341556072077;2028.1785876719953;2073.1189244571074;2276.84062115652;2313.687652166961;2316.2356422380576;2255.481239314967;2263.309690544383;2282.942926426836;2285.999999999992;2292.197730794209;2335.544568809083;2342.988285058581;2365.053978408633;2366.0417530900013;2367.3253122860074;7944.927434796344;7994.040715114165;7998.560079195642;8002.51984880909;8007.559584222866;4684.437867455893;4791.065742180276;4795.880102997418;4815.638827087947;4824.517548245182;3192.233863872677;3199.792013519114;3227.5320078588475;3292.1769557610596;3327.619038099186;1054.0039662518923;1069.3370110531444;1096.6161843354841;1104.276698762312;1143.6854864912132;10908.727281817939;11216;11224.551017959271;11237.550497980072;11244.437778111143;4236.068065025689;4278.657707383401;4308.3968244445805;4318.978405107954;4332.4367832181615;9175.357724959322;9177.08229177077;9179.082091790768;9180.127887850644;9181.540922953825;4868.245421959602;4873.853784386474;4875.219964805614;4876.146674331978;4879.878003049952;4452.643788496913;4500.437445319335;4505.481869584974;4507.121111383249;4518.548145185455;3547.716182705429;3577.4276115821344;3583.6416358364477;3596.2268112355446;3609.296187243462;1046.3721766939927;1083.6965649618119;1102.1238115698038;1109.9944500277447;1115.3363748569589;1005.818952588541;1085.7394225386008;1098.0666433531435;1299.5412798391976;1447.7828325751223;7286.089238845145;7352.742654007084;7391.484745627134;7396.55620662762;7399.889001664979;1158.7103224193952;1177.776222517719;1178.5698220149584;1200.9819852702217;1202.939853007346;5907.556933229939;5969.850753731192;5973.910391344133;5982.132590774313;5991.64050156992;9260.444373337486;9794.118529332252;9798.65704700332;9804.019598040282;9805.264605154642;9653.662121825702;9660.32377733552;9673.177779888789;9684.806303873884;9685.225181985425;7454.142773580999;7627.4279429041935;7628.313451789366;7713.035870516298;7725.150233474314;7253.347198752138;7304.780856574312;7337.3029562191505;7338.449616278907;7343.559386436831;8496.06543280251;8738.388312818042;8759.080296569024;8784.033756286932;8790;8747.16901894319;8758.211760941545;8871.778205544913;8958.731238062868;8968.461892286223;6990.475714321345;7073.504854660125;7073.752418665322;7099.751508697171;7106.928930710626;7581.3176814087;7693.3076023155945;7748.961255193688;7799.415043871619;7811.023622047244;7257.891131632924;7341.59621220838;7352.191258961403;7398.445116616168;7435.739749108755;5884.823455296348;6166.630002199882;6788.422984046411;7131.393831524171;7276.527025743309;7092.397146242421;7145.714171433292;7148.0350152728715;7155.749548765768;7204.135503739585;6327;6350.491960643045;6809.353111454404;6815.591064536144;6824.385805277549;6861.691223894837;6921.723131074902;6922.653867306715;6924.238333783179;6929.099217101744 08:26:20 INFO - 2016-02-01 08:26:20,504 DEBUG : BROWSER_OUTPUT: |4;prototype.html;334.0046660950367;335.9630440651528;340.8551365669591;351.7115964908776;358.3406531981156;6039.094135879614;6069.635821850692;6199.938000619993;6200.658963756997;6214.316425193225;5912.408759124085;6048.546359023069;6070.969645151768;6076.605020673653;6084.5132389408855;4687.6249900007915;4690.624750019999;4696.238910408597;4697.718136911796;4698.483166851644;4695.812167513295;4698.036902435009;4699.624030077603;4702.365180700613;4708.8587342379615;4514.367988481598;4620.676552641316;4621.861344159681;4630.282306242521;4661.953380466202;4589.0821835633005;4600.35595016698;4607.746573938432;4610.97694511527;4611.400517932664;3870.7290489665734;3981.4824072870633;4040.5555388907196;4044.959550404488;4050.0887300357185;5725.112607545795;5774.682392468455;5820.621659592113;5828.679422631753;5829.154772557998;5352.170413585901;5360.954613850301;5361.2226227197225;5367.141257398797;5368.275282836806;6369.745210191587;6412.23053233615;6473.06140609609;6477.83805404864;6478.93521064788;4427.380166776622;4441.600255976945;4443.400140980959;4446.110777844444;4447.73313601185;6614.636195009272;6698.296678848699;6699.296573859772;6700.698468568926;6706.698198581075;4565.338025986213;4566.3150527420485;4566.725996440157;4568.451785785727;4575;4616.446026476844;4618.538146185355;4620.214563524209;4621.1219868224925;4634.884127896829;5465.289512363367;5473.9452605474235;5483.75323110461;5488.780448782004;5489.478499542537;6537.1501704778075;6546.70539825709;6551;6555.246146693096;6557.442617377481;1647.2011074628795;1681.9831801682071;1682.1084825042747;1684.107423065777;1684.4441334359633;1133.7789131119353;1145.7536629624672;1153.9538418463335;1173.5833779008328;1184.4788293150987;6369.394907483875;6376.553641245161;6377.808665740035;6378.649174295459;6380.776672816429;6728;6733.562318449285;6734.494912881553;6736.46108311334;6744.696488658022;6229.221347331583;6342.619442833445;6370.490360771128;6370.904436433457;6374.58565193261;3604.441311596707;3646.3072016316814;3659.469376940382;3671.30245253401;3672.6878215351994;6703.296153903867;6826.590404575642;6946.2011868634745;7002.544834585736;7056.964715176391;6966.407855332252;6968.860622787516;6975.825604359931;6976.093107896043;6981.232064472904;6824.6928888200155;7046.894296585555;7048.471364647672;7060.3292687194635;7067.89398159028;6781.152355955505;6787.389134977876;6788.25329213796;6790.660466976533;6801.1158549388265;6651;6693.799186024427;6707.396334329934;6708.530402871947;6713.966430167818;6781.457483401217;6937.791866244021;6943.194769360104;6943.9652801735665;7042.119735033121;4576.496585375539;4592.770361481846;4594.1730488512385;4601.401817763669;4601.516840731809;6788.2193547742645;6796.2524122347495;6796.592204467748;6797.252302246848;6797.864042719217;6464.741410343534;6553.705083271359;6553.901691474727;6581.868362632625;6606.339366063356;6506.219253689398;6511.902321465087;6516.315786842503;6518.73925043012;6519.348065193442;6487.935120648733;6488.545801793829;6494.577852439576;6494.675266236574;6498.902516462163;6474.417302442803;6611.206655201214;6622.503312251504;6632.369924857131;6664;6501.934980650133;6508.772192973223;6510.023496475377;6510.316416776171;6517.348265173445 08:26:20 INFO - 2016-02-01 08:26:20,505 DEBUG : BROWSER_OUTPUT: |5;yui.html;928.9117533834285;929.0987741890366;936.4100616611533;942.6700654770831;964.0937518732395;13562.186268823853;13579.117357371764;13586.864131358683;13750.862491375083;13753.51862684805;13847.792283115736;13927.721445571082;13927.860721392808;13933.164010159397;13946.651333716662;716.0977168767339;718.5078221418319;730.0545793197822;731.9414446844239;853.197993885747;827.0034608296987;832.8584140696096;836.6318819719304;838.932885369172;839.8320335932807;746.5781833264189;902.2917010147036;907.020417948614;911.4075850697034;911.8677791720197;735.5439627430974;893.1158153428092;904.7873749668888;905.1762895764888;905.3888625178045;659.7097277198018;666.183924692253;668.588817877007;669.357416879796;669.5313280703479;13322.80015799754;13355.465781368734;13406.46374145033;13425.798613020812;13433.39549720265;12523.248605083632;12700.80948785769;12837.358132093452;12937.676558086028;13224.27266500342;10024.749381265454;10066.245031622657;10136;10249.33379330341;10406.115480184193;3933.6263055009726;3940.527136743609;3962.187751510933;3977.5823538528616;3980.960190398088;43066;43754.99999999968;43800.56199437997;44046.33930491045;44811.32783008225;10773.084287835465;10870.021698047056;10921.672349829518;10959.342439453658;11146;18614.813851861578;18623.53441163982;18651.440456786317;18675.533111672044;18744.718829217574;7704.229577042251;7936.4047696423;8040.276375126266;8086.474379165335;8111.553864537389;4656.650751193674;4697.224957881979;4710.26990816424;4729.314249433813;4736.076465089343;3312.2381852174135;3347.681970212826;3347.983260083684;3351.497275408708;3353.84907679155;599.6342231238941;623.9563230573816;624.5971348480296;628.9119523266746;635.3614617309574;606.3663471672113;713.9428845692332;714.746265075901;740.4113729584993;765.3953376832352;8816.911830881738;9215.94016688076;9247.306452016126;9283.87639414914;9302.674406395743;589.3105067071536;662.8541720821412;692.5671455340412;741.9740309089156;747.1669088965873;4336.9100636637795;4405.40527028847;4416.1609294233995;4432.933505997413;4496.9100617988115;56397.7180114105;56830.715846420506;56937.145942810894;57044;57065.42934570601;50835.49164508308;56460;57155;57318.14022789661;57507;46128.538714613096;46690.29964550534;47066.82332941635;47337;47878.760606197444;8332.583370831313;8467.195616416431;8616.526091065052;8822.955885220534;8867.778305542155;34976.475352870235;35746;36020.63979360173;36033;38673.80663096779;43607;44294.335584966895;44982;45592;45863.31205031994;10566.947165264124;11052.668419947415;12120.818187727367;12198.14612977083;12334.50661973511;13350.866491334962;13543.59369218925;13607.455701772215;14003.43986240539;14353.282335883372;12046.698832529257;12252;12353.258804471761;12493.312867791996;13093.738125237633;10738.516766745359;11049.613263535737;12273.693157670772;12446;12731.93634031824;10427.593872659492;10581.841272381074;10826.729331766768;11676.241044332432;12159.088068395082;9600.231981441328;9642.855357169788;9807.558659860466;9822.263330250116;10411.739706507095;9652.372595781533;9855.655052073429;10233.744156395851;10348.275620706478;10585.841212381974 08:26:20 INFO - 2016-02-01 08:26:20,505 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:26:20 INFO - 2016-02-01 08:26:20,505 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:26:20 INFO - 2016-02-01 08:26:20,505 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1045 08:26:20 INFO - 2016-02-01 08:26:20,505 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:26:20 INFO - 2016-02-01 08:26:20,505 DEBUG : BROWSER_OUTPUT: __startTimestamp1454343980498__endTimestamp 08:26:20 INFO - 2016-02-01 08:26:20,505 DEBUG : BROWSER_OUTPUT: 08:26:20 INFO - 2016-02-01 08:26:20,505 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:26:20 INFO - 2016-02-01 08:26:20,505 DEBUG : BROWSER_OUTPUT: Number of tests: 6 08:26:20 INFO - 2016-02-01 08:26:20,505 DEBUG : BROWSER_OUTPUT: 08:26:20 INFO - 2016-02-01 08:26:20,505 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5607.61 Median:5854.36 stddev:624.17 (10.7%) stddev-sans-first:599.81 08:26:20 INFO - 2016-02-01 08:26:20,505 DEBUG : BROWSER_OUTPUT: Values: 3323.5 3354.4 3378.8 3384.8 3399.1 5495.3 5581.8 5594.3 5594.7 5596.2 5559.4 5617.1 5619.1 5620.9 5625.1 5000.8 5061.9 5085.1 5106.3 5161.2 5078.0 5090.4 5144.2 5147.6 5149.0 5054.9 5060.8 5140.0 5181.4 5185.0 5168.5 5204.2 5206.6 5209.0 5237.9 4916.8 5096.6 5116.4 5168.5 5186.3 5289.3 5337.2 5346.5 5349.8 5384.9 6007.4 6019.5 6058.3 6059.6 6071.2 5944.6 5982.7 6098.1 6098.7 6103.2 5517.5 5592.2 5596.9 5604.4 5633.2 6179.9 6220.5 6221.0 6223.8 6224.2 5760.2 5871.0 5872.5 5995.8 6011.1 5993.1 5994.1 5994.9 5996.7 6045.4 5912.8 5945.0 5957.1 5960.1 5969.9 5823.2 5921.2 5964.2 5997.2 6104.6 4649.3 4656.5 4658.2 4668.5 4684.1 4156.5 4165.6 4181.5 4188.9 4219.4 5614.2 5690.6 5699.9 5704.5 5828.4 6103.7 6180.3 6182.8 6184.9 6185.6 5819.7 5871.4 5877.2 5886.2 5907.2 5154.6 5193.6 5301.7 5311.1 5333.9 6124.4 6272.6 6273.4 6289.1 6291.4 6083.5 6092.1 6099.8 6101.8 6102.3 6104.8 6143.1 6198.6 6206.6 6275.0 5968.3 5971.0 5984.5 5992.0 6022.0 6105.8 6112.6 6120.2 6123.6 6136.3 5527.9 5623.6 5851.4 5950.4 5955.4 5878.1 5967.9 5973.1 5980.7 5981.3 5840.1 5856.2 5866.4 5866.5 5867.8 5854.4 5866.6 5934.3 5987.0 6003.2 5794.3 5797.3 5798.6 5804.2 5817.4 08:26:20 INFO - 2016-02-01 08:26:20,505 DEBUG : BROWSER_OUTPUT: 08:26:20 INFO - 2016-02-01 08:26:20,506 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:46980.90 Median:32490.51 stddev:77385.03 (238.2%) stddev-sans-first:77523.73 08:26:20 INFO - 2016-02-01 08:26:20,507 DEBUG : BROWSER_OUTPUT: Values: 589.7 638.8 645.1 650.2 656.3 29193.4 29865.7 29896.7 40627.8 41440.4 43249.4 43666.8 43751.6 43841.0 43888.8 8981.1 9132.7 9151.1 9160.6 9165.8 8274.0 8458.2 10530.4 10810.2 10820.1 10590.7 10731.6 10742.1 10746.1 10750.0 10562.3 10709.6 10713.0 10715.2 10723.1 10440.4 10614.7 10629.5 10637.1 10639.7 41771.4 42414.4 42526.6 42553.6 42582.0 3555.8 3615.4 3617.0 3942.0 4049.2 31555.7 31936.5 31945.2 32065.8 32443.7 4288.3 4329.6 4353.5 4393.3 4414.1 276268.0 278880.0 279550.0 279836.0 279912.0 58150.0 58900.0 61730.0 62852.4 62924.0 56396.4 56569.7 56629.2 56729.7 56792.0 15635.5 15759.1 15866.3 15870.6 15892.9 11819.6 11911.1 11922.2 12001.9 12002.9 20356.0 20604.9 20619.4 20693.3 20712.6 7459.7 7560.7 7632.0 7634.8 7678.9 1026.1 1029.9 1088.4 1116.2 1137.3 28551.0 28940.0 29560.7 29618.6 29751.6 1089.4 1159.7 1172.0 1178.4 1258.4 13488.8 15044.0 15620.1 16312.9 16510.9 339769.0 343672.0 344365.0 344439.0 345760.0 55375.7 55710.7 55788.4 55827.0 55924.4 1349.2 1354.8 1372.7 1382.2 1394.5 29800.0 29950.1 30238.8 30545.4 31602.0 49613.3 50080.5 50138.2 50166.0 50251.0 258902.0 259271.0 260222.0 261541.0 261927.0 29047.3 33214.5 33548.8 34357.7 34536.7 39389.2 39558.6 39722.6 40751.0 40903.8 33806.7 34000.5 34020.8 34288.0 34495.5 31378.8 36659.8 37051.0 38222.0 38479.2 37866.0 38192.4 38480.8 38645.8 39112.2 29092.1 29266.0 31564.8 32537.3 32678.8 24944.0 25638.2 25760.1 25873.6 25951.9 08:26:20 INFO - 2016-02-01 08:26:20,507 DEBUG : BROWSER_OUTPUT: 08:26:20 INFO - 2016-02-01 08:26:20,507 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31619.93 Median:11001.27 stddev:101651.41 (924.0%) stddev-sans-first:101909.50 08:26:20 INFO - 2016-02-01 08:26:20,508 DEBUG : BROWSER_OUTPUT: Values: 380.3 380.5 381.2 382.8 384.2 14709.6 16095.5 16449.2 17973.4 17977.6 16355.6 16545.6 16563.9 17741.1 17772.2 5585.3 5699.7 5708.6 5713.2 5715.1 4312.4 4433.6 5635.8 6081.2 6306.4 6108.8 6188.4 6235.4 6261.0 6267.4 5254.4 6303.8 6305.7 6306.2 6308.1 5225.2 5538.6 5542.6 5563.7 5567.2 20007.9 20197.5 20271.4 21341.8 21379.5 2316.2 2334.6 2357.9 2448.9 2463.2 9950.1 10053.2 10183.5 11616.9 13925.3 1953.6 1958.6 1964.6 1987.4 1994.6 63183.4 64011.4 64379.0 65161.0 66296.3 10808.4 10811.2 10818.4 10827.4 10884.9 14404.9 14479.3 14570.1 14586.9 14627.0 7578.7 7603.2 7615.2 7777.5 7815.6 7148.6 7151.5 7167.2 7219.3 7225.3 4645.2 4652.3 4660.6 4710.2 4737.0 1749.3 1793.5 1875.0 1970.8 1974.6 962.1 1069.2 1078.8 1102.3 1126.4 14612.3 16594.0 16708.0 16944.8 17337.3 976.0 980.2 1015.5 1040.2 1087.3 7049.0 9549.2 9632.9 9720.1 9764.8 608950.0 611980.0 625219.0 633224.0 635327.0 78096.6 78155.6 80179.6 80405.0 82618.0 1152.3 1154.6 1191.5 1194.4 1205.6 15231.2 15332.0 15654.6 15742.3 15839.4 35468.1 35620.3 35639.6 35957.5 35986.6 44431.0 44847.0 44903.9 45873.3 47411.0 10098.6 10181.2 10258.9 10364.5 10365.0 14371.8 18274.5 18815.6 18833.1 18907.4 15601.2 15641.5 15735.8 16055.8 16066.9 15050.2 15614.3 15674.9 15691.8 15727.6 15480.8 15787.8 15845.0 16188.8 16190.5 10207.4 10364.5 10458.5 10526.8 10539.7 10912.1 10998.1 14959.8 15548.0 16019.4 08:26:20 INFO - 2016-02-01 08:26:20,508 DEBUG : BROWSER_OUTPUT: 08:26:20 INFO - 2016-02-01 08:26:20,508 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5591.00 Median:6079.14 stddev:2900.01 (47.7%) stddev-sans-first:2892.45 08:26:20 INFO - 2016-02-01 08:26:20,508 DEBUG : BROWSER_OUTPUT: Values: 1577.8 1598.1 1631.9 1771.5 1854.3 7707.3 7850.6 7851.4 7854.6 7869.4 6931.5 7134.6 7140.9 7595.4 7967.5 2294.1 2311.3 2337.6 2337.6 2341.6 2346.9 2376.5 2386.8 2387.4 2394.3 2028.2 2073.1 2276.8 2313.7 2316.2 2255.5 2263.3 2282.9 2286.0 2292.2 2335.5 2343.0 2365.1 2366.0 2367.3 7944.9 7994.0 7998.6 8002.5 8007.6 4684.4 4791.1 4795.9 4815.6 4824.5 3192.2 3199.8 3227.5 3292.2 3327.6 1054.0 1069.3 1096.6 1104.3 1143.7 10908.7 11216.0 11224.6 11237.6 11244.4 4236.1 4278.7 4308.4 4319.0 4332.4 9175.4 9177.1 9179.1 9180.1 9181.5 4868.2 4873.9 4875.2 4876.1 4879.9 4452.6 4500.4 4505.5 4507.1 4518.5 3547.7 3577.4 3583.6 3596.2 3609.3 1046.4 1083.7 1102.1 1110.0 1115.3 1005.8 1085.7 1098.1 1299.5 1447.8 7286.1 7352.7 7391.5 7396.6 7399.9 1158.7 1177.8 1178.6 1201.0 1202.9 5907.6 5969.9 5973.9 5982.1 5991.6 9260.4 9794.1 9798.7 9804.0 9805.3 9653.7 9660.3 9673.2 9684.8 9685.2 7454.1 7627.4 7628.3 7713.0 7725.2 7253.3 7304.8 7337.3 7338.4 7343.6 8496.1 8738.4 8759.1 8784.0 8790.0 8747.2 8758.2 8871.8 8958.7 8968.5 6990.5 7073.5 7073.8 7099.8 7106.9 7581.3 7693.3 7749.0 7799.4 7811.0 7257.9 7341.6 7352.2 7398.4 7435.7 5884.8 6166.6 6788.4 7131.4 7276.5 7092.4 7145.7 7148.0 7155.7 7204.1 6327.0 6350.5 6809.4 6815.6 6824.4 6861.7 6921.7 6922.7 6924.2 6929.1 08:26:20 INFO - 2016-02-01 08:26:20,508 DEBUG : BROWSER_OUTPUT: 08:26:20 INFO - 2016-02-01 08:26:20,508 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5454.93 Median:6285.92 stddev:1654.41 (26.3%) stddev-sans-first:1613.79 08:26:20 INFO - 2016-02-01 08:26:20,509 DEBUG : BROWSER_OUTPUT: Values: 334.0 336.0 340.9 351.7 358.3 6039.1 6069.6 6199.9 6200.7 6214.3 5912.4 6048.5 6071.0 6076.6 6084.5 4687.6 4690.6 4696.2 4697.7 4698.5 4695.8 4698.0 4699.6 4702.4 4708.9 4514.4 4620.7 4621.9 4630.3 4662.0 4589.1 4600.4 4607.7 4611.0 4611.4 3870.7 3981.5 4040.6 4045.0 4050.1 5725.1 5774.7 5820.6 5828.7 5829.2 5352.2 5361.0 5361.2 5367.1 5368.3 6369.7 6412.2 6473.1 6477.8 6478.9 4427.4 4441.6 4443.4 4446.1 4447.7 6614.6 6698.3 6699.3 6700.7 6706.7 4565.3 4566.3 4566.7 4568.5 4575.0 4616.4 4618.5 4620.2 4621.1 4634.9 5465.3 5473.9 5483.8 5488.8 5489.5 6537.2 6546.7 6551.0 6555.2 6557.4 1647.2 1682.0 1682.1 1684.1 1684.4 1133.8 1145.8 1154.0 1173.6 1184.5 6369.4 6376.6 6377.8 6378.6 6380.8 6728.0 6733.6 6734.5 6736.5 6744.7 6229.2 6342.6 6370.5 6370.9 6374.6 3604.4 3646.3 3659.5 3671.3 3672.7 6703.3 6826.6 6946.2 7002.5 7057.0 6966.4 6968.9 6975.8 6976.1 6981.2 6824.7 7046.9 7048.5 7060.3 7067.9 6781.2 6787.4 6788.3 6790.7 6801.1 6651.0 6693.8 6707.4 6708.5 6714.0 6781.5 6937.8 6943.2 6944.0 7042.1 4576.5 4592.8 4594.2 4601.4 4601.5 6788.2 6796.3 6796.6 6797.3 6797.9 6464.7 6553.7 6553.9 6581.9 6606.3 6506.2 6511.9 6516.3 6518.7 6519.3 6487.9 6488.5 6494.6 6494.7 6498.9 6474.4 6611.2 6622.5 6632.4 6664.0 6501.9 6508.8 6510.0 6510.3 6517.3 08:26:20 INFO - 2016-02-01 08:26:20,509 DEBUG : BROWSER_OUTPUT: 08:26:20 INFO - 2016-02-01 08:26:20,509 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14120.87 Median:24739.46 stddev:16021.71 (64.8%) stddev-sans-first:16036.03 08:26:20 INFO - 2016-02-01 08:26:20,509 DEBUG : BROWSER_OUTPUT: Values: 928.9 929.1 936.4 942.7 964.1 13562.2 13579.1 13586.9 13750.9 13753.5 13847.8 13927.7 13927.9 13933.2 13946.7 716.1 718.5 730.1 731.9 853.2 827.0 832.9 836.6 838.9 839.8 746.6 902.3 907.0 911.4 911.9 735.5 893.1 904.8 905.2 905.4 659.7 666.2 668.6 669.4 669.5 13322.8 13355.5 13406.5 13425.8 13433.4 12523.2 12700.8 12837.4 12937.7 13224.3 10024.7 10066.2 10136.0 10249.3 10406.1 3933.6 3940.5 3962.2 3977.6 3981.0 43066.0 43755.0 43800.6 44046.3 44811.3 10773.1 10870.0 10921.7 10959.3 11146.0 18614.8 18623.5 18651.4 18675.5 18744.7 7704.2 7936.4 8040.3 8086.5 8111.6 4656.7 4697.2 4710.3 4729.3 4736.1 3312.2 3347.7 3348.0 3351.5 3353.8 599.6 624.0 624.6 628.9 635.4 606.4 713.9 714.7 740.4 765.4 8816.9 9215.9 9247.3 9283.9 9302.7 589.3 662.9 692.6 742.0 747.2 4336.9 4405.4 4416.2 4432.9 4496.9 56397.7 56830.7 56937.1 57044.0 57065.4 50835.5 56460.0 57155.0 57318.1 57507.0 46128.5 46690.3 47066.8 47337.0 47878.8 8332.6 8467.2 8616.5 8823.0 8867.8 34976.5 35746.0 36020.6 36033.0 38673.8 43607.0 44294.3 44982.0 45592.0 45863.3 10566.9 11052.7 12120.8 12198.1 12334.5 13350.9 13543.6 13607.5 14003.4 14353.3 12046.7 12252.0 12353.3 12493.3 13093.7 10738.5 11049.6 12273.7 12446.0 12731.9 10427.6 10581.8 10826.7 11676.2 12159.1 9600.2 9642.9 9807.6 9822.3 10411.7 9652.4 9855.7 10233.7 10348.3 10585.8 08:26:20 INFO - 2016-02-01 08:26:20,509 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:26:20 INFO - 2016-02-01 08:26:20,510 DEBUG : BROWSER_OUTPUT: 08:26:21 INFO - 2016-02-01 08:26:21,110 INFO : Browser exited with error code: 0 08:26:21 INFO - 2016-02-01 08:26:21,128 INFO : Completed test dromaeo_css (00:18:32) 08:26:21 INFO - 2016-02-01 08:26:21,128 INFO : Starting test kraken 08:26:21 INFO - 2016-02-01 08:26:21,128 DEBUG : operating with platform_type : linux_ 08:26:21 INFO - 2016-02-01 08:26:21,128 INFO : Initialising browser for kraken test... 08:26:21 INFO - 2016-02-01 08:26:21,134 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzWNL4j/profile http://localhost:58603/getInfo.html 08:26:26 INFO - 2016-02-01 08:26:26,184 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:26:26 INFO - 2016-02-01 08:26:26,185 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:26:26 INFO - 2016-02-01 08:26:26,185 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:26:26 INFO - 2016-02-01 08:26:26,185 DEBUG : BROWSER_OUTPUT: __metrics 08:26:26 INFO - 2016-02-01 08:26:26,414 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] 08:26:27 INFO - 2016-02-01 08:26:27,303 INFO : Browser initialized. 08:26:27 INFO - 2016-02-01 08:26:27,304 INFO : Running cycle 1/1 for kraken test... 08:26:27 INFO - 2016-02-01 08:26:27,304 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzWNL4j/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:26:39 INFO - 2016-02-01 08:26:39,078 DEBUG : BROWSER_OUTPUT: RSS: Main: 134557696 08:26:39 INFO - 2016-02-01 08:26:39,079 DEBUG : BROWSER_OUTPUT: 08:26:41 INFO - 2016-02-01 08:26:41,493 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58603/tests/kraken/driver.html?testName=audio-beat-detection) 08:26:42 INFO - 2016-02-01 08:26:42,075 DEBUG : BROWSER_OUTPUT: RSS: Main: 164843520 08:26:42 INFO - 2016-02-01 08:26:42,076 DEBUG : BROWSER_OUTPUT: 08:26:44 INFO - 2016-02-01 08:26:44,654 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58603/tests/kraken/driver.html?testName=audio-dft) 08:26:45 INFO - 2016-02-01 08:26:45,220 DEBUG : BROWSER_OUTPUT: RSS: Main: 163209216 08:26:45 INFO - 2016-02-01 08:26:45,220 DEBUG : BROWSER_OUTPUT: 08:26:48 INFO - 2016-02-01 08:26:48,024 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58603/tests/kraken/driver.html?testName=audio-fft) 08:26:48 INFO - 2016-02-01 08:26:48,585 DEBUG : BROWSER_OUTPUT: RSS: Main: 161751040 08:26:48 INFO - 2016-02-01 08:26:48,585 DEBUG : BROWSER_OUTPUT: 08:26:50 INFO - 2016-02-01 08:26:50,538 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58603/tests/kraken/driver.html?testName=audio-oscillator) 08:26:51 INFO - 2016-02-01 08:26:51,099 DEBUG : BROWSER_OUTPUT: RSS: Main: 158334976 08:26:51 INFO - 2016-02-01 08:26:51,099 DEBUG : BROWSER_OUTPUT: 08:26:53 INFO - 2016-02-01 08:26:53,245 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58603/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:26:53 INFO - 2016-02-01 08:26:53,807 DEBUG : BROWSER_OUTPUT: RSS: Main: 158801920 08:26:53 INFO - 2016-02-01 08:26:53,807 DEBUG : BROWSER_OUTPUT: 08:26:56 INFO - 2016-02-01 08:26:56,671 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58603/tests/kraken/driver.html?testName=imaging-darkroom) 08:26:57 INFO - 2016-02-01 08:26:57,238 DEBUG : BROWSER_OUTPUT: RSS: Main: 157532160 08:26:57 INFO - 2016-02-01 08:26:57,238 DEBUG : BROWSER_OUTPUT: 08:27:00 INFO - 2016-02-01 08:27:00,609 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58603/tests/kraken/driver.html?testName=imaging-desaturate) 08:27:01 INFO - 2016-02-01 08:27:01,177 DEBUG : BROWSER_OUTPUT: RSS: Main: 158973952 08:27:01 INFO - 2016-02-01 08:27:01,177 DEBUG : BROWSER_OUTPUT: 08:27:03 INFO - 2016-02-01 08:27:03,938 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58603/tests/kraken/driver.html?testName=json-parse-financial) 08:27:04 INFO - 2016-02-01 08:27:04,501 DEBUG : BROWSER_OUTPUT: RSS: Main: 159129600 08:27:04 INFO - 2016-02-01 08:27:04,502 DEBUG : BROWSER_OUTPUT: 08:27:06 INFO - 2016-02-01 08:27:06,072 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58603/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:27:06 INFO - 2016-02-01 08:27:06,623 DEBUG : BROWSER_OUTPUT: RSS: Main: 165122048 08:27:06 INFO - 2016-02-01 08:27:06,623 DEBUG : BROWSER_OUTPUT: 08:27:08 INFO - 2016-02-01 08:27:08,358 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58603/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:27:08 INFO - 2016-02-01 08:27:08,945 DEBUG : BROWSER_OUTPUT: RSS: Main: 161914880 08:27:08 INFO - 2016-02-01 08:27:08,945 DEBUG : BROWSER_OUTPUT: 08:27:10 INFO - 2016-02-01 08:27:10,896 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58603/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:27:11 INFO - 2016-02-01 08:27:11,478 DEBUG : BROWSER_OUTPUT: RSS: Main: 166227968 08:27:11 INFO - 2016-02-01 08:27:11,478 DEBUG : BROWSER_OUTPUT: 08:27:13 INFO - 2016-02-01 08:27:13,860 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58603/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:27:14 INFO - 2016-02-01 08:27:14,442 DEBUG : BROWSER_OUTPUT: RSS: Main: 175214592 08:27:14 INFO - 2016-02-01 08:27:14,442 DEBUG : BROWSER_OUTPUT: 08:27:16 INFO - 2016-02-01 08:27:16,905 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58603/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:27:17 INFO - 2016-02-01 08:27:17,471 DEBUG : BROWSER_OUTPUT: RSS: Main: 172941312 08:27:17 INFO - 2016-02-01 08:27:17,471 DEBUG : BROWSER_OUTPUT: 08:27:19 INFO - 2016-02-01 08:27:19,121 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58603/tests/kraken/driver.html?testName=ai-astar) 08:27:19 INFO - 2016-02-01 08:27:19,366 DEBUG : BROWSER_OUTPUT: RSS: Main: 230277120 08:27:19 INFO - 2016-02-01 08:27:19,367 DEBUG : BROWSER_OUTPUT: 08:27:19 INFO - 2016-02-01 08:27:19,368 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:27:19 INFO - 2016-02-01 08:27:19,368 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:27:19 INFO - 2016-02-01 08:27:19,368 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:27:19 INFO - 2016-02-01 08:27:19,368 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:27:19 INFO - 2016-02-01 08:27:19,369 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;109;102;107;109;108;98;109;108;108 08:27:19 INFO - 2016-02-01 08:27:19,369 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;159;120;147;169;123;154;145;127;159;120 08:27:19 INFO - 2016-02-01 08:27:19,369 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;159;160;159;159;159;159;161;159;161 08:27:19 INFO - 2016-02-01 08:27:19,369 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;71;71;146;74;71;70;72;157;73 08:27:19 INFO - 2016-02-01 08:27:19,369 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;93;137;92;92;93;134;93;93;133 08:27:19 INFO - 2016-02-01 08:27:19,369 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;110;109;109;110;109 08:27:19 INFO - 2016-02-01 08:27:19,369 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;146;145;146;147;145;146;145;147 08:27:19 INFO - 2016-02-01 08:27:19,369 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;97;95;96;95;95;95;96;96;96;96 08:27:19 INFO - 2016-02-01 08:27:19,370 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;60;60;61;58;62;58;59;59;57;57 08:27:19 INFO - 2016-02-01 08:27:19,370 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;63;63;64;64;63;64;63;64;64 08:27:19 INFO - 2016-02-01 08:27:19,370 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;78;81;78;77;78;78;78;79;78;79 08:27:19 INFO - 2016-02-01 08:27:19,370 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;122;113;111;118;123;118;111;114;117;117 08:27:19 INFO - 2016-02-01 08:27:19,370 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;142;141;142;142;143;142;140;141;139;130 08:27:19 INFO - 2016-02-01 08:27:19,370 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;63;64;63;66;65;64;64;63;64 08:27:19 INFO - 2016-02-01 08:27:19,370 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:27:19 INFO - 2016-02-01 08:27:19,370 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:27:19 INFO - 2016-02-01 08:27:19,370 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,873 08:27:19 INFO - 2016-02-01 08:27:19,370 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:27:19 INFO - 2016-02-01 08:27:19,370 DEBUG : BROWSER_OUTPUT: __startTimestamp1454344039367__endTimestamp 08:27:19 INFO - 2016-02-01 08:27:19,371 DEBUG : BROWSER_OUTPUT: 08:27:19 INFO - 2016-02-01 08:27:19,372 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:27:19 INFO - 2016-02-01 08:27:19,372 DEBUG : BROWSER_OUTPUT: Number of tests: 14 08:27:19 INFO - 2016-02-01 08:27:19,372 DEBUG : BROWSER_OUTPUT: 08:27:19 INFO - 2016-02-01 08:27:19,372 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.80 Median:109.00 stddev:3.79 (3.5%) stddev-sans-first:3.84 08:27:19 INFO - 2016-02-01 08:27:19,372 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 102.0 107.0 109.0 108.0 98.0 109.0 108.0 108.0 08:27:19 INFO - 2016-02-01 08:27:19,372 DEBUG : BROWSER_OUTPUT: 08:27:19 INFO - 2016-02-01 08:27:19,373 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.30 Median:150.50 stddev:18.37 (12.2%) stddev-sans-first:18.47 08:27:19 INFO - 2016-02-01 08:27:19,373 DEBUG : BROWSER_OUTPUT: Values: 159.0 120.0 147.0 169.0 123.0 154.0 145.0 127.0 159.0 120.0 08:27:19 INFO - 2016-02-01 08:27:19,373 DEBUG : BROWSER_OUTPUT: 08:27:19 INFO - 2016-02-01 08:27:19,373 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.60 Median:159.50 stddev:0.84 (0.5%) stddev-sans-first:0.88 08:27:19 INFO - 2016-02-01 08:27:19,373 DEBUG : BROWSER_OUTPUT: Values: 160.0 159.0 160.0 159.0 159.0 159.0 159.0 161.0 159.0 161.0 08:27:19 INFO - 2016-02-01 08:27:19,373 DEBUG : BROWSER_OUTPUT: 08:27:19 INFO - 2016-02-01 08:27:19,373 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.50 Median:71.00 stddev:33.85 (47.7%) stddev-sans-first:35.31 08:27:19 INFO - 2016-02-01 08:27:19,373 DEBUG : BROWSER_OUTPUT: Values: 70.0 71.0 71.0 146.0 74.0 71.0 70.0 72.0 157.0 73.0 08:27:19 INFO - 2016-02-01 08:27:19,373 DEBUG : BROWSER_OUTPUT: 08:27:19 INFO - 2016-02-01 08:27:19,374 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.20 Median:92.50 stddev:20.36 (22.0%) stddev-sans-first:21.03 08:27:19 INFO - 2016-02-01 08:27:19,374 DEBUG : BROWSER_OUTPUT: Values: 92.0 93.0 137.0 92.0 92.0 93.0 134.0 93.0 93.0 133.0 08:27:19 INFO - 2016-02-01 08:27:19,374 DEBUG : BROWSER_OUTPUT: 08:27:19 INFO - 2016-02-01 08:27:19,374 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 08:27:19 INFO - 2016-02-01 08:27:19,374 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 110.0 109.0 109.0 110.0 109.0 08:27:19 INFO - 2016-02-01 08:27:19,375 DEBUG : BROWSER_OUTPUT: 08:27:19 INFO - 2016-02-01 08:27:19,375 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.83 08:27:19 INFO - 2016-02-01 08:27:19,375 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 146.0 145.0 146.0 147.0 145.0 146.0 145.0 147.0 08:27:19 INFO - 2016-02-01 08:27:19,375 DEBUG : BROWSER_OUTPUT: 08:27:19 INFO - 2016-02-01 08:27:19,375 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.53 08:27:19 INFO - 2016-02-01 08:27:19,375 DEBUG : BROWSER_OUTPUT: Values: 97.0 95.0 96.0 95.0 95.0 95.0 96.0 96.0 96.0 96.0 08:27:19 INFO - 2016-02-01 08:27:19,375 DEBUG : BROWSER_OUTPUT: 08:27:19 INFO - 2016-02-01 08:27:19,375 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:59.10 Median:59.50 stddev:1.66 (2.8%) stddev-sans-first:1.73 08:27:19 INFO - 2016-02-01 08:27:19,375 DEBUG : BROWSER_OUTPUT: Values: 60.0 60.0 61.0 58.0 62.0 58.0 59.0 59.0 57.0 57.0 08:27:19 INFO - 2016-02-01 08:27:19,376 DEBUG : BROWSER_OUTPUT: 08:27:19 INFO - 2016-02-01 08:27:19,376 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.60 Median:64.00 stddev:0.52 (0.8%) stddev-sans-first:0.53 08:27:19 INFO - 2016-02-01 08:27:19,376 DEBUG : BROWSER_OUTPUT: Values: 64.0 63.0 63.0 64.0 64.0 63.0 64.0 63.0 64.0 64.0 08:27:19 INFO - 2016-02-01 08:27:19,376 DEBUG : BROWSER_OUTPUT: 08:27:19 INFO - 2016-02-01 08:27:19,376 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:78.00 stddev:1.07 (1.4%) stddev-sans-first:1.13 08:27:19 INFO - 2016-02-01 08:27:19,376 DEBUG : BROWSER_OUTPUT: Values: 78.0 81.0 78.0 77.0 78.0 78.0 78.0 79.0 78.0 79.0 08:27:19 INFO - 2016-02-01 08:27:19,376 DEBUG : BROWSER_OUTPUT: 08:27:19 INFO - 2016-02-01 08:27:19,376 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:116.40 Median:117.50 stddev:4.17 (3.5%) stddev-sans-first:3.90 08:27:19 INFO - 2016-02-01 08:27:19,376 DEBUG : BROWSER_OUTPUT: Values: 122.0 113.0 111.0 118.0 123.0 118.0 111.0 114.0 117.0 117.0 08:27:19 INFO - 2016-02-01 08:27:19,376 DEBUG : BROWSER_OUTPUT: 08:27:19 INFO - 2016-02-01 08:27:19,376 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:140.20 Median:142.00 stddev:3.77 (2.7%) stddev-sans-first:3.94 08:27:19 INFO - 2016-02-01 08:27:19,377 DEBUG : BROWSER_OUTPUT: Values: 142.0 141.0 142.0 142.0 143.0 142.0 140.0 141.0 139.0 130.0 08:27:19 INFO - 2016-02-01 08:27:19,377 DEBUG : BROWSER_OUTPUT: 08:27:19 INFO - 2016-02-01 08:27:19,377 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:64.00 stddev:1.34 (2.1%) stddev-sans-first:1.00 08:27:19 INFO - 2016-02-01 08:27:19,377 DEBUG : BROWSER_OUTPUT: Values: 67.0 63.0 64.0 63.0 66.0 65.0 64.0 64.0 63.0 64.0 08:27:19 INFO - 2016-02-01 08:27:19,377 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:27:19 INFO - 2016-02-01 08:27:19,377 DEBUG : BROWSER_OUTPUT: 08:27:20 INFO - 2016-02-01 08:27:20,098 INFO : Browser exited with error code: 0 08:27:20 INFO - 2016-02-01 08:27:20,150 INFO : Completed test kraken (00:00:59) 08:27:20 INFO - 2016-02-01 08:27:20,151 INFO : Starting test v8_7 08:27:20 INFO - 2016-02-01 08:27:20,151 DEBUG : operating with platform_type : linux_ 08:27:20 INFO - 2016-02-01 08:27:20,151 INFO : Initialising browser for v8_7 test... 08:27:20 INFO - 2016-02-01 08:27:20,157 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBDeZvk/profile http://localhost:58603/getInfo.html 08:27:24 INFO - 2016-02-01 08:27:24,863 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:27:24 INFO - 2016-02-01 08:27:24,863 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:27:24 INFO - 2016-02-01 08:27:24,863 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:27:24 INFO - 2016-02-01 08:27:24,863 DEBUG : BROWSER_OUTPUT: __metrics 08:27:25 INFO - 2016-02-01 08:27:25,121 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] 08:27:26 INFO - 2016-02-01 08:27:26,325 INFO : Browser initialized. 08:27:26 INFO - 2016-02-01 08:27:26,326 INFO : Running cycle 1/1 for v8_7 test... 08:27:26 INFO - 2016-02-01 08:27:26,326 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBDeZvk/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:27:38 INFO - 2016-02-01 08:27:38,082 DEBUG : BROWSER_OUTPUT: RSS: Main: 134447104 08:27:38 INFO - 2016-02-01 08:27:38,082 DEBUG : BROWSER_OUTPUT: 08:27:59 INFO - 2016-02-01 08:27:59,464 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58603/tests/v8_7/run.html (next: http://localhost:58603/tests/v8_7/run.html) 08:27:59 INFO - 2016-02-01 08:27:59,722 DEBUG : BROWSER_OUTPUT: RSS: Main: 420319232 08:27:59 INFO - 2016-02-01 08:27:59,722 DEBUG : BROWSER_OUTPUT: 08:27:59 INFO - 2016-02-01 08:27:59,722 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:27:59 INFO - 2016-02-01 08:27:59,722 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:27:59 INFO - 2016-02-01 08:27:59,722 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:27:59 INFO - 2016-02-01 08:27:59,723 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:27:59 INFO - 2016-02-01 08:27:59,723 DEBUG : BROWSER_OUTPUT: |0;Richards;167.95431642593215 08:27:59 INFO - 2016-02-01 08:27:59,723 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.69038208168644 08:27:59 INFO - 2016-02-01 08:27:59,723 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.70680044593087 08:27:59 INFO - 2016-02-01 08:27:59,723 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5143.589743589743 08:27:59 INFO - 2016-02-01 08:27:59,723 DEBUG : BROWSER_OUTPUT: |4;RayTrace;974.6588693957115 08:27:59 INFO - 2016-02-01 08:27:59,723 DEBUG : BROWSER_OUTPUT: |5;Earley;542.8881650380022 08:27:59 INFO - 2016-02-01 08:27:59,724 DEBUG : BROWSER_OUTPUT: |6;Boyer;10923.91304347826 08:27:59 INFO - 2016-02-01 08:27:59,724 DEBUG : BROWSER_OUTPUT: |7;RegExp;24261.904761904763 08:27:59 INFO - 2016-02-01 08:27:59,724 DEBUG : BROWSER_OUTPUT: |8;Splay;528.8207297726071 08:27:59 INFO - 2016-02-01 08:27:59,724 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 08:27:59 INFO - 2016-02-01 08:27:59,724 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:27:59 INFO - 2016-02-01 08:27:59,724 DEBUG : BROWSER_OUTPUT: __startTimestamp1454344079716__endTimestamp 08:27:59 INFO - 2016-02-01 08:27:59,724 DEBUG : BROWSER_OUTPUT: 08:27:59 INFO - 2016-02-01 08:27:59,724 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:27:59 INFO - 2016-02-01 08:27:59,725 DEBUG : BROWSER_OUTPUT: Number of tests: 10 08:27:59 INFO - 2016-02-01 08:27:59,725 DEBUG : BROWSER_OUTPUT: 08:27:59 INFO - 2016-02-01 08:27:59,725 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.95 Median:167.95 stddev:0.00 (0.0%) 08:27:59 INFO - 2016-02-01 08:27:59,725 DEBUG : BROWSER_OUTPUT: Values: 168.0 08:27:59 INFO - 2016-02-01 08:27:59,725 DEBUG : BROWSER_OUTPUT: 08:27:59 INFO - 2016-02-01 08:27:59,725 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.69 Median:164.69 stddev:0.00 (0.0%) 08:27:59 INFO - 2016-02-01 08:27:59,725 DEBUG : BROWSER_OUTPUT: Values: 164.7 08:27:59 INFO - 2016-02-01 08:27:59,726 DEBUG : BROWSER_OUTPUT: 08:27:59 INFO - 2016-02-01 08:27:59,726 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.71 Median:278.71 stddev:0.00 (0.0%) 08:27:59 INFO - 2016-02-01 08:27:59,726 DEBUG : BROWSER_OUTPUT: Values: 278.7 08:27:59 INFO - 2016-02-01 08:27:59,726 DEBUG : BROWSER_OUTPUT: 08:27:59 INFO - 2016-02-01 08:27:59,726 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5143.59 Median:5143.59 stddev:0.00 (0.0%) 08:27:59 INFO - 2016-02-01 08:27:59,726 DEBUG : BROWSER_OUTPUT: Values: 5143.6 08:27:59 INFO - 2016-02-01 08:27:59,726 DEBUG : BROWSER_OUTPUT: 08:27:59 INFO - 2016-02-01 08:27:59,727 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:974.66 Median:974.66 stddev:0.00 (0.0%) 08:27:59 INFO - 2016-02-01 08:27:59,727 DEBUG : BROWSER_OUTPUT: Values: 974.7 08:27:59 INFO - 2016-02-01 08:27:59,727 DEBUG : BROWSER_OUTPUT: 08:27:59 INFO - 2016-02-01 08:27:59,727 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:542.89 Median:542.89 stddev:0.00 (0.0%) 08:27:59 INFO - 2016-02-01 08:27:59,727 DEBUG : BROWSER_OUTPUT: Values: 542.9 08:27:59 INFO - 2016-02-01 08:27:59,727 DEBUG : BROWSER_OUTPUT: 08:27:59 INFO - 2016-02-01 08:27:59,727 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10923.91 Median:10923.91 stddev:0.00 (0.0%) 08:27:59 INFO - 2016-02-01 08:27:59,727 DEBUG : BROWSER_OUTPUT: Values: 10923.9 08:27:59 INFO - 2016-02-01 08:27:59,728 DEBUG : BROWSER_OUTPUT: 08:27:59 INFO - 2016-02-01 08:27:59,728 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24261.90 Median:24261.90 stddev:0.00 (0.0%) 08:27:59 INFO - 2016-02-01 08:27:59,728 DEBUG : BROWSER_OUTPUT: Values: 24261.9 08:27:59 INFO - 2016-02-01 08:27:59,728 DEBUG : BROWSER_OUTPUT: 08:27:59 INFO - 2016-02-01 08:27:59,728 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:528.82 Median:528.82 stddev:0.00 (0.0%) 08:27:59 INFO - 2016-02-01 08:27:59,728 DEBUG : BROWSER_OUTPUT: Values: 528.8 08:27:59 INFO - 2016-02-01 08:27:59,728 DEBUG : BROWSER_OUTPUT: 08:27:59 INFO - 2016-02-01 08:27:59,729 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 08:27:59 INFO - 2016-02-01 08:27:59,729 DEBUG : BROWSER_OUTPUT: Values: 4822.1 08:27:59 INFO - 2016-02-01 08:27:59,729 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:27:59 INFO - 2016-02-01 08:27:59,729 DEBUG : BROWSER_OUTPUT: 08:28:00 INFO - 2016-02-01 08:28:00,422 INFO : Browser exited with error code: 0 08:28:00 INFO - 2016-02-01 08:28:00,438 INFO : Completed test v8_7 (00:00:40) 08:28:00 INFO - 2016-02-01 08:28:00,740 INFO : Completed test suite (00:20:12) 08:28:00 INFO - 2016-02-01 08:28:00,740 DEBUG : Working with test: dromaeo_css 08:28:00 INFO - 2016-02-01 08:28:00,740 DEBUG : Generating results file: dromaeo_css 08:28:00 INFO - 2016-02-01 08:28:00,741 DEBUG : Working with test: kraken 08:28:00 INFO - 2016-02-01 08:28:00,742 DEBUG : Generating results file: kraken 08:28:00 INFO - 2016-02-01 08:28:00,742 INFO : javascript benchmark 08:28:00 INFO - 2016-02-01 08:28:00,742 DEBUG : Working with test: v8_7 08:28:00 INFO - 2016-02-01 08:28:00,742 DEBUG : Generating results file: v8_7 08:28:00 INFO - 2016-02-01 08:28:00,742 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:28:00 INFO - 2016-02-01 08:28:00,795 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 08:28:00 INFO - 2016-02-01 08:28:00,796 DEBUG : process_Request line: dromaeo_css 7288.72 graph.html#tests=[[72,53,35]] 08:28:00 INFO - 2016-02-01 08:28:00,796 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:28:00 INFO - 2016-02-01 08:28:00,830 DEBUG : process_Request line: kraken 1474.00 graph.html#tests=[[232,53,35]] 08:28:00 INFO - 2016-02-01 08:28:00,830 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:28:00 INFO - 2016-02-01 08:28:00,862 DEBUG : process_Request line: v8_7 23133.50 graph.html#tests=[[230,53,35]] 08:28:00 INFO - 2016-02-01 08:28:00,864 INFO : javascript benchmark 08:28:00 INFO - 2016-02-01 08:28:00,866 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3323.468245080787, 3354.379439803638, 3378.8141652209133, 3384.8476818543177, 3399.082247793096, 5495.258140151083, 5581.776728930838, 5594.328680558328, 5594.748236329365, 5596.160575913615, 5559.444055594438, 5617.101263797793, 5619.072852979264, 5620.91568626471, 5625.071863142587, 5000.849974500771, 5061.898762024757, 5085.059264036166, 5106.310648062501, 5161.225816127573, 5077.974610126944, 5090.440051594322, 5144.228365745131, 5147.639665223436, 5148.974255128738, 5054.949450505503, 5060.822871199509, 5140.0490909181735, 5181.4300426953005, 5185.0, 5168.508991645787, 5204.193350030713, 5206.557442617401, 5209.0, 5237.9476205238225, 4916.827911023097, 5096.566791822699, 5116.437191908887, 5168.508991645787, 5186.273921650972, 5289.285946397225, 5337.226102215196, 5346.545543628797, 5349.759260833272, 5384.892302153974, 6007.36922623127, 6019.548533859978, 6058.333583305833, 6059.575829691923, 6071.150038994544, 5944.613600115978, 5982.73077711504, 6098.0852872069545, 6098.72555734993, 6103.176071230371, 5517.503424691797, 5592.245046918695, 5596.888062238732, 5604.411536788579, 5633.155026746021, 6179.876402472015, 6220.471259942865, 6220.968895155495, 6223.782167624202, 6224.221972253468, 5760.193572899798, 5871.031279838779, 5872.530197584183, 5995.760169593167, 6011.12838638395, 5993.101034844808, 5994.07091900756, 5994.940050599438, 5996.730147143302, 6045.365236650088, 5912.763489460373, 5945.048792193228, 5957.1362152487645, 5960.0761881908375, 5969.880602387928, 5823.184754134425, 5921.230240068763, 5964.194833697482, 5997.220361352996, 6104.572679912363, 4649.279361698942, 4656.5110663379855, 4658.231391820379, 4668.486466488684, 4684.133435314409, 4156.521999969982, 4165.625093741578, 4181.477315335583, 4188.91622167561, 4219.430376899079, 5614.214010038598, 5690.601657883991, 5699.914501282484, 5704.515116215085, 5828.417158284137, 6103.667681453349, 6180.258368995749, 6182.845428864314, 6184.938150618526, 6185.567010309325, 5819.709763059651, 5871.3541510433815, 5877.206577112121, 5886.175935369051, 5907.232059832194, 5154.587632989352, 5193.610479214074, 5301.655392399482, 5311.097113490639, 5333.866653333697, 6124.3609037543765, 6272.5922815016875, 6273.435390814758, 6289.088082228142, 6291.433770960635, 6083.513318934386, 6092.055731361469, 6099.817005489843, 6101.816945491642, 6102.298235702951, 6104.847378815565, 6143.109249158936, 6198.6280823150755, 6206.627602343874, 6274.996000639876, 5968.313643931004, 5970.9701451492465, 5984.521238300838, 5991.970040149771, 6021.969890150521, 6105.8168254952425, 6112.633242005494, 6120.17377654011, 6123.571350005457, 6136.32500424944, 5527.8894422110525, 5623.606347555632, 5851.414858514115, 5950.434708702839, 5955.40445955401, 5878.059510478303, 5967.850803729942, 5973.074173503114, 5980.700964951648, 5981.282246130492, 5840.065589505659, 5856.238688970407, 5866.413358664099, 5866.472017518273, 5867.765289388377, 5854.356020837789, 5866.560007999503, 5934.3175534814045, 5987.0, 6003.189569408074, 5794.275715535558, 5797.333306669787, 5798.623089499169, 5804.21643078179, 5817.360090390139], "unit": "ms", "name": "dojo.html", "value": 5567.112528982074}, {"lowerIsBetter": false, "replicates": [589.6904125334199, 638.8083574927526, 645.071097619428, 650.1775254303304, 656.346934799874, 29193.41613167738, 29865.701342986562, 29896.70103298969, 40627.796861015726, 41440.37839432411, 43249.35125973105, 43666.7816660917, 43751.56248437515, 43841.0, 43888.780556097256, 8981.056989016157, 9132.726018219464, 9151.084891510862, 9160.633574657038, 9165.762622046004, 8274.0, 8458.238758511732, 10530.42082685452, 10810.243282970196, 10820.13438924884, 10590.682279531626, 10731.624393146241, 10742.086922611586, 10746.086582640446, 10750.03249707526, 10562.26064175508, 10709.625163119254, 10713.035826775627, 10715.19636027301, 10723.088537474285, 10440.425776582286, 10614.73463163427, 10629.468526573717, 10637.149028077734, 10639.680809575726, 41771.37342939858, 42414.36378454325, 42526.57473425257, 42553.57446425558, 42582.0, 3555.8219092566133, 3615.403458429356, 3617.0234036810175, 3941.960580394188, 4049.1901619675887, 31555.684443155504, 31936.520952185736, 31945.201369965704, 32065.83967080173, 32443.67556324454, 4288.335308027261, 4329.631981281595, 4353.499347575038, 4393.29707246839, 4414.073044660625, 276268.0, 278880.0, 279550.0, 279836.0, 279912.0, 58150.0, 58900.0, 61730.0, 62852.37147628557, 62924.0, 56396.43603563994, 56569.71715141398, 56629.15056274159, 56729.71635141798, 56792.0, 15635.530934071996, 15759.054456732632, 15866.286017129258, 15870.60323491922, 15892.920535397248, 11819.586314479124, 11911.10666700001, 11922.225055371373, 12001.939990300167, 12002.939985300192, 20356.0, 20604.896975515025, 20619.381418557467, 20693.27573534919, 20712.58574828525, 7459.664315105833, 7560.659770310349, 7632.045994250719, 7634.770956871303, 7678.884816727754, 1026.1431704526653, 1029.8558201851747, 1088.3633074651418, 1116.213069785799, 1137.323292640894, 28551.0, 28939.98822320026, 29560.704392956224, 29618.555721664194, 29751.553726694117, 1089.4171618184234, 1159.675290918544, 1172.0, 1178.4402408855724, 1258.3519487463964, 13488.797668034988, 15044.0, 15620.062796232036, 16312.918435407748, 16510.917445412695, 339769.0, 343672.0, 344365.0, 344439.0, 345760.0, 55375.72312138414, 55710.72144639332, 55788.442115579135, 55827.0, 55924.44075559274, 1349.2107117336272, 1354.7832346824462, 1372.670559065837, 1382.1845111384332, 1394.532831501438, 29800.0, 29950.101496955125, 30238.84880575583, 30545.389092217585, 31602.0, 49613.25580116373, 50080.499195007586, 50138.247926280404, 50166.0, 50251.0, 258902.0, 259271.0, 260222.0, 261541.0, 261927.0, 29047.273818154714, 33214.5017824728, 33548.83225583954, 34357.656423435445, 34536.65463345334, 39389.212215756095, 39558.604413955494, 39722.60277397305, 40751.0, 40903.795481022404, 33806.661933380354, 34000.48999264964, 34020.829895850366, 34288.0, 34495.482567762, 31378.843105784326, 36659.81670091739, 37051.0, 38222.0, 38479.23041539209, 37866.0, 38192.42711359276, 38480.80759596184, 38645.806770965966, 39112.217755645295, 29092.127236182947, 29266.0, 31564.842175788974, 32537.349253014334, 32678.83660581777, 24944.027524994246, 25638.230853074438, 25760.10224560889, 25873.611895821203, 25951.87024064943], "unit": "ms", "name": "ext.html", "value": 19306.286195097342}, {"lowerIsBetter": false, "replicates": [380.2907577368208, 380.4787441205548, 381.23181788695786, 382.8181613733475, 384.2027792330914, 14709.558713238594, 16095.517134485985, 16449.17754112294, 17973.370932017366, 17977.64044719105, 16355.59111022225, 16545.586360340967, 16563.917180414082, 17741.112944352797, 17772.200250988702, 5585.273914391123, 5699.660579763752, 5708.600397972144, 5713.228714123603, 5715.057015592422, 4312.374705667677, 4433.556644335573, 5635.802746903859, 6081.239845019372, 6306.400891915284, 6108.755649774003, 6188.44304012641, 6235.376462353751, 6260.968695156518, 6267.4045965633195, 5254.448282930275, 6303.810885673437, 6305.716242769064, 6306.180196574439, 6308.053791931201, 5225.163973764217, 5538.584606154514, 5542.584306177013, 5563.721813909288, 5567.220589117527, 20007.899960500104, 20197.495062623406, 20271.391858244275, 21341.786582134137, 21379.465513362135, 2316.247219653619, 2334.5564342774987, 2357.8821058947156, 2448.926532204037, 2463.224084413422, 9950.054744799232, 10053.195744340435, 10183.490825458697, 11616.941915290454, 13925.30373481332, 1953.6483432982056, 1958.5593241520687, 1964.6463636545416, 1987.4037788663488, 1994.6409646263667, 63183.368166318665, 64011.359886401005, 64379.0, 65161.0, 66296.3370366295, 10808.405537695502, 10811.18916081297, 10818.350898945931, 10827.404492752818, 10884.945575272232, 14404.855951440351, 14479.276036198147, 14570.125792452487, 14586.927065364818, 14627.0, 7578.658960346687, 7603.201663825329, 7615.16233214346, 7777.494462859896, 7815.609219539, 7148.5710857347685, 7151.499395042297, 7167.1757747859665, 7219.278072192844, 7225.2774722528375, 4645.233536466513, 4652.255639097728, 4660.627149828006, 4710.175719249118, 4736.976315118472, 1749.309857417982, 1793.5336812428861, 1874.9625007499774, 1970.773361063496, 1974.575466274729, 962.1340793286098, 1069.2408390043156, 1078.8327809189589, 1102.3496137279044, 1126.3692332293942, 14612.342444590018, 16594.0, 16708.0, 16944.830551694573, 17337.306507739802, 976.0, 980.2207245240052, 1015.4719545836124, 1040.2042437535292, 1087.3095584304024, 7048.964755176191, 9549.236061115094, 9632.9036709632, 9720.125188733047, 9764.804703905882, 608950.0, 611980.0, 625219.0, 633224.0, 635327.0, 78096.60951695206, 78155.60922195467, 80179.59910200529, 80405.0, 82618.0, 1152.3316476443642, 1154.6362895687832, 1191.5293459083614, 1194.3729541990351, 1205.5840734946605, 15231.16228607393, 15332.0, 15654.608634784221, 15742.29159687794, 15839.366425343318, 35468.137206107946, 35620.28759424745, 35639.64360356363, 35957.460638090975, 35986.64013359833, 44431.0, 44847.0, 44903.92793724913, 45873.311900320856, 47411.0, 10098.596056157965, 10181.185505159718, 10258.897411025795, 10364.533595988349, 10365.0, 14371.784423233868, 18274.543136421697, 18815.623687526448, 18833.058347082864, 18907.43277701671, 15601.219939002778, 15641.53075407694, 15735.763963540327, 16055.759163612789, 16066.919665401598, 15050.172240526876, 15614.297356619207, 15674.9216253918, 15691.843081569039, 15727.606809829846, 15480.84519154794, 15787.842121578637, 15845.049297042215, 16188.838111618734, 16190.514284571482, 10207.387556746618, 10364.481775911023, 10458.529366178387, 10526.842097368699, 10539.736506587396, 10912.127029837753, 10998.12015038779, 14959.775603366175, 15548.0, 16019.439319623758], "unit": "ms", "name": "jquery.html", "value": 9354.611326696755}, {"lowerIsBetter": false, "replicates": [1577.8027746531684, 1598.0890892191455, 1631.8776091793115, 1771.521689143931, 1854.2675643120958, 7707.26780955809, 7850.646720897559, 7851.371890248781, 7854.607269636524, 7869.409794265424, 6931.480138989571, 7134.607596582186, 7140.857182856353, 7595.43034272429, 7967.521948683083, 2294.1167650454613, 2311.2835021143414, 2337.649352597107, 2337.649352597107, 2341.6487526870937, 2346.8709220992837, 2376.453415714378, 2386.7971222446113, 2387.3554140382084, 2394.341556072077, 2028.1785876719953, 2073.1189244571074, 2276.84062115652, 2313.687652166961, 2316.2356422380576, 2255.481239314967, 2263.309690544383, 2282.942926426836, 2285.999999999992, 2292.197730794209, 2335.544568809083, 2342.988285058581, 2365.053978408633, 2366.0417530900013, 2367.3253122860074, 7944.927434796344, 7994.040715114165, 7998.560079195642, 8002.51984880909, 8007.559584222866, 4684.437867455893, 4791.065742180276, 4795.880102997418, 4815.638827087947, 4824.517548245182, 3192.233863872677, 3199.792013519114, 3227.5320078588475, 3292.1769557610596, 3327.619038099186, 1054.0039662518923, 1069.3370110531444, 1096.6161843354841, 1104.276698762312, 1143.6854864912132, 10908.727281817939, 11216.0, 11224.551017959271, 11237.550497980072, 11244.437778111143, 4236.068065025689, 4278.657707383401, 4308.3968244445805, 4318.978405107954, 4332.4367832181615, 9175.357724959322, 9177.08229177077, 9179.082091790768, 9180.127887850644, 9181.540922953825, 4868.245421959602, 4873.853784386474, 4875.219964805614, 4876.146674331978, 4879.878003049952, 4452.643788496913, 4500.437445319335, 4505.481869584974, 4507.121111383249, 4518.548145185455, 3547.716182705429, 3577.4276115821344, 3583.6416358364477, 3596.2268112355446, 3609.296187243462, 1046.3721766939927, 1083.6965649618119, 1102.1238115698038, 1109.9944500277447, 1115.3363748569589, 1005.818952588541, 1085.7394225386008, 1098.0666433531435, 1299.5412798391976, 1447.7828325751223, 7286.089238845145, 7352.742654007084, 7391.484745627134, 7396.55620662762, 7399.889001664979, 1158.7103224193952, 1177.776222517719, 1178.5698220149584, 1200.9819852702217, 1202.939853007346, 5907.556933229939, 5969.850753731192, 5973.910391344133, 5982.132590774313, 5991.64050156992, 9260.444373337486, 9794.118529332252, 9798.65704700332, 9804.019598040282, 9805.264605154642, 9653.662121825702, 9660.32377733552, 9673.177779888789, 9684.806303873884, 9685.225181985425, 7454.142773580999, 7627.4279429041935, 7628.313451789366, 7713.035870516298, 7725.150233474314, 7253.347198752138, 7304.780856574312, 7337.3029562191505, 7338.449616278907, 7343.559386436831, 8496.06543280251, 8738.388312818042, 8759.080296569024, 8784.033756286932, 8790.0, 8747.16901894319, 8758.211760941545, 8871.778205544913, 8958.731238062868, 8968.461892286223, 6990.475714321345, 7073.504854660125, 7073.752418665322, 7099.751508697171, 7106.928930710626, 7581.3176814087, 7693.3076023155945, 7748.961255193688, 7799.415043871619, 7811.023622047244, 7257.891131632924, 7341.59621220838, 7352.191258961403, 7398.445116616168, 7435.739749108755, 5884.823455296348, 6166.630002199882, 6788.422984046411, 7131.393831524171, 7276.527025743309, 7092.397146242421, 7145.714171433292, 7148.0350152728715, 7155.749548765768, 7204.135503739585, 6327.0, 6350.491960643045, 6809.353111454404, 6815.591064536144, 6824.385805277549, 6861.691223894837, 6921.723131074902, 6922.653867306715, 6924.238333783179, 6929.099217101744], "unit": "ms", "name": "mootools.html", "value": 4597.590474424415}, {"lowerIsBetter": false, "replicates": [334.0046660950367, 335.9630440651528, 340.8551365669591, 351.7115964908776, 358.3406531981156, 6039.094135879614, 6069.635821850692, 6199.938000619993, 6200.658963756997, 6214.316425193225, 5912.408759124085, 6048.546359023069, 6070.969645151768, 6076.605020673653, 6084.5132389408855, 4687.6249900007915, 4690.624750019999, 4696.238910408597, 4697.718136911796, 4698.483166851644, 4695.812167513295, 4698.036902435009, 4699.624030077603, 4702.365180700613, 4708.8587342379615, 4514.367988481598, 4620.676552641316, 4621.861344159681, 4630.282306242521, 4661.953380466202, 4589.0821835633005, 4600.35595016698, 4607.746573938432, 4610.97694511527, 4611.400517932664, 3870.7290489665734, 3981.4824072870633, 4040.5555388907196, 4044.959550404488, 4050.0887300357185, 5725.112607545795, 5774.682392468455, 5820.621659592113, 5828.679422631753, 5829.154772557998, 5352.170413585901, 5360.954613850301, 5361.2226227197225, 5367.141257398797, 5368.275282836806, 6369.745210191587, 6412.23053233615, 6473.06140609609, 6477.83805404864, 6478.93521064788, 4427.380166776622, 4441.600255976945, 4443.400140980959, 4446.110777844444, 4447.73313601185, 6614.636195009272, 6698.296678848699, 6699.296573859772, 6700.698468568926, 6706.698198581075, 4565.338025986213, 4566.3150527420485, 4566.725996440157, 4568.451785785727, 4575.0, 4616.446026476844, 4618.538146185355, 4620.214563524209, 4621.1219868224925, 4634.884127896829, 5465.289512363367, 5473.9452605474235, 5483.75323110461, 5488.780448782004, 5489.478499542537, 6537.1501704778075, 6546.70539825709, 6551.0, 6555.246146693096, 6557.442617377481, 1647.2011074628795, 1681.9831801682071, 1682.1084825042747, 1684.107423065777, 1684.4441334359633, 1133.7789131119353, 1145.7536629624672, 1153.9538418463335, 1173.5833779008328, 1184.4788293150987, 6369.394907483875, 6376.553641245161, 6377.808665740035, 6378.649174295459, 6380.776672816429, 6728.0, 6733.562318449285, 6734.494912881553, 6736.46108311334, 6744.696488658022, 6229.221347331583, 6342.619442833445, 6370.490360771128, 6370.904436433457, 6374.58565193261, 3604.441311596707, 3646.3072016316814, 3659.469376940382, 3671.30245253401, 3672.6878215351994, 6703.296153903867, 6826.590404575642, 6946.2011868634745, 7002.544834585736, 7056.964715176391, 6966.407855332252, 6968.860622787516, 6975.825604359931, 6976.093107896043, 6981.232064472904, 6824.6928888200155, 7046.894296585555, 7048.471364647672, 7060.3292687194635, 7067.89398159028, 6781.152355955505, 6787.389134977876, 6788.25329213796, 6790.660466976533, 6801.1158549388265, 6651.0, 6693.799186024427, 6707.396334329934, 6708.530402871947, 6713.966430167818, 6781.457483401217, 6937.791866244021, 6943.194769360104, 6943.9652801735665, 7042.119735033121, 4576.496585375539, 4592.770361481846, 4594.1730488512385, 4601.401817763669, 4601.516840731809, 6788.2193547742645, 6796.2524122347495, 6796.592204467748, 6797.252302246848, 6797.864042719217, 6464.741410343534, 6553.705083271359, 6553.901691474727, 6581.868362632625, 6606.339366063356, 6506.219253689398, 6511.902321465087, 6516.315786842503, 6518.73925043012, 6519.348065193442, 6487.935120648733, 6488.545801793829, 6494.577852439576, 6494.675266236574, 6498.902516462163, 6474.417302442803, 6611.206655201214, 6622.503312251504, 6632.369924857131, 6664.0, 6501.934980650133, 6508.772192973223, 6510.023496475377, 6510.316416776171, 6517.348265173445], "unit": "ms", "name": "prototype.html", "value": 4921.582918575507}, {"lowerIsBetter": false, "replicates": [928.9117533834285, 929.0987741890366, 936.4100616611533, 942.6700654770831, 964.0937518732395, 13562.186268823853, 13579.117357371764, 13586.864131358683, 13750.862491375083, 13753.51862684805, 13847.792283115736, 13927.721445571082, 13927.860721392808, 13933.164010159397, 13946.651333716662, 716.0977168767339, 718.5078221418319, 730.0545793197822, 731.9414446844239, 853.197993885747, 827.0034608296987, 832.8584140696096, 836.6318819719304, 838.932885369172, 839.8320335932807, 746.5781833264189, 902.2917010147036, 907.020417948614, 911.4075850697034, 911.8677791720197, 735.5439627430974, 893.1158153428092, 904.7873749668888, 905.1762895764888, 905.3888625178045, 659.7097277198018, 666.183924692253, 668.588817877007, 669.357416879796, 669.5313280703479, 13322.80015799754, 13355.465781368734, 13406.46374145033, 13425.798613020812, 13433.39549720265, 12523.248605083632, 12700.80948785769, 12837.358132093452, 12937.676558086028, 13224.27266500342, 10024.749381265454, 10066.245031622657, 10136.0, 10249.33379330341, 10406.115480184193, 3933.6263055009726, 3940.527136743609, 3962.187751510933, 3977.5823538528616, 3980.960190398088, 43066.0, 43754.99999999968, 43800.56199437997, 44046.33930491045, 44811.32783008225, 10773.084287835465, 10870.021698047056, 10921.672349829518, 10959.342439453658, 11146.0, 18614.813851861578, 18623.53441163982, 18651.440456786317, 18675.533111672044, 18744.718829217574, 7704.229577042251, 7936.4047696423, 8040.276375126266, 8086.474379165335, 8111.553864537389, 4656.650751193674, 4697.224957881979, 4710.26990816424, 4729.314249433813, 4736.076465089343, 3312.2381852174135, 3347.681970212826, 3347.983260083684, 3351.497275408708, 3353.84907679155, 599.6342231238941, 623.9563230573816, 624.5971348480296, 628.9119523266746, 635.3614617309574, 606.3663471672113, 713.9428845692332, 714.746265075901, 740.4113729584993, 765.3953376832352, 8816.911830881738, 9215.94016688076, 9247.306452016126, 9283.87639414914, 9302.674406395743, 589.3105067071536, 662.8541720821412, 692.5671455340412, 741.9740309089156, 747.1669088965873, 4336.9100636637795, 4405.40527028847, 4416.1609294233995, 4432.933505997413, 4496.9100617988115, 56397.7180114105, 56830.715846420506, 56937.145942810894, 57044.0, 57065.42934570601, 50835.49164508308, 56460.0, 57155.0, 57318.14022789661, 57507.0, 46128.538714613096, 46690.29964550534, 47066.82332941635, 47337.0, 47878.760606197444, 8332.583370831313, 8467.195616416431, 8616.526091065052, 8822.955885220534, 8867.778305542155, 34976.475352870235, 35746.0, 36020.63979360173, 36033.0, 38673.80663096779, 43607.0, 44294.335584966895, 44982.0, 45592.0, 45863.31205031994, 10566.947165264124, 11052.668419947415, 12120.818187727367, 12198.14612977083, 12334.50661973511, 13350.866491334962, 13543.59369218925, 13607.455701772215, 14003.43986240539, 14353.282335883372, 12046.698832529257, 12252.0, 12353.258804471761, 12493.312867791996, 13093.738125237633, 10738.516766745359, 11049.613263535737, 12273.693157670772, 12446.0, 12731.93634031824, 10427.593872659492, 10581.841272381074, 10826.729331766768, 11676.241044332432, 12159.088068395082, 9600.231981441328, 9642.855357169788, 9807.558659860466, 9822.263330250116, 10411.739706507095, 9652.372595781533, 9855.655052073429, 10233.744156395851, 10348.275620706478, 10585.841212381974], "unit": "ms", "name": "yui.html", "value": 6589.931631786748}], "name": "dromaeo_css", "value": 7288.72253906739}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 109.0, 102.0, 107.0, 109.0, 108.0, 98.0, 109.0, 108.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 106.8}, {"lowerIsBetter": true, "replicates": [159.0, 120.0, 147.0, 169.0, 123.0, 154.0, 145.0, 127.0, 159.0, 120.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.3}, {"lowerIsBetter": true, "replicates": [160.0, 159.0, 160.0, 159.0, 159.0, 159.0, 159.0, 161.0, 159.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 159.6}, {"lowerIsBetter": true, "replicates": [70.0, 71.0, 71.0, 146.0, 74.0, 71.0, 70.0, 72.0, 157.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 87.5}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 137.0, 92.0, 92.0, 93.0, 134.0, 93.0, 93.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 105.2}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 146.0, 145.0, 146.0, 147.0, 145.0, 146.0, 145.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.7}, {"lowerIsBetter": true, "replicates": [97.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.7}, {"lowerIsBetter": true, "replicates": [60.0, 60.0, 61.0, 58.0, 62.0, 58.0, 59.0, 59.0, 57.0, 57.0], "unit": "ms", "name": "json-parse-financial", "value": 59.1}, {"lowerIsBetter": true, "replicates": [64.0, 63.0, 63.0, 64.0, 64.0, 63.0, 64.0, 63.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.6}, {"lowerIsBetter": true, "replicates": [78.0, 81.0, 78.0, 77.0, 78.0, 78.0, 78.0, 79.0, 78.0, 79.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.4}, {"lowerIsBetter": true, "replicates": [122.0, 113.0, 111.0, 118.0, 123.0, 118.0, 111.0, 114.0, 117.0, 117.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 116.4}, {"lowerIsBetter": true, "replicates": [142.0, 141.0, 142.0, 142.0, 143.0, 142.0, 140.0, 141.0, 139.0, 130.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 140.2}, {"lowerIsBetter": true, "replicates": [67.0, 63.0, 64.0, 63.0, 66.0, 65.0, 64.0, 64.0, 63.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.3}], "name": "kraken", "value": 1474.0000000000005}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.95431642593215], "unit": "score", "name": "Richards", "value": 210.18810800000006}, {"lowerIsBetter": false, "replicates": [164.69038208168644], "unit": "score", "name": "DeltaBlue", "value": 401.4684960000001}, {"lowerIsBetter": false, "replicates": [278.70680044593087], "unit": "score", "name": "Encrypt", "value": 955.0574279999998}, {"lowerIsBetter": false, "replicates": [5143.589743589743], "unit": "score", "name": "Decrypt", "value": 51.75004486540379}, {"lowerIsBetter": false, "replicates": [974.6588693957115], "unit": "score", "name": "RayTrace", "value": 759.2287140000002}, {"lowerIsBetter": false, "replicates": [542.8881650380022], "unit": "score", "name": "Earley", "value": 1227.624846}, {"lowerIsBetter": false, "replicates": [10923.91304347826], "unit": "score", "name": "Boyer", "value": 61.0095482587065}, {"lowerIsBetter": false, "replicates": [24261.904761904763], "unit": "score", "name": "RegExp", "value": 37.547958783120734}, {"lowerIsBetter": false, "replicates": [528.8207297726071], "unit": "score", "name": "Splay", "value": 154.099481}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 23133.504543420255}]} 08:28:00 INFO - RETURN: dromaeo_css: 7288.72 08:28:00 INFO - RETURN: kraken: 1474.00 08:28:00 INFO - RETURN: v8_7: 23133.50 08:28:00 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23133.50"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1474.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7288.72"}}} 08:28:00 INFO - Return code: 0 08:28:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 08:28:00 INFO - # TBPL SUCCESS # 08:28:00 INFO - Running post-action listener: _resource_record_post_action 08:28:00 INFO - Running post-run listener: _resource_record_post_run 08:28:02 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 57344; Write bytes: 335912960; Read time: 80; Write time: 714728 08:28:02 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 71659520; Read time: 0; Write time: 132956 08:28:02 INFO - run-tests - Wall time: 1214s; CPU: 13.0%; Read bytes: 57344; Write bytes: 264253440; Read time: 80; Write time: 581772 08:28:02 INFO - Running post-run listener: _upload_blobber_files 08:28:02 INFO - Blob upload gear active. 08:28:02 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 08:28:02 INFO - Copying logs to upload dir... 08:28:02 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1275.825262 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-02-01 08:28:02.164500) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-01 08:28:02.168244) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010084 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454334147/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-02-01 08:28:02.218735) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:28:02.219069) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1454342804.474563-1129618607 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004614 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:28:02.268829) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-01 08:28:02.269133) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-01 08:28:02.269422) ========= ========= Total master_lag: 0.24 =========