builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-055 starttime: 1453869012.54 results: success (0) buildid: 20160126180419 builduid: 3d64e70fb967408588e7bd363160b69f revision: cb40dba596a5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:12.536242) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:12.536702) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:12.537035) ========= 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=82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006424 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:12.570250) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:12.570600) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:12.619520) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:12.619860) ========= 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=82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004573 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:12.669763) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:12.670074) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:12.670482) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:12.670800) ========= 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=82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-26 20:30:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 77.3M=0s 2016-01-26 20:30:13 (77.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098356 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:12.781892) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:12.782240) ========= 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=82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022234 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:12.832243) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:12.832634) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev cb40dba596a5 --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 cb40dba596a5 --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=82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-26 20:30:13,654 Setting DEBUG logging. 2016-01-26 20:30:13,654 attempt 1/10 2016-01-26 20:30:13,654 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/cb40dba596a5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-26 20:30:13,902 unpacking tar archive at: mozilla-beta-cb40dba596a5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.540284 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:13.387525) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:13.387902) ========= echo cb40dba596a5 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'cb40dba596a5'] 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=82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False cb40dba596a5 program finished with exit code 0 elapsedTime=0.003977 script_repo_revision: 'cb40dba596a5' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:13.436815) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:13.437104) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-26 20:30:13.447361) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-01-26 20:30:13.447756) ========= /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=82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:30:14 INFO - MultiFileLogger online at 20160126 20:30:14 in /builds/slave/test-pgo 20:30:14 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 20:30:14 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 20:30:14 INFO - {'append_to_log': False, 20:30:14 INFO - 'base_work_dir': '/builds/slave/test-pgo', 20:30:14 INFO - 'blob_upload_branch': 'Mozilla-Beta', 20:30:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 20:30:14 INFO - 'branch': 'Mozilla-Beta', 20:30:14 INFO - 'buildbot_json_path': 'buildprops.json', 20:30:14 INFO - 'config_files': ('talos/linux_config.py',), 20:30:14 INFO - 'default_actions': ('clobber', 20:30:14 INFO - 'read-buildbot-config', 20:30:14 INFO - 'download-and-extract', 20:30:14 INFO - 'populate-webroot', 20:30:14 INFO - 'create-virtualenv', 20:30:14 INFO - 'install', 20:30:14 INFO - 'run-tests'), 20:30:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:30:14 INFO - 'download_minidump_stackwalk': True, 20:30:14 INFO - 'download_symbols': 'ondemand', 20:30:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:30:14 INFO - 'tooltool.py': '/tools/tooltool.py', 20:30:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:30:14 INFO - '/tools/misc-python/virtualenv.py')}, 20:30:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:30:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:30:14 INFO - 'installer_path': 'installer.exe', 20:30:14 INFO - 'log_level': 'info', 20:30:14 INFO - 'log_name': 'talos', 20:30:14 INFO - 'log_to_console': True, 20:30:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:30:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:30:14 INFO - 'opt_config_files': (), 20:30:14 INFO - 'pip_index': False, 20:30:14 INFO - 'sps_profile': False, 20:30:14 INFO - 'sps_profile_interval': 0, 20:30:14 INFO - 'suite': 'dromaeojs', 20:30:14 INFO - 'system_bits': '32', 20:30:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:30:14 INFO - 'title': 'talos-linux64-ix-055', 20:30:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:30:14 INFO - 'use_talos_json': True, 20:30:14 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 20:30:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:30:14 INFO - 'work_dir': 'build'} 20:30:14 INFO - ##### 20:30:14 INFO - ##### Running clobber step. 20:30:14 INFO - ##### 20:30:14 INFO - Running pre-action listener: _resource_record_pre_action 20:30:14 INFO - Running main action method: clobber 20:30:14 INFO - rmtree: /builds/slave/test-pgo/build 20:30:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 20:30:14 INFO - Running post-action listener: _resource_record_post_action 20:30:14 INFO - ##### 20:30:14 INFO - ##### Running read-buildbot-config step. 20:30:14 INFO - ##### 20:30:14 INFO - Running pre-action listener: _resource_record_pre_action 20:30:14 INFO - Running main action method: read_buildbot_config 20:30:14 INFO - Using buildbot properties: 20:30:14 INFO - { 20:30:14 INFO - "properties": { 20:30:14 INFO - "buildnumber": 88, 20:30:14 INFO - "product": "firefox", 20:30:14 INFO - "script_repo_revision": "production", 20:30:14 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 20:30:14 INFO - "repository": "", 20:30:14 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 20:30:14 INFO - "buildid": "20160126180419", 20:30:14 INFO - "slavename": "talos-linux64-ix-055", 20:30:14 INFO - "pgo_build": "True", 20:30:14 INFO - "basedir": "/builds/slave/test-pgo", 20:30:14 INFO - "project": "", 20:30:14 INFO - "platform": "ubuntu64_hw", 20:30:14 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 20:30:14 INFO - "slavebuilddir": "test", 20:30:14 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 20:30:14 INFO - "branch": "mozilla-beta", 20:30:14 INFO - "repo_path": "releases/mozilla-beta", 20:30:14 INFO - "stage_platform": "linux64-pgo", 20:30:14 INFO - "builduid": "3d64e70fb967408588e7bd363160b69f", 20:30:14 INFO - "revision": "cb40dba596a5" 20:30:14 INFO - }, 20:30:14 INFO - "sourcestamp": { 20:30:14 INFO - "repository": "", 20:30:14 INFO - "hasPatch": false, 20:30:14 INFO - "project": "", 20:30:14 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 20:30:14 INFO - "changes": [ 20:30:14 INFO - { 20:30:14 INFO - "category": null, 20:30:14 INFO - "files": [ 20:30:14 INFO - { 20:30:14 INFO - "url": null, 20:30:14 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/firefox-45.0.en-US.linux-x86_64.tar.bz2" 20:30:14 INFO - } 20:30:14 INFO - ], 20:30:14 INFO - "repository": "", 20:30:14 INFO - "rev": "cb40dba596a5", 20:30:14 INFO - "who": "sendchange", 20:30:14 INFO - "when": 1453868930, 20:30:14 INFO - "number": 7522018, 20:30:14 INFO - "comments": "Bug 1242641 - GTK+3 still not working for buildbot builds on beta. r=mshal a=ritu on CLOSED TREE", 20:30:14 INFO - "project": "", 20:30:14 INFO - "at": "Tue 26 Jan 2016 20:28:50", 20:30:14 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 20:30:14 INFO - "revlink": "", 20:30:14 INFO - "properties": [ 20:30:14 INFO - [ 20:30:14 INFO - "buildid", 20:30:14 INFO - "20160126180419", 20:30:14 INFO - "Change" 20:30:14 INFO - ], 20:30:14 INFO - [ 20:30:14 INFO - "builduid", 20:30:14 INFO - "3d64e70fb967408588e7bd363160b69f", 20:30:14 INFO - "Change" 20:30:14 INFO - ], 20:30:14 INFO - [ 20:30:14 INFO - "pgo_build", 20:30:14 INFO - "True", 20:30:14 INFO - "Change" 20:30:14 INFO - ] 20:30:14 INFO - ], 20:30:14 INFO - "revision": "cb40dba596a5" 20:30:14 INFO - } 20:30:14 INFO - ], 20:30:14 INFO - "revision": "cb40dba596a5" 20:30:14 INFO - } 20:30:14 INFO - } 20:30:14 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/firefox-45.0.en-US.linux-x86_64.tar.bz2. 20:30:14 INFO - Running post-action listener: _resource_record_post_action 20:30:14 INFO - ##### 20:30:14 INFO - ##### Running download-and-extract step. 20:30:14 INFO - ##### 20:30:14 INFO - Running pre-action listener: _resource_record_pre_action 20:30:14 INFO - Running main action method: download_and_extract 20:30:14 INFO - mkdir: /builds/slave/test-pgo/build/tests 20:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:14 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/test_packages.json 20:30:14 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 20:30:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 20:30:14 INFO - Downloaded 1270 bytes. 20:30:14 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 20:30:14 INFO - Using the following test package requirements: 20:30:14 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 20:30:14 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 20:30:14 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 20:30:14 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 20:30:14 INFO - u'jsshell-linux-x86_64.zip'], 20:30:14 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 20:30:14 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 20:30:14 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 20:30:14 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 20:30:14 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 20:30:14 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 20:30:14 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 20:30:14 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 20:30:14 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 20:30:14 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 20:30:14 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 20:30:14 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 20:30:14 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:14 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/firefox-45.0.en-US.linux-x86_64.common.tests.zip 20:30:14 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/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 20:30:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/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 20:30:15 INFO - Downloaded 21580338 bytes. 20:30:15 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 20:30:15 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 20:30:15 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 20:30:16 INFO - Return code: 0 20:30:16 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 20:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:16 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/firefox-45.0.en-US.linux-x86_64.common.tests.zip 20:30:16 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/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 20:30:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/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 20:30:16 INFO - Downloaded 21580338 bytes. 20:30:16 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 20:30:16 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 20:30:16 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 20:30:17 INFO - Return code: 0 20:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:17 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 20:30:17 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/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 20:30:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/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 20:30:20 INFO - Downloaded 11132038 bytes. 20:30:20 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 20:30:20 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 20:30:20 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 20:30:21 INFO - Return code: 0 20:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:21 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/firefox-45.0.en-US.linux-x86_64.tar.bz2 20:30:21 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/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 20:30:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/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 20:30:22 INFO - Downloaded 57943807 bytes. 20:30:22 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/firefox-45.0.en-US.linux-x86_64.tar.bz2 20:30:22 INFO - mkdir: /builds/slave/test-pgo/properties 20:30:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 20:30:22 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 20:30:22 INFO - Contents: 20:30:22 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/firefox-45.0.en-US.linux-x86_64.tar.bz2 20:30:22 INFO - Running post-action listener: _resource_record_post_action 20:30:22 INFO - Running post-action listener: set_extra_try_arguments 20:30:22 INFO - ##### 20:30:22 INFO - ##### Running populate-webroot step. 20:30:22 INFO - ##### 20:30:22 INFO - Running pre-action listener: _resource_record_pre_action 20:30:22 INFO - Running main action method: populate_webroot 20:30:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:30:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:30:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:30:22 INFO - '--tppagecycles', 20:30:22 INFO - '7'], 20:30:22 INFO - 'tests': ['tsvgm']}}, 20:30:22 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 20:30:22 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 20:30:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:30:22 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 20:30:22 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 20:30:22 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 20:30:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:30:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:30:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:30:22 INFO - 'talos_options': ['--e10s'], 20:30:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:30:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:30:22 INFO - 'tests': ['damp', 'tps']}, 20:30:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:30:22 INFO - 'talos_options': ['--e10s'], 20:30:22 INFO - 'tests': ['damp', 'tps']}, 20:30:22 INFO - 'g3': {'tests': ['dromaeo_dom']}, 20:30:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:30:22 INFO - 'other': {'tests': ['a11yr', 20:30:22 INFO - 'ts_paint', 20:30:22 INFO - 'tpaint', 20:30:22 INFO - 'sessionrestore', 20:30:22 INFO - 'sessionrestore_no_auto_restore']}, 20:30:22 INFO - 'other-e10s': {'talos_options': ['--e10s'], 20:30:22 INFO - 'tests': ['a11yr', 20:30:22 INFO - 'ts_paint', 20:30:22 INFO - 'tpaint', 20:30:22 INFO - 'sessionrestore', 20:30:22 INFO - 'sessionrestore_no_auto_restore']}, 20:30:22 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 20:30:22 INFO - 'tests': ['a11yr', 20:30:22 INFO - 'ts_paint', 20:30:22 INFO - 'tpaint', 20:30:22 INFO - 'sessionrestore', 20:30:22 INFO - 'sessionrestore_no_auto_restore']}, 20:30:22 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 20:30:22 INFO - 'tests': ['a11yr', 20:30:22 INFO - 'ts_paint', 20:30:22 INFO - 'tpaint', 20:30:22 INFO - 'sessionrestore', 20:30:22 INFO - 'sessionrestore_no_auto_restore']}, 20:30:22 INFO - 'other_l64': {'tests': ['a11yr', 20:30:22 INFO - 'ts_paint', 20:30:22 INFO - 'tpaint', 20:30:22 INFO - 'sessionrestore', 20:30:22 INFO - 'sessionrestore_no_auto_restore']}, 20:30:22 INFO - 'other_nol64': {'tests': ['a11yr', 20:30:22 INFO - 'ts_paint', 20:30:22 INFO - 'tpaint', 20:30:22 INFO - 'sessionrestore', 20:30:22 INFO - 'sessionrestore_no_auto_restore']}, 20:30:22 INFO - 'svgr': {'tests': ['tsvgx', 20:30:22 INFO - 'tsvgr_opacity', 20:30:22 INFO - 'tart', 20:30:22 INFO - 'tscrollx', 20:30:22 INFO - 'cart']}, 20:30:22 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 20:30:22 INFO - 'tests': ['tsvgx', 20:30:22 INFO - 'tsvgr_opacity', 20:30:22 INFO - 'tart', 20:30:22 INFO - 'tscrollx', 20:30:22 INFO - 'cart']}, 20:30:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:30:22 INFO - 'tests': ['tp5o']}, 20:30:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:30:22 INFO - 'talos_options': ['--e10s'], 20:30:22 INFO - 'tests': ['tp5o']}, 20:30:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:30:22 INFO - 'talos_options': ['--xperf_path', 20:30:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:30:22 INFO - 'tests': ['tp5n']}, 20:30:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:30:22 INFO - 'talos_options': ['--e10s', 20:30:22 INFO - '--xperf_path', 20:30:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:30:22 INFO - 'tests': ['tp5n']}}, 20:30:22 INFO - 'talos.zip': {'path': '', 20:30:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:30:22 INFO - Running post-action listener: _resource_record_post_action 20:30:22 INFO - ##### 20:30:22 INFO - ##### Running create-virtualenv step. 20:30:22 INFO - ##### 20:30:22 INFO - Running pre-action listener: _resource_record_pre_action 20:30:22 INFO - Running main action method: create_virtualenv 20:30:22 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 20:30:22 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 20:30:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 20:30:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:30:22 INFO - Using real prefix '/usr' 20:30:22 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 20:30:23 INFO - Installing distribute.............................................................................................................................................................................................done. 20:30:25 INFO - Installing pip.................done. 20:30:25 INFO - Return code: 0 20:30:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 20:30: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')]} 20:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30: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')]} 20:30:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:25 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 0x7f8b1bd04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d030e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d91e30>, '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 0x1d92d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d931c0>, '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': '82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261', '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 20:30:25 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 20:30:25 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 20:30:25 INFO - Using env: {'DISPLAY': ':0', 20:30:25 INFO - 'HOME': '/home/cltbld', 20:30:25 INFO - 'LANG': 'en_US.UTF-8', 20:30:25 INFO - 'LANGUAGE': 'en_US:en', 20:30:25 INFO - 'LOGNAME': 'cltbld', 20:30:25 INFO - 'MAIL': '/var/mail/cltbld', 20:30:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:25 INFO - 'MOZ_NO_REMOTE': '1', 20:30:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:30:25 INFO - 'NO_EM_RESTART': '1', 20:30:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:30:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:30:25 INFO - 'PWD': '/builds/slave/test-pgo', 20:30:25 INFO - 'SHELL': '/bin/bash', 20:30:25 INFO - 'SHLVL': '1', 20:30:25 INFO - 'TERM': 'linux', 20:30:25 INFO - 'TMOUT': '86400', 20:30:25 INFO - 'USER': 'cltbld', 20:30:25 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261', 20:30:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:25 INFO - '_': '/tools/buildbot/bin/python'} 20:30:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:25 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 20:30:25 INFO - Cleaning up... 20:30:25 INFO - Return code: 0 20:30:25 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 20:30: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')]} 20:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30: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')]} 20:30:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:25 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 0x7f8b1bd04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d030e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d91e30>, '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 0x1d92d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d931c0>, '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': '82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261', '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 20:30:25 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 20:30:25 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 20:30:25 INFO - Using env: {'DISPLAY': ':0', 20:30:25 INFO - 'HOME': '/home/cltbld', 20:30:25 INFO - 'LANG': 'en_US.UTF-8', 20:30:25 INFO - 'LANGUAGE': 'en_US:en', 20:30:25 INFO - 'LOGNAME': 'cltbld', 20:30:25 INFO - 'MAIL': '/var/mail/cltbld', 20:30:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:25 INFO - 'MOZ_NO_REMOTE': '1', 20:30:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:30:25 INFO - 'NO_EM_RESTART': '1', 20:30:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:30:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:30:25 INFO - 'PWD': '/builds/slave/test-pgo', 20:30:25 INFO - 'SHELL': '/bin/bash', 20:30:25 INFO - 'SHLVL': '1', 20:30:25 INFO - 'TERM': 'linux', 20:30:25 INFO - 'TMOUT': '86400', 20:30:25 INFO - 'USER': 'cltbld', 20:30:25 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261', 20:30:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:25 INFO - '_': '/tools/buildbot/bin/python'} 20:30:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:25 INFO - Downloading/unpacking psutil>=0.7.1 20:30:25 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 20:30:25 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 20:30:25 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 20:30:25 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 20:30:25 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 20:30:25 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 20:30:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 20:30:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:30:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:30:27 INFO - Installing collected packages: psutil 20:30:27 INFO - Running setup.py install for psutil 20:30:27 INFO - building 'psutil._psutil_linux' extension 20:30:27 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 20:30:27 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 20:30:27 INFO - building 'psutil._psutil_posix' extension 20:30:27 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 20:30:27 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 20:30:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:30:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:30:28 INFO - Successfully installed psutil 20:30:28 INFO - Cleaning up... 20:30:28 INFO - Return code: 0 20:30:28 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 20:30: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')]} 20:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30: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')]} 20:30:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30: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', '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 0x7f8b1bd04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d030e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d91e30>, '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 0x1d92d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d931c0>, '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': '82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261', '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 20:30: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 20:30: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 mozsystemmonitor==0.0.0 20:30:28 INFO - Using env: {'DISPLAY': ':0', 20:30:28 INFO - 'HOME': '/home/cltbld', 20:30:28 INFO - 'LANG': 'en_US.UTF-8', 20:30:28 INFO - 'LANGUAGE': 'en_US:en', 20:30:28 INFO - 'LOGNAME': 'cltbld', 20:30:28 INFO - 'MAIL': '/var/mail/cltbld', 20:30:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:28 INFO - 'MOZ_NO_REMOTE': '1', 20:30:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:30:28 INFO - 'NO_EM_RESTART': '1', 20:30:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:30:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:30:28 INFO - 'PWD': '/builds/slave/test-pgo', 20:30:28 INFO - 'SHELL': '/bin/bash', 20:30:28 INFO - 'SHLVL': '1', 20:30:28 INFO - 'TERM': 'linux', 20:30:28 INFO - 'TMOUT': '86400', 20:30:28 INFO - 'USER': 'cltbld', 20:30:28 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261', 20:30:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:28 INFO - '_': '/tools/buildbot/bin/python'} 20:30:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:28 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 20:30: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 20:30: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 20:30: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 20:30: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 20:30: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 20:30: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 20:30:30 INFO - Downloading mozsystemmonitor-0.0.tar.gz 20:30:30 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:30:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 20:30:30 INFO - Installing collected packages: mozsystemmonitor 20:30:30 INFO - Running setup.py install for mozsystemmonitor 20:30:30 INFO - Successfully installed mozsystemmonitor 20:30:30 INFO - Cleaning up... 20:30:30 INFO - Return code: 0 20:30:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 20:30:30 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')]} 20:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:30 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')]} 20:30:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:30 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 0x7f8b1bd04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d030e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d91e30>, '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 0x1d92d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d931c0>, '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': '82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261', '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 20:30:30 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 20:30:30 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 20:30:30 INFO - Using env: {'DISPLAY': ':0', 20:30:30 INFO - 'HOME': '/home/cltbld', 20:30:30 INFO - 'LANG': 'en_US.UTF-8', 20:30:30 INFO - 'LANGUAGE': 'en_US:en', 20:30:30 INFO - 'LOGNAME': 'cltbld', 20:30:30 INFO - 'MAIL': '/var/mail/cltbld', 20:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:30 INFO - 'MOZ_NO_REMOTE': '1', 20:30:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:30:30 INFO - 'NO_EM_RESTART': '1', 20:30:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:30:30 INFO - 'PWD': '/builds/slave/test-pgo', 20:30:30 INFO - 'SHELL': '/bin/bash', 20:30:30 INFO - 'SHLVL': '1', 20:30:30 INFO - 'TERM': 'linux', 20:30:30 INFO - 'TMOUT': '86400', 20:30:30 INFO - 'USER': 'cltbld', 20:30:30 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261', 20:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:30 INFO - '_': '/tools/buildbot/bin/python'} 20:30:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:30 INFO - Downloading/unpacking blobuploader==1.2.4 20:30: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 20:30: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 20:30: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 20:30: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 20:30: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 20:30: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 20:30:32 INFO - Downloading blobuploader-1.2.4.tar.gz 20:30:32 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:30:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:30:32 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 20:30:32 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 20:30:32 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 20:30:32 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 20:30:32 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 20:30:32 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 20:30:32 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 20:30:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:30:32 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 20:30:32 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 20:30:32 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 20:30:32 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 20:30:32 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 20:30:32 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 20:30:32 INFO - Downloading docopt-0.6.1.tar.gz 20:30:32 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 20:30:33 INFO - Installing collected packages: blobuploader, requests, docopt 20:30:33 INFO - Running setup.py install for blobuploader 20:30:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:30:33 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 20:30:33 INFO - Running setup.py install for requests 20:30:33 INFO - Running setup.py install for docopt 20:30:33 INFO - Successfully installed blobuploader requests docopt 20:30:33 INFO - Cleaning up... 20:30:33 INFO - Return code: 0 20:30:33 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 20:30:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:33 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 0x7f8b1bd04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d030e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d91e30>, '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 0x1d92d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d931c0>, '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': '82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261', '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 20:30:33 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 20:30:33 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 20:30:33 INFO - Using env: {'DISPLAY': ':0', 20:30:33 INFO - 'HOME': '/home/cltbld', 20:30:33 INFO - 'LANG': 'en_US.UTF-8', 20:30:33 INFO - 'LANGUAGE': 'en_US:en', 20:30:33 INFO - 'LOGNAME': 'cltbld', 20:30:33 INFO - 'MAIL': '/var/mail/cltbld', 20:30:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:33 INFO - 'MOZ_NO_REMOTE': '1', 20:30:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:30:33 INFO - 'NO_EM_RESTART': '1', 20:30:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:30:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:30:33 INFO - 'PWD': '/builds/slave/test-pgo', 20:30:33 INFO - 'SHELL': '/bin/bash', 20:30:33 INFO - 'SHLVL': '1', 20:30:33 INFO - 'TERM': 'linux', 20:30:33 INFO - 'TMOUT': '86400', 20:30:33 INFO - 'USER': 'cltbld', 20:30:33 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261', 20:30:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:33 INFO - '_': '/tools/buildbot/bin/python'} 20:30:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 20:30:33 INFO - Running setup.py (path:/tmp/pip-9TsMgz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 20:30:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 20:30:33 INFO - Running setup.py (path:/tmp/pip-Pkei6J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 20:30:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 20:30:33 INFO - Running setup.py (path:/tmp/pip-d29YXS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 20:30:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 20:30:34 INFO - Running setup.py (path:/tmp/pip-V7r_eh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 20:30:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 20:30:34 INFO - Running setup.py (path:/tmp/pip-RNoHct-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 20:30:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 20:30:34 INFO - Running setup.py (path:/tmp/pip-XihBV1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 20:30:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 20:30:34 INFO - Running setup.py (path:/tmp/pip-DeyVeE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 20:30:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 20:30:34 INFO - Running setup.py (path:/tmp/pip-pubJrb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 20:30:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 20:30:34 INFO - Running setup.py (path:/tmp/pip-HT1opQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 20:30:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 20:30:34 INFO - Running setup.py (path:/tmp/pip-JbWbtF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 20:30:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 20:30:34 INFO - Running setup.py (path:/tmp/pip-YM437T-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 20:30:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 20:30:34 INFO - Running setup.py (path:/tmp/pip-Awup5c-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 20:30:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 20:30:34 INFO - Running setup.py (path:/tmp/pip-fV1N50-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 20:30:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 20:30:34 INFO - Running setup.py (path:/tmp/pip-cpf5V5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 20:30:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 20:30:34 INFO - Running setup.py (path:/tmp/pip-3Racb7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 20:30:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 20:30:34 INFO - Running setup.py (path:/tmp/pip-0Bgjra-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 20:30:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 20:30:34 INFO - Running setup.py (path:/tmp/pip-cwdfNM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 20:30:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:30:35 INFO - Running setup.py install for manifestparser 20:30:35 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 20:30:35 INFO - Running setup.py install for mozcrash 20:30:35 INFO - Running setup.py install for mozdebug 20:30:35 INFO - Running setup.py install for mozdevice 20:30:35 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 20:30:35 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 20:30:35 INFO - Running setup.py install for mozfile 20:30:35 INFO - Running setup.py install for mozhttpd 20:30:35 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 20:30:35 INFO - Running setup.py install for mozinfo 20:30:35 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 20:30:35 INFO - Running setup.py install for mozInstall 20:30:35 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 20:30:35 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 20:30:35 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 20:30:35 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 20:30:35 INFO - Running setup.py install for mozleak 20:30:35 INFO - Running setup.py install for mozlog 20:30:36 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 20:30:36 INFO - Running setup.py install for moznetwork 20:30:36 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 20:30:36 INFO - Running setup.py install for mozprocess 20:30:36 INFO - Running setup.py install for mozprofile 20:30:36 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 20:30:36 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 20:30:36 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 20:30:36 INFO - Running setup.py install for mozrunner 20:30:36 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 20:30:36 INFO - Running setup.py install for mozscreenshot 20:30:36 INFO - Running setup.py install for moztest 20:30:36 INFO - Running setup.py install for mozversion 20:30:36 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 20:30:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:30:36 INFO - Cleaning up... 20:30:36 INFO - Return code: 0 20:30:36 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 20:30:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:36 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 0x7f8b1bd04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d030e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d91e30>, '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 0x1d92d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d931c0>, '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': '82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261', '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 20:30:36 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 20:30:36 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 20:30:36 INFO - Using env: {'DISPLAY': ':0', 20:30:36 INFO - 'HOME': '/home/cltbld', 20:30:36 INFO - 'LANG': 'en_US.UTF-8', 20:30:36 INFO - 'LANGUAGE': 'en_US:en', 20:30:36 INFO - 'LOGNAME': 'cltbld', 20:30:36 INFO - 'MAIL': '/var/mail/cltbld', 20:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:36 INFO - 'MOZ_NO_REMOTE': '1', 20:30:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:30:36 INFO - 'NO_EM_RESTART': '1', 20:30:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:30:36 INFO - 'PWD': '/builds/slave/test-pgo', 20:30:36 INFO - 'SHELL': '/bin/bash', 20:30:36 INFO - 'SHLVL': '1', 20:30:36 INFO - 'TERM': 'linux', 20:30:36 INFO - 'TMOUT': '86400', 20:30:36 INFO - 'USER': 'cltbld', 20:30:36 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261', 20:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:36 INFO - '_': '/tools/buildbot/bin/python'} 20:30:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 20:30:37 INFO - Running setup.py (path:/tmp/pip-vsRrhw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 20:30:37 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)) 20:30:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 20:30:37 INFO - Running setup.py (path:/tmp/pip-stbKyG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 20:30:37 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)) 20:30:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 20:30:37 INFO - Running setup.py (path:/tmp/pip-gCefWN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 20:30:37 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)) 20:30:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 20:30:37 INFO - Running setup.py (path:/tmp/pip-uYEljU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 20:30:37 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)) 20:30:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 20:30:37 INFO - Running setup.py (path:/tmp/pip-3or7Mc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 20:30:37 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)) 20:30:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 20:30:37 INFO - Running setup.py (path:/tmp/pip-oqi1T9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 20:30:37 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)) 20:30:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 20:30:37 INFO - Running setup.py (path:/tmp/pip-KpEs3K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 20:30:37 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)) 20:30:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 20:30:37 INFO - Running setup.py (path:/tmp/pip-uG_rgB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 20:30:37 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)) 20:30:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 20:30:37 INFO - Running setup.py (path:/tmp/pip-_Ltt7b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 20:30:37 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)) 20:30:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 20:30:37 INFO - Running setup.py (path:/tmp/pip-kqME9l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 20:30:37 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)) 20:30:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 20:30:37 INFO - Running setup.py (path:/tmp/pip-slH3rX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 20:30:37 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)) 20:30:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 20:30:37 INFO - Running setup.py (path:/tmp/pip-XY2Gjf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 20:30:37 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)) 20:30:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 20:30:37 INFO - Running setup.py (path:/tmp/pip-QnhEAR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 20:30:37 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)) 20:30:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 20:30:37 INFO - Running setup.py (path:/tmp/pip-XOC0zV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 20:30:38 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)) 20:30:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 20:30:38 INFO - Running setup.py (path:/tmp/pip-KO7IJX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 20:30:38 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)) 20:30:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 20:30:38 INFO - Running setup.py (path:/tmp/pip-Uh5tVP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 20:30:38 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)) 20:30:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 20:30:38 INFO - Running setup.py (path:/tmp/pip-85uU90-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 20:30:38 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)) 20:30:38 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)) 20:30:38 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)) 20:30:38 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)) 20:30:38 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)) 20:30:38 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)) 20:30:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 20:30:38 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 20:30:38 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 20:30:38 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 20:30:38 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 20:30:38 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 20:30:38 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 20:30:39 INFO - Downloading blessings-1.6.tar.gz 20:30:39 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 20:30:40 INFO - Installing collected packages: blessings 20:30:40 INFO - Running setup.py install for blessings 20:30:40 INFO - Successfully installed blessings 20:30:40 INFO - Cleaning up... 20:30:40 INFO - Return code: 0 20:30:40 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 20:30:40 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 20:30:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 20:30:40 INFO - Reading from file tmpfile_stdout 20:30:40 INFO - Current package versions: 20:30:40 INFO - argparse == 1.2.1 20:30:40 INFO - blessings == 1.6 20:30:40 INFO - blobuploader == 1.2.4 20:30:40 INFO - docopt == 0.6.1 20:30:40 INFO - manifestparser == 1.1 20:30:40 INFO - mozInstall == 1.12 20:30:40 INFO - mozcrash == 0.16 20:30:40 INFO - mozdebug == 0.1 20:30:40 INFO - mozdevice == 0.47 20:30:40 INFO - mozfile == 1.2 20:30:40 INFO - mozhttpd == 0.7 20:30:40 INFO - mozinfo == 0.9 20:30:40 INFO - mozleak == 0.1 20:30:40 INFO - mozlog == 3.1 20:30:40 INFO - moznetwork == 0.27 20:30:40 INFO - mozprocess == 0.22 20:30:40 INFO - mozprofile == 0.27 20:30:40 INFO - mozrunner == 6.11 20:30:40 INFO - mozscreenshot == 0.1 20:30:40 INFO - mozsystemmonitor == 0.0 20:30:40 INFO - moztest == 0.7 20:30:40 INFO - mozversion == 1.4 20:30:40 INFO - psutil == 3.1.1 20:30:40 INFO - requests == 1.2.3 20:30:40 INFO - wsgiref == 0.1.2 20:30:40 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 20:30:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:40 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 0x7f8b1bd04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d030e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d91e30>, '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 0x1d92d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d931c0>, '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': '82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261', '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 20:30:40 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 20:30:40 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 20:30:40 INFO - Using env: {'DISPLAY': ':0', 20:30:40 INFO - 'HOME': '/home/cltbld', 20:30:40 INFO - 'LANG': 'en_US.UTF-8', 20:30:40 INFO - 'LANGUAGE': 'en_US:en', 20:30:40 INFO - 'LOGNAME': 'cltbld', 20:30:40 INFO - 'MAIL': '/var/mail/cltbld', 20:30:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:40 INFO - 'MOZ_NO_REMOTE': '1', 20:30:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:30:40 INFO - 'NO_EM_RESTART': '1', 20:30:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:30:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:30:40 INFO - 'PWD': '/builds/slave/test-pgo', 20:30:40 INFO - 'SHELL': '/bin/bash', 20:30:40 INFO - 'SHLVL': '1', 20:30:40 INFO - 'TERM': 'linux', 20:30:40 INFO - 'TMOUT': '86400', 20:30:40 INFO - 'USER': 'cltbld', 20:30:40 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261', 20:30:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:40 INFO - '_': '/tools/buildbot/bin/python'} 20:30:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:40 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)) 20:30:40 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)) 20:30:40 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)) 20:30:40 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)) 20:30:40 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)) 20:30:40 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)) 20:30:40 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)) 20:30:40 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)) 20:30:40 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)) 20:30:40 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)) 20:30:40 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)) 20:30:40 INFO - Cleaning up... 20:30:40 INFO - Return code: 0 20:30:40 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 20:30:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:40 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 0x7f8b1bd04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d030e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d91e30>, '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 0x1d92d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d931c0>, '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': '82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261', '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 20:30:40 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 20:30:40 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 20:30:40 INFO - Using env: {'DISPLAY': ':0', 20:30:40 INFO - 'HOME': '/home/cltbld', 20:30:40 INFO - 'LANG': 'en_US.UTF-8', 20:30:40 INFO - 'LANGUAGE': 'en_US:en', 20:30:40 INFO - 'LOGNAME': 'cltbld', 20:30:40 INFO - 'MAIL': '/var/mail/cltbld', 20:30:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:40 INFO - 'MOZ_NO_REMOTE': '1', 20:30:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:30:40 INFO - 'NO_EM_RESTART': '1', 20:30:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:30:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:30:40 INFO - 'PWD': '/builds/slave/test-pgo', 20:30:40 INFO - 'SHELL': '/bin/bash', 20:30:40 INFO - 'SHLVL': '1', 20:30:40 INFO - 'TERM': 'linux', 20:30:40 INFO - 'TMOUT': '86400', 20:30:40 INFO - 'USER': 'cltbld', 20:30:40 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261', 20:30:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:40 INFO - '_': '/tools/buildbot/bin/python'} 20:30:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:40 INFO - Downloading/unpacking jsonschema 20:30:40 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 20:30:40 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 20:30:40 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 20:30:40 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 20:30:40 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 20:30:40 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 20:30:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:30:42 INFO - Downloading/unpacking functools32 (from jsonschema) 20:30:42 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 20:30:42 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 20:30:42 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 20:30:42 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 20:30:42 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 20:30:42 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 20:30:42 INFO - Downloading functools32-3.2.3-2.tar.gz 20:30:42 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 20:30:42 INFO - warning: no files found matching '*.txt' 20:30:42 INFO - no previously-included directories found matching 'build' 20:30:42 INFO - no previously-included directories found matching 'dist' 20:30:42 INFO - no previously-included directories found matching '.git*' 20:30:42 INFO - Installing collected packages: jsonschema, functools32 20:30:42 INFO - Running setup.py install for functools32 20:30:43 INFO - warning: no files found matching '*.txt' 20:30:43 INFO - no previously-included directories found matching 'build' 20:30:43 INFO - no previously-included directories found matching 'dist' 20:30:43 INFO - no previously-included directories found matching '.git*' 20:30:43 INFO - Successfully installed jsonschema functools32 20:30:43 INFO - Cleaning up... 20:30:43 INFO - Return code: 0 20:30:43 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 20:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:43 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 0x7f8b1bd04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d030e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d91e30>, '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 0x1d92d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d931c0>, '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': '82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261', '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 20:30:43 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 20:30:43 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 20:30:43 INFO - Using env: {'DISPLAY': ':0', 20:30:43 INFO - 'HOME': '/home/cltbld', 20:30:43 INFO - 'LANG': 'en_US.UTF-8', 20:30:43 INFO - 'LANGUAGE': 'en_US:en', 20:30:43 INFO - 'LOGNAME': 'cltbld', 20:30:43 INFO - 'MAIL': '/var/mail/cltbld', 20:30:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:43 INFO - 'MOZ_NO_REMOTE': '1', 20:30:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:30:43 INFO - 'NO_EM_RESTART': '1', 20:30:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:30:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:30:43 INFO - 'PWD': '/builds/slave/test-pgo', 20:30:43 INFO - 'SHELL': '/bin/bash', 20:30:43 INFO - 'SHLVL': '1', 20:30:43 INFO - 'TERM': 'linux', 20:30:43 INFO - 'TMOUT': '86400', 20:30:43 INFO - 'USER': 'cltbld', 20:30:43 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261', 20:30:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:43 INFO - '_': '/tools/buildbot/bin/python'} 20:30:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:43 INFO - Downloading/unpacking flake8 20:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:45 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:30:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:45 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:30:45 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:45 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 20:30:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:30:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:30:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:30:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:30:45 INFO - no previously-included directories found matching 'docs/_build' 20:30:45 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:30:45 INFO - Running setup.py install for pep8 20:30:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:30:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:30:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:30:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:30:45 INFO - no previously-included directories found matching 'docs/_build' 20:30:45 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 20:30:45 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:30:45 INFO - Cleaning up... 20:30:46 INFO - Return code: 0 20:30:46 INFO - Running post-action listener: _resource_record_post_action 20:30:46 INFO - Running post-action listener: _start_resource_monitoring 20:30:46 INFO - Starting resource monitoring. 20:30:46 INFO - ##### 20:30:46 INFO - ##### Running install step. 20:30:46 INFO - ##### 20:30:46 INFO - Running pre-action listener: _resource_record_pre_action 20:30:46 INFO - Running main action method: install 20:30:46 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 20:30:46 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 20:30:46 INFO - Reading from file tmpfile_stdout 20:30:46 INFO - Detecting whether we're running mozinstall >=1.0... 20:30:46 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 20:30:46 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 20:30:46 INFO - Reading from file tmpfile_stdout 20:30:46 INFO - Output received: 20:30:46 INFO - Usage: mozinstall [options] installer 20:30:46 INFO - Options: 20:30:46 INFO - -h, --help show this help message and exit 20:30:46 INFO - -d DEST, --destination=DEST 20:30:46 INFO - Directory to install application into. [default: 20:30:46 INFO - "/builds/slave/test-pgo"] 20:30:46 INFO - --app=APP Application being installed. [default: firefox] 20:30:46 INFO - mkdir: /builds/slave/test-pgo/build/application 20:30:46 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'] 20:30:46 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 20:31:00 INFO - Reading from file tmpfile_stdout 20:31:00 INFO - Output received: 20:31:00 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 20:31:00 INFO - Running post-action listener: _resource_record_post_action 20:31:00 INFO - ##### 20:31:00 INFO - ##### Running run-tests step. 20:31:00 INFO - ##### 20:31:00 INFO - Running pre-action listener: _resource_record_pre_action 20:31:00 INFO - Running main action method: run_tests 20:31:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 20:31:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 20:31:00 INFO - Python 2.7.3 20:31:00 INFO - Return code: 0 20:31:00 INFO - grabbing minidump binary from tooltool 20:31:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:31:00 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 0x1d91e30>, '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 0x1d92d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d931c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 20:31:00 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 20:31:00 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 20:31:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:31:00 INFO - Return code: 0 20:31:00 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 20:31:00 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 20:31:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 20:31:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 20:31:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 20:31:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 20:31:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 20:31:02 INFO - Return code: 0 20:31:02 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/1453860259/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 20:31:02 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/1453860259/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-055 --webServer localhost 20:31:02 INFO - Using env: {'DISPLAY': ':0', 20:31:02 INFO - 'HOME': '/home/cltbld', 20:31:02 INFO - 'LANG': 'en_US.UTF-8', 20:31:02 INFO - 'LANGUAGE': 'en_US:en', 20:31:02 INFO - 'LOGNAME': 'cltbld', 20:31:02 INFO - 'MAIL': '/var/mail/cltbld', 20:31:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 20:31:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 20:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:31:02 INFO - 'MOZ_NO_REMOTE': '1', 20:31:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 20:31:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:31:02 INFO - 'NO_EM_RESTART': '1', 20:31:02 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:31:02 INFO - 'PWD': '/builds/slave/test-pgo', 20:31:02 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 20:31:02 INFO - 'SHELL': '/bin/bash', 20:31:02 INFO - 'SHLVL': '1', 20:31:02 INFO - 'TERM': 'linux', 20:31:02 INFO - 'TMOUT': '86400', 20:31:02 INFO - 'USER': 'cltbld', 20:31:02 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261', 20:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:31:02 INFO - '_': '/tools/buildbot/bin/python'} 20:31:02 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/1453860259/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--webServer', 'localhost'] with output_timeout 3600 20:31:02 INFO - mozversion INFO | application_buildid: 20160126180419 20:31:02 INFO - mozversion INFO | application_changeset: cb40dba596a540eb95963be956504c5037cbc95f 20:31:02 INFO - mozversion INFO | application_display_name: Nightly 20:31:02 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:31:02 INFO - mozversion INFO | application_name: Firefox 20:31:02 INFO - mozversion INFO | application_remotingname: firefox 20:31:02 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 20:31:02 INFO - mozversion INFO | application_vendor: Mozilla 20:31:02 INFO - mozversion INFO | application_version: 45.0 20:31:02 INFO - mozversion INFO | platform_buildid: 20160126180419 20:31:02 INFO - mozversion INFO | platform_changeset: cb40dba596a540eb95963be956504c5037cbc95f 20:31:02 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 20:31:02 INFO - mozversion INFO | platform_version: 45.0 20:31:02 INFO - 2016-01-26 20:31:02,204 DEBUG : using testdate: 1453869062 20:31:02 INFO - 2016-01-26 20:31:02,204 DEBUG : actual date: 1453869062 20:31:02 INFO - 2016-01-26 20:31:02,213 INFO : starting webserver on 'localhost:35816' 20:31:02 INFO - 2016-01-26 20:31:02,214 INFO : Starting test suite talos-linux64-ix-055 20:31:02 INFO - 2016-01-26 20:31:02,214 INFO : Starting test dromaeo_css 20:31:02 INFO - 2016-01-26 20:31:02,214 DEBUG : operating with platform_type : linux_ 20:31:02 INFO - 2016-01-26 20:31:02,215 INFO : Initialising browser for dromaeo_css test... 20:31:02 INFO - 2016-01-26 20:31:02,228 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp0ldWfs/profile http://localhost:35816/getInfo.html 20:31:05 INFO - 2016-01-26 20:31:05,924 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 20:31:05 INFO - 2016-01-26 20:31:05,924 DEBUG : BROWSER_OUTPUT: colorDepth:24 20:31:05 INFO - 2016-01-26 20:31:05,924 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 20:31:05 INFO - 2016-01-26 20:31:05,924 DEBUG : BROWSER_OUTPUT: __metrics 20:31:06 INFO - 2016-01-26 20:31:06,049 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] 20:31:06 INFO - 2016-01-26 20:31:06,697 INFO : Browser initialized. 20:31:06 INFO - 2016-01-26 20:31:06,697 INFO : Running cycle 1/1 for dromaeo_css test... 20:31:06 INFO - 2016-01-26 20:31:06,698 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp0ldWfs/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 20:31:18 INFO - 2016-01-26 20:31:18,456 DEBUG : BROWSER_OUTPUT: RSS: Main: 134373376 20:31:18 INFO - 2016-01-26 20:31:18,456 DEBUG : BROWSER_OUTPUT: 20:32:09 INFO - 2016-01-26 20:32:09,336 DEBUG : BROWSER_OUTPUT: 1453869129334 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] 20:34:17 INFO - 2016-01-26 20:34:17,713 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35816/tests/dromaeo/cssquery-ext.html) 20:34:18 INFO - 2016-01-26 20:34:18,786 DEBUG : BROWSER_OUTPUT: RSS: Main: 215961600 20:34:18 INFO - 2016-01-26 20:34:18,786 DEBUG : BROWSER_OUTPUT: 20:37:20 INFO - 2016-01-26 20:37:20,992 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/dromaeo/cssquery-ext.html (next: http://localhost:35816/tests/dromaeo/cssquery-jquery.html) 20:37:21 INFO - 2016-01-26 20:37:21,559 DEBUG : BROWSER_OUTPUT: RSS: Main: 223440896 20:37:21 INFO - 2016-01-26 20:37:21,559 DEBUG : BROWSER_OUTPUT: 20:40:23 INFO - 2016-01-26 20:40:23,341 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35816/tests/dromaeo/cssquery-mootools.html) 20:40:23 INFO - 2016-01-26 20:40:23,902 DEBUG : BROWSER_OUTPUT: RSS: Main: 225579008 20:40:23 INFO - 2016-01-26 20:40:23,903 DEBUG : BROWSER_OUTPUT: 20:43:25 INFO - 2016-01-26 20:43:25,596 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35816/tests/dromaeo/cssquery-prototype.html) 20:43:26 INFO - 2016-01-26 20:43:26,157 DEBUG : BROWSER_OUTPUT: RSS: Main: 203571200 20:43:26 INFO - 2016-01-26 20:43:26,158 DEBUG : BROWSER_OUTPUT: 20:46:28 INFO - 2016-01-26 20:46:28,624 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35816/tests/dromaeo/cssquery-yui.html) 20:46:29 INFO - 2016-01-26 20:46:29,185 DEBUG : BROWSER_OUTPUT: RSS: Main: 203931648 20:46:29 INFO - 2016-01-26 20:46:29,185 DEBUG : BROWSER_OUTPUT: 20:49:31 INFO - 2016-01-26 20:49:31,177 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/dromaeo/cssquery-yui.html (next: http://localhost:35816/tests/dromaeo/cssquery-dojo.html) 20:49:31 INFO - 2016-01-26 20:49:31,424 DEBUG : BROWSER_OUTPUT: RSS: Main: 246046720 20:49:31 INFO - 2016-01-26 20:49:31,424 DEBUG : BROWSER_OUTPUT: 20:49:31 INFO - 2016-01-26 20:49:31,426 DEBUG : BROWSER_OUTPUT: __start_tp_report 20:49:31 INFO - 2016-01-26 20:49:31,426 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 20:49:31 INFO - 2016-01-26 20:49:31,426 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 20:49:31 INFO - 2016-01-26 20:49:31,426 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 20:49:31 INFO - 2016-01-26 20:49:31,427 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3330.883419080332;3396.9320613587715;3434.8969530914064;3440.5355277037606;3463.774854634448;5628.2120503129545;5641.717914104289;5674.31908171019;5678.119891416832;5699.943000569998;5657.61388459828;5686.488216060554;5702.857428564288;5714.599978001542;5730.398308177644;4901.509849015088;5119.436861945183;5177.48225177483;5240.292560504321;5266.841994740144;5127.154019586782;5143.691378517301;5151.716655583941;5151.948480515203;5165.431802501741;5063.696178229299;5139.640225184238;5159.664621799571;5162.974185129069;5186.377634683843;5183.092958732225;5229.712365819878;5231.659942103751;5232.843014709565;5237.6071794615555;5069.087564238435;5116.616253780981;5199.792008319662;5207.140821764404;5253.763580638881;5285.048691235576;5310.867228319285;5391.48780865821;5420.756065977001;5424.620276580641;6104.786332478387;6115.663638499881;6140.0789881517685;6152.077188421727;6152.538559608002;6112.633242005494;6179.443850053517;6186.721597528122;6190.009598464269;6194.163787888622;5537.36320323164;5634.690092044936;5644.153376993443;5714.028615135437;5743.741531631045;6146.5390095743;6211.192544969125;6233.252009758858;6239;6246;5787.565932554991;6074.240719910012;6077.544184186204;6085.57400981927;6125.509959203253;5916.230889984275;6003.039513677791;6004.519638428916;6043.214382130383;6082.817515474454;6075.119107729354;6076.635401875901;6120.693965301717;6121.969390153021;6123.846903827351;5783.595148339576;5961.791337303173;6104.542159338067;6107.144999700045;6109.816705498843;4588.977055114703;4656.11533808582;4657.6506761992305;4662.393888794435;4683.461401938753;4181.540030596632;4189.664826813909;4198.244316023145;4200.558941311179;4225.070484493468;5603.159526071122;5667.121596152603;5727.284089488814;5819.272590926134;5830.737616807254;6055.485283750842;6061.727222275008;6079.392060793885;6083.361247068994;6089.756409743649;5725.914111288334;5762.74067666956;5764.106563482583;5764.337101233328;5769.1923130761725;5112.182050871843;5149.536541711264;5229.4770522948165;5255.159174532057;5278.472152784768;6199.814005579839;6360.128873155407;6377.298497165308;6390.616563006328;6408.423241908251;6200.3489633589625;6232.470240029648;6233.625982440886;6235.625862448268;6236.563440559117;5975.342712301556;6121.755129794758;6150.969245153746;6227.9377206227355;6242.875142497215;6014.977453832947;6040.395960403925;6060.969695151496;6102.877942441038;6109.053096770008;6171.876562468636;6188.783392581238;6200.472959798468;6206.410391012847;6208.875822483616;5761.078227483583;5838.503727183237;5859.384764599826;5924.496417804363;5960.403959604178;5904.232449781501;5981.491573216325;5990;5991.910121348271;5992.250968628921;5688.175214593944;5832.445917637818;5878.2064421302575;5878.559108066997;5903.173555702291;5900.380460051804;5952.404759524013;6053.636781793107;6056.830753590621;6092.664903430176;5885.205497257986;5924.674142922181;5928.4960778334325;5935.9109613356695;5944.346121926498 20:49:31 INFO - 2016-01-26 20:49:31,429 DEBUG : BROWSER_OUTPUT: |1;ext.html;621.9830577006594;625.9937400625995;641.4034947498827;646.166445285582;650.6063831382016;32615;32967.17582060447;33016.66983330166;40100.79949600248;41059.794701026454;43926.560734392646;44332.3350149748;44500;44511.10977780443;44528;8691.869621955675;8908.8663670045;8914.242289405409;8918.509481978488;8924.973628032762;8202.466839655433;8397.118302578234;10672.252942294042;10852.131829453625;10892.292001019909;10637.893621063768;10725.570977160904;10747.62383316584;10748;10749.516271767789;8177.427580069398;10556.366618002945;10632.946835265775;10673.83989240162;10676.252662313642;7767.650455729506;10681.359118452918;10913.563457461692;10929.180311476672;10934.289271197425;42040.579594204275;42138;42327.36508952368;42463.787681061396;42473;3662.688671462929;3753.6997040236715;3764.4353346997896;3817.48463957365;4328.09110086879;32614;33072.17319567006;33240.83379583111;34029.31941361184;34083;3865.130345672201;3938.251732170907;3959.802009899522;3980;4009.7794621295816;290491.9999999979;291442.5427872868;292567;293655;294214;55481.44518554763;56321.15518267229;63226.367736322965;63682.36317636857;65119.67440162864;63018.684906575174;63141.36858631447;63240.102796916166;63572;63648.36351636425;17217.31130754756;17250.223739931735;17306.307747689953;17330.39343622967;17484.213210405564;12671.619851404472;12945.288009159398;13047.282399467931;13145;13305.800412993813;20117.692349997204;20457.38627841167;20477.897610511853;20558.588828223652;20614.896925515277;7700.345470634947;7708.267714567107;7757.534547927143;7764.417668674872;7818.6090695465;1005.4419797012582;1026.5226669598671;1028.4908970059869;1064.5422468338534;1085.8154113800672;29494.852525737235;29606.259843504085;29765.57788068061;29967.06861118668;29975;1131.2590253384046;1140.988590114105;1192.9821052684217;1205.7287110400089;1235.1292338901053;13574.864251357558;15643.452479163174;15705.293261803246;15848.603784905239;16773.24520396585;357089;360418;360992;364192;364816;49026.26460603094;49220.75389623101;54294;54760.452395476335;56509.152362714594;1413.547664747271;1430.470725831449;1432.649000994763;1434.4333988074652;1438.6187660269827;27635.30911727223;27639.170824875288;28399.574006389506;28447.43105137927;28563.571546426403;47803.5219647813;48116;48157.75921120372;48196.7590162047;48220.5177948216;267036;267824;268907;269257;269945;32771.34457310888;32989.34021319512;33107.50338744873;33320.50019249665;34740.47889281713;41490.37764433596;41826.79086604548;41866.37200441935;41968.370474443516;42686;34849.47725784166;34909.65090349064;34910.301793963474;35102.64897351096;35474.29051418906;35150.29699406049;35245.647543525265;35448.29103417969;35671.46492802662;35964.46053309255;36035.63964360323;36112.63887361198;36152.819235903655;36776.816115919246;38680;32987.835060824546;33430.49271304613;34251.486227706104;34544.30911381808;34728.826355868056;25696.96848664025;26097.73902260953;26114.347141321618;26132.8693356532;26406.867965660047 20:49:31 INFO - 2016-01-26 20:49:31,430 DEBUG : BROWSER_OUTPUT: |2;jquery.html;374.3411595591759;380.51294343240664;380.5300453939386;382.4416352125895;384.91916697493525;15062.849371506309;17012.4045658402;17677;18435.262589496422;18481.79868308559;17796;18054.909725451354;18434.631307373846;19221;19226.807731922676;5460.481254280837;5560.471755183251;5570.972145139269;5579.442055794409;5584.134459158817;4261.829526818923;4392.97803510982;4393.868183954486;4396.780160991953;5516.172574113875;5915.378885217055;6092.6953652317425;6109.083637454373;6109.236345456818;6109.694515274241;5866;5991.790287339944;6019.8194054178;6021.187139736124;6028.999999999978;5284.339457567804;5370.301860758115;5377.542908852753;5379.300690910157;5390.083685773388;22083.33749987503;22167.77832221673;22359.217427390126;22414.32757017292;22417.55164896709;2314.3751187179537;2357.104300365867;2380.8106660317894;2391.4141035446255;2459.9016039358403;13679.726405471834;13787.379567919468;14111.576652700436;14215.431382744679;14509.782353264709;1831;1914.5596512802133;1937.5446770009178;1955.9804401956083;1967.4196112146951;59785.7010714948;60664.696676516774;62230.68884655548;64257.357426426075;64302.35697643011;10251.7437064074;10501.632442864464;10648.094911932572;10667.893321066846;10701.625443109453;14064.156150630995;14141.222232777296;14147.151170929777;14150;14165.929170354082;8344.66621335152;8374.748757537283;8385.496870187686;8394.706185283485;8490.830183396421;7741.8838717419285;7766.378689704923;7778.416618753616;7855.8821617675785;7901.288884000468;6313.463355614732;6314.116023756677;6339.207599050119;6383.904241436382;6412.551121421456;1691.8392752688476;1701;1716.8709254243565;1777.2180240694054;1783.3669047488215;926.3284119013796;1009.8333774927202;1010.9898901011043;1076.3272954403499;1079.1744315598573;14846.777298340534;18469.90765046166;18495.352662656744;18555.536111597317;18852.622947541247;1049.1239814754613;1051.64244156987;1081.599808071003;1102.3771569063454;1131.3947038334452;10134.442605656612;10234.33476824004;10365.01532354425;10510.316829406063;10925.90740925901;663486;670677;671508;688667;699894;81171.3808986174;85864;88544;89206.55396723104;89422.55288723514;1109.5229051507768;1192.4753108632174;1194.9283043017447;1196.4257156564724;1198.2870192235605;15937.8406215941;16035;16038.117903514953;16142;16406.343746250495;34093.31813363769;34915;35074.64925350714;36034;36279.818600907885;46720.06559868716;51661.7416912913;52920;53269.467305328;55999.16001260066;9996.800063998533;10267.640632577824;10650.307729997525;10665.573377064831;10685.786284274116;18072;18101.818981810015;18188.636227275645;18643;19059.332923347618;13989.300534973414;14091.859081409464;14409.279536023367;14791.92604036973;15624;15091.396344146111;15502.612434689223;15584.766228506809;15816.446424375552;15889.841101588836;11526.193166478266;14181.290935453393;15398.307076181374;15646;15922.522324330288;9928.950355248178;10336.689899302732;10358;10371.222158338003;10490.423026733602;12236;15560.922195389403;15873.12697801632;16092.195390230207;16192.271347789143 20:49:31 INFO - 2016-01-26 20:49:31,431 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1569.6311366828793;1574.984250157498;1783.3936461603053;1857.823506766857;1884.4817675139338;7494.775156745306;7626.389888808896;7968.083670377909;8194.262516373532;8201.671933122676;7777.533347999124;7883.80290492738;7988.840223195532;7993.760187194379;7995.600219989006;2361.315218586612;2407.157494876788;2408.0127147869375;2408.4821763320883;2409.963850542243;2131.8720876747366;2181.945451363713;2377.72656144543;2442.072012635196;2453.1046168148678;2393.437542177596;2401.4236583220077;2413.070967677448;2416.226807421626;2417.746136655652;2411.855288682676;2433.476802487456;2434.3914021494625;2436.9634455483183;2440.73151953285;2382.9642555361684;2438.536678031186;2440.438699099217;2441;2447.5104979004273;8175.672973081129;8252.21603947624;8255.752327430127;8257.545834979073;8261.17388261175;4847.709137451729;4967.7019378837385;4974.701517908901;4975.751212439363;4979.153543897546;3266.477363621809;3332.150301673082;3401.353742788887;3404.3361544498835;3416.24842534642;1096.8080585897435;1101.091599430473;1145.4959817680278;1174.7474293026955;1184.2006645514243;11691;11711.297322160697;11727;11746.412679365998;11750.764984700429;4617.861464156081;4625.953740462553;4650.976745116321;4692.835750748707;4692.835750748707;9902.80194396108;9903.356281841658;9908.504574771232;9946.104850563484;9956.701298961043;4991.750412479434;5012.598992080625;5019.372578427697;5022.472640372708;5134.947335796152;4439.067795762926;4490.932636010462;4493.59557639814;4494.213512635276;4498.595126438636;3406.5741782277214;4055.371417430244;4058.1072164123843;4072.898177545585;4085.9387109193385;1211.5335595795507;1277.310252463678;1279.3922886628964;1292.3150730113055;1327.3296985022503;1152.7694461107812;1381.6683995841126;1406.0157889477202;1409.8660627240395;1419.666378401075;7669.501482403737;7693.730719424793;7725.072991241087;7787.727429539939;7792.259735325135;1166.7433164703752;1174.4186627619383;1177.5643012085584;1181.1318680769627;1192.7793358228755;5858.589898707135;5947.405259474018;5970.31341395745;5977.790777322773;5987.101934709828;9481.288903332139;9912.207023438108;9912.55393507294;10029.899701003042;10047;9903.60385584583;9937.850932236022;9962.950185249027;9963.850542241873;9967.302288839712;7455.664495097733;7754.302112809987;7755.3407960322875;7756.92243077562;7768;7253.854922901618;7292.744753933587;7313.122425308997;7330.010448589373;7352.338289553966;8799.076097009722;8869.911300886908;8943.955280223558;8986.55067246622;9012.68455604077;8855.070217627057;9055.411398259092;9213.032631573857;9240.676576319796;9296.95351523238;7061.329173728487;7119.1457025157315;7119.893201601876;7120.287971202838;7123.786286411416;7664.3485303747575;7682.961585192038;7715.807104822425;7913.44605877583;7925.326347260317;7329.890051649123;7340.596267205355;7390.334869861738;7428.517146385468;7513.436492262993;7222.711091556279;7281.599512026889;7392.334689877936;7576.583287919218;7613.276738709814;7483.214262502359;7649.143385404535;7685.961570192113;7725.49784264021;7726.18875018115;6770.661466926733;6816.352446517573;6827.21487028998;6844.726210951506;6844.863102738017;6920.9307906920285;6959.547629404073;6971.2331643518155;6972.546784458994;6976.895346569704 20:49:31 INFO - 2016-01-26 20:49:31,432 DEBUG : BROWSER_OUTPUT: |4;prototype.html;366.15783697495755;369.2301551265611;370.108039624505;371.8884334699591;378.08692008798755;6067.362926892679;6073.969630151849;6104.359042300561;6107.175531303278;6149.754009839601;6017.819465416033;6077.78727744529;6086.8478288042825;6110.20567326247;6180.165677633529;4754.38193034905;4758.809647614108;4774.069056533977;4785.28220766886;4811.085893680207;4593.265077587586;4693.577578017977;4725.243960966246;4729.739864307479;4800.135975524404;4536.8865778355475;4627.652926030544;4697.647676424264;4787.904241915159;4791.640626952975;4765.547273009059;4779.1875381185455;4784.976075119602;4786.688865223767;4791.832285869978;4251.341042138473;4253.213155566199;4254.234237837188;4263.062126332202;4266.210751011073;5824.621399609012;5837.474627283523;5855.268091488564;5861.589688721791;5867.589268751189;5501.449855014506;5614.438556144353;5662.716864156817;5666.206731057655;5671.943280567224;6525.086487891699;6545.639989800559;6601.966990165019;6606.273309935903;6608;4518.367428560005;4575.816967321303;4630.536946305376;4765.142274390609;4768.427788665348;6706.731730730765;6759.864802703918;6784.253732089466;6785.321467853225;6795.252522222551;4824.951750482521;4884.096442158212;4887.437944636341;4889.584385327287;4893.608511319086;4866.902661946741;4952.653314267966;4954.405471343462;4957.925631115536;4958.132326842788;5591.636543624732;5597.972010139923;5606.6636001840025;5609.971950140223;5612.691301978349;6602.504812139109;6606.8348291293105;6609.1077704510235;6617.7683781067435;6620.3710647488415;1723.620803423245;1746.6681330547156;1753.6405036967392;1754.271977129477;1763.3916298876868;1203.2539825308365;1204.3677069538562;1204.6386084174887;1216.7019080325376;1237.826704261404;6359.014352775327;6375.10748495211;6379.170707807955;6380.361963803675;6384.3934826191435;6817.454603631698;6827.112475378269;6827.385535301847;6829.385355317946;6833.384995350343;6249.281332646713;6288.088227207029;6375.2987171411105;6379.649119298483;6381.936180638134;3672.8714494992546;3693.926121477555;3694.722895782827;3699.445083237482;3699.7780133192095;6928.272531384233;6936.271691472423;6951.16586009682;6953.826154346182;6955.89566156508;6821.829454263584;6916.3429474199875;6958.47811414146;6958.547694399847;6961.060256865258;6940.444764418834;6947.235804061549;6993.160820701447;6998.195207551095;7002.404795592432;6782.457403407617;6782.796516104525;6790.049393085071;6794.524383293122;6816.182058153054;6567.671616419065;6629.806634805781;6699.76550820719;6789.558678685869;6804.76183333581;6997.370236678723;7071.151461824614;7072.787816365517;7075.115610548682;7084.822879428056;4820;4820.469748327751;4823.2282834746275;4825.4933232011535;4825.662203645851;6682.431993280431;6827.351401616838;6835.97460380927;6837.384635382839;6843.213030501357;6655.301193374626;6673.1992160941;6675.465962722873;6676.599404035774;6682.298358672269;6542.247641521286;6631.900521492278;6657.833554161185;6670.699818508276;6675.866482670416;6589.374009469093;6593.076969224217;6593.670316484253;6595.835104122435;6604.570702904296;6716.597004179765;6728.125343705287;6732.259451460238;6733.966330168318;6734.855074637226;6439.774607888701;6537.5750576212395;6554.410103090745;6569.868602628017;6591.736330546724 20:49:31 INFO - 2016-01-26 20:49:31,434 DEBUG : BROWSER_OUTPUT: |5;yui.html;753.6382536382537;792.7780221537971;925.9860452804177;934.1405906565959;971.689059500959;13830.446782128716;14649.19429431381;15470;15475.30361133749;15527.689446211069;14046.08700434471;14058.367373468192;14118.43526258951;14139.151650900927;14142.080764750284;757.4849102610223;767.4589414462819;898.9011208767031;901.0403919825364;920.7421921861855;917.499962520428;924.1728652855691;930.6696122876383;931.7018554062703;931.8276118917988;785.4344871692401;964.2382517810921;973.6640858903667;973.8052389522088;974.0697633759743;789.917058708836;938.1650331205233;949.7388218240039;953.4946478366477;953.7329547726648;707.5789905006513;708.4544900426642;708.9432845372357;712.4264966701803;712.4798896805361;13002.739945201138;13192.406341714646;13274.601761947157;13296.401661925236;13359.666008349772;12168.51325946961;12802.03984701151;12845.229286242762;13070.607881763563;13144.277064761434;10280.588776449007;10420.374777513298;10483;10533.26267161299;10534.20993425496;3776.3580191367532;3781.810909454516;3816.160444702161;3818;3832.06114501946;48199.5180048202;48618.27072593914;49981;50078.7496062521;50243.74878125586;9439.103285187895;9843.75390615226;9962.850557241647;10041;10215.642452514126;19492.902535487232;19596.21615135407;19604.411867643994;19635.509112272306;19667;7710.267524585155;7762.029746281715;7861.2531809478005;7887.092984306878;8130.3495720342235;3896.7661940283674;3915.530136383652;3995.4606128172904;4020.2562525932794;4042.110735638155;3332.7833690810503;3347.313800670857;3355.177981394574;3365.6802603752603;3372.072680013016;540.0980362794144;552.3427121725215;555.2420945409567;556.3990889838964;563.2115038945509;638.9488840892717;681.9215790184193;710.1052673631261;730.9524880882726;738.9704411823575;8418.663253469793;8790.120987901286;8906.376553641312;9122.635094596275;9201.907980920105;595.559286128262;705.301751266253;719.0948567786086;738.3539403022355;766.5898744171841;4055;4109.732867363612;4114.7119701621195;4181.790910454465;4197.622214000755;61270.38729612736;62500;62532.68733656302;62569.06146407808;62733.05900411498;53766.46233537693;54772.45227547674;55245.72377138169;55297.44702552923;55431.168532472046;49261.507384925695;49507.25739113916;49674.75162624236;50049.74975125101;50312.49687503078;8240.13478584764;8305.379178147963;8365.749027529184;8502.14978502145;8551.35864810154;34593.82703086468;35319.470207947415;35419.46870796889;36294.63705362913;36488;41643.37534937039;45789.31316030329;46236;46300.7684961573;47089.2936605958;10162.237832162766;10363.06732394088;10921.071708904832;10935.781284374427;11166.665000050012;11116.16628752863;11252.156088293574;11449.313041217554;11641.243319184226;11794;10372.066514013775;10395.480225988822;10485.26603137773;10619.522121504706;10872.07587355052;10854.945725271324;11140.610078647209;11388.94305528467;11756.529738810352;12082.39588020613;11214.831777523506;11298.209125361145;11374.43127843621;11757.882421175678;11794.410279485819;10238.897611023795;10544;10725.570977161138;10763.94618026905;10849.349039057683;8889.555522223734;9340.159385655325;9682.177014953808;9838.852417213604;9898.604055837686 20:49:31 INFO - 2016-01-26 20:49:31,434 DEBUG : BROWSER_OUTPUT: __end_tp_report 20:49:31 INFO - 2016-01-26 20:49:31,434 DEBUG : BROWSER_OUTPUT: __start_cc_report 20:49:31 INFO - 2016-01-26 20:49:31,434 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,817 20:49:31 INFO - 2016-01-26 20:49:31,434 DEBUG : BROWSER_OUTPUT: __end_cc_report 20:49:31 INFO - 2016-01-26 20:49:31,434 DEBUG : BROWSER_OUTPUT: __startTimestamp1453870171424__endTimestamp 20:49:31 INFO - 2016-01-26 20:49:31,434 DEBUG : BROWSER_OUTPUT: 20:49:31 INFO - 2016-01-26 20:49:31,434 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 20:49:31 INFO - 2016-01-26 20:49:31,434 DEBUG : BROWSER_OUTPUT: Number of tests: 6 20:49:31 INFO - 2016-01-26 20:49:31,435 DEBUG : BROWSER_OUTPUT: 20:49:31 INFO - 2016-01-26 20:49:31,435 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5651.67 Median:5878.21 stddev:635.65 (10.8%) stddev-sans-first:610.97 20:49:31 INFO - 2016-01-26 20:49:31,436 DEBUG : BROWSER_OUTPUT: Values: 3330.9 3396.9 3434.9 3440.5 3463.8 5628.2 5641.7 5674.3 5678.1 5699.9 5657.6 5686.5 5702.9 5714.6 5730.4 4901.5 5119.4 5177.5 5240.3 5266.8 5127.2 5143.7 5151.7 5151.9 5165.4 5063.7 5139.6 5159.7 5163.0 5186.4 5183.1 5229.7 5231.7 5232.8 5237.6 5069.1 5116.6 5199.8 5207.1 5253.8 5285.0 5310.9 5391.5 5420.8 5424.6 6104.8 6115.7 6140.1 6152.1 6152.5 6112.6 6179.4 6186.7 6190.0 6194.2 5537.4 5634.7 5644.2 5714.0 5743.7 6146.5 6211.2 6233.3 6239.0 6246.0 5787.6 6074.2 6077.5 6085.6 6125.5 5916.2 6003.0 6004.5 6043.2 6082.8 6075.1 6076.6 6120.7 6122.0 6123.8 5783.6 5961.8 6104.5 6107.1 6109.8 4589.0 4656.1 4657.7 4662.4 4683.5 4181.5 4189.7 4198.2 4200.6 4225.1 5603.2 5667.1 5727.3 5819.3 5830.7 6055.5 6061.7 6079.4 6083.4 6089.8 5725.9 5762.7 5764.1 5764.3 5769.2 5112.2 5149.5 5229.5 5255.2 5278.5 6199.8 6360.1 6377.3 6390.6 6408.4 6200.3 6232.5 6233.6 6235.6 6236.6 5975.3 6121.8 6151.0 6227.9 6242.9 6015.0 6040.4 6061.0 6102.9 6109.1 6171.9 6188.8 6200.5 6206.4 6208.9 5761.1 5838.5 5859.4 5924.5 5960.4 5904.2 5981.5 5990.0 5991.9 5992.3 5688.2 5832.4 5878.2 5878.6 5903.2 5900.4 5952.4 6053.6 6056.8 6092.7 5885.2 5924.7 5928.5 5935.9 5944.3 20:49:31 INFO - 2016-01-26 20:49:31,436 DEBUG : BROWSER_OUTPUT: 20:49:31 INFO - 2016-01-26 20:49:31,436 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48335.90 Median:34397.90 stddev:81059.88 (235.7%) stddev-sans-first:81208.10 20:49:31 INFO - 2016-01-26 20:49:31,436 DEBUG : BROWSER_OUTPUT: Values: 622.0 626.0 641.4 646.2 650.6 32615.0 32967.2 33016.7 40100.8 41059.8 43926.6 44332.3 44500.0 44511.1 44528.0 8691.9 8908.9 8914.2 8918.5 8925.0 8202.5 8397.1 10672.3 10852.1 10892.3 10637.9 10725.6 10747.6 10748.0 10749.5 8177.4 10556.4 10632.9 10673.8 10676.3 7767.7 10681.4 10913.6 10929.2 10934.3 42040.6 42138.0 42327.4 42463.8 42473.0 3662.7 3753.7 3764.4 3817.5 4328.1 32614.0 33072.2 33240.8 34029.3 34083.0 3865.1 3938.3 3959.8 3980.0 4009.8 290492.0 291442.5 292567.0 293655.0 294214.0 55481.4 56321.2 63226.4 63682.4 65119.7 63018.7 63141.4 63240.1 63572.0 63648.4 17217.3 17250.2 17306.3 17330.4 17484.2 12671.6 12945.3 13047.3 13145.0 13305.8 20117.7 20457.4 20477.9 20558.6 20614.9 7700.3 7708.3 7757.5 7764.4 7818.6 1005.4 1026.5 1028.5 1064.5 1085.8 29494.9 29606.3 29765.6 29967.1 29975.0 1131.3 1141.0 1193.0 1205.7 1235.1 13574.9 15643.5 15705.3 15848.6 16773.2 357089.0 360418.0 360992.0 364192.0 364816.0 49026.3 49220.8 54294.0 54760.5 56509.2 1413.5 1430.5 1432.6 1434.4 1438.6 27635.3 27639.2 28399.6 28447.4 28563.6 47803.5 48116.0 48157.8 48196.8 48220.5 267036.0 267824.0 268907.0 269257.0 269945.0 32771.3 32989.3 33107.5 33320.5 34740.5 41490.4 41826.8 41866.4 41968.4 42686.0 34849.5 34909.7 34910.3 35102.6 35474.3 35150.3 35245.6 35448.3 35671.5 35964.5 36035.6 36112.6 36152.8 36776.8 38680.0 32987.8 33430.5 34251.5 34544.3 34728.8 25697.0 26097.7 26114.3 26132.9 26406.9 20:49:31 INFO - 2016-01-26 20:49:31,437 DEBUG : BROWSER_OUTPUT: 20:49:31 INFO - 2016-01-26 20:49:31,437 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:33763.87 Median:10486.95 stddev:110800.98 (1056.6%) stddev-sans-first:111083.43 20:49:31 INFO - 2016-01-26 20:49:31,438 DEBUG : BROWSER_OUTPUT: Values: 374.3 380.5 380.5 382.4 384.9 15062.8 17012.4 17677.0 18435.3 18481.8 17796.0 18054.9 18434.6 19221.0 19226.8 5460.5 5560.5 5571.0 5579.4 5584.1 4261.8 4393.0 4393.9 4396.8 5516.2 5915.4 6092.7 6109.1 6109.2 6109.7 5866.0 5991.8 6019.8 6021.2 6029.0 5284.3 5370.3 5377.5 5379.3 5390.1 22083.3 22167.8 22359.2 22414.3 22417.6 2314.4 2357.1 2380.8 2391.4 2459.9 13679.7 13787.4 14111.6 14215.4 14509.8 1831.0 1914.6 1937.5 1956.0 1967.4 59785.7 60664.7 62230.7 64257.4 64302.4 10251.7 10501.6 10648.1 10667.9 10701.6 14064.2 14141.2 14147.2 14150.0 14165.9 8344.7 8374.7 8385.5 8394.7 8490.8 7741.9 7766.4 7778.4 7855.9 7901.3 6313.5 6314.1 6339.2 6383.9 6412.6 1691.8 1701.0 1716.9 1777.2 1783.4 926.3 1009.8 1011.0 1076.3 1079.2 14846.8 18469.9 18495.4 18555.5 18852.6 1049.1 1051.6 1081.6 1102.4 1131.4 10134.4 10234.3 10365.0 10510.3 10925.9 663486.0 670677.0 671508.0 688667.0 699894.0 81171.4 85864.0 88544.0 89206.6 89422.6 1109.5 1192.5 1194.9 1196.4 1198.3 15937.8 16035.0 16038.1 16142.0 16406.3 34093.3 34915.0 35074.6 36034.0 36279.8 46720.1 51661.7 52920.0 53269.5 55999.2 9996.8 10267.6 10650.3 10665.6 10685.8 18072.0 18101.8 18188.6 18643.0 19059.3 13989.3 14091.9 14409.3 14791.9 15624.0 15091.4 15502.6 15584.8 15816.4 15889.8 11526.2 14181.3 15398.3 15646.0 15922.5 9929.0 10336.7 10358.0 10371.2 10490.4 12236.0 15560.9 15873.1 16092.2 16192.3 20:49:31 INFO - 2016-01-26 20:49:31,438 DEBUG : BROWSER_OUTPUT: 20:49:31 INFO - 2016-01-26 20:49:31,438 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5792.18 Median:5982.45 stddev:2988.77 (50.0%) stddev-sans-first:2980.30 20:49:31 INFO - 2016-01-26 20:49:31,438 DEBUG : BROWSER_OUTPUT: Values: 1569.6 1575.0 1783.4 1857.8 1884.5 7494.8 7626.4 7968.1 8194.3 8201.7 7777.5 7883.8 7988.8 7993.8 7995.6 2361.3 2407.2 2408.0 2408.5 2410.0 2131.9 2181.9 2377.7 2442.1 2453.1 2393.4 2401.4 2413.1 2416.2 2417.7 2411.9 2433.5 2434.4 2437.0 2440.7 2383.0 2438.5 2440.4 2441.0 2447.5 8175.7 8252.2 8255.8 8257.5 8261.2 4847.7 4967.7 4974.7 4975.8 4979.2 3266.5 3332.2 3401.4 3404.3 3416.2 1096.8 1101.1 1145.5 1174.7 1184.2 11691.0 11711.3 11727.0 11746.4 11750.8 4617.9 4626.0 4651.0 4692.8 4692.8 9902.8 9903.4 9908.5 9946.1 9956.7 4991.8 5012.6 5019.4 5022.5 5134.9 4439.1 4490.9 4493.6 4494.2 4498.6 3406.6 4055.4 4058.1 4072.9 4085.9 1211.5 1277.3 1279.4 1292.3 1327.3 1152.8 1381.7 1406.0 1409.9 1419.7 7669.5 7693.7 7725.1 7787.7 7792.3 1166.7 1174.4 1177.6 1181.1 1192.8 5858.6 5947.4 5970.3 5977.8 5987.1 9481.3 9912.2 9912.6 10029.9 10047.0 9903.6 9937.9 9963.0 9963.9 9967.3 7455.7 7754.3 7755.3 7756.9 7768.0 7253.9 7292.7 7313.1 7330.0 7352.3 8799.1 8869.9 8944.0 8986.6 9012.7 8855.1 9055.4 9213.0 9240.7 9297.0 7061.3 7119.1 7119.9 7120.3 7123.8 7664.3 7683.0 7715.8 7913.4 7925.3 7329.9 7340.6 7390.3 7428.5 7513.4 7222.7 7281.6 7392.3 7576.6 7613.3 7483.2 7649.1 7686.0 7725.5 7726.2 6770.7 6816.4 6827.2 6844.7 6844.9 6920.9 6959.5 6971.2 6972.5 6976.9 20:49:31 INFO - 2016-01-26 20:49:31,438 DEBUG : BROWSER_OUTPUT: 20:49:31 INFO - 2016-01-26 20:49:31,438 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5546.20 Median:6268.68 stddev:1636.20 (26.1%) stddev-sans-first:1593.92 20:49:31 INFO - 2016-01-26 20:49:31,440 DEBUG : BROWSER_OUTPUT: Values: 366.2 369.2 370.1 371.9 378.1 6067.4 6074.0 6104.4 6107.2 6149.8 6017.8 6077.8 6086.8 6110.2 6180.2 4754.4 4758.8 4774.1 4785.3 4811.1 4593.3 4693.6 4725.2 4729.7 4800.1 4536.9 4627.7 4697.6 4787.9 4791.6 4765.5 4779.2 4785.0 4786.7 4791.8 4251.3 4253.2 4254.2 4263.1 4266.2 5824.6 5837.5 5855.3 5861.6 5867.6 5501.4 5614.4 5662.7 5666.2 5671.9 6525.1 6545.6 6602.0 6606.3 6608.0 4518.4 4575.8 4630.5 4765.1 4768.4 6706.7 6759.9 6784.3 6785.3 6795.3 4825.0 4884.1 4887.4 4889.6 4893.6 4866.9 4952.7 4954.4 4957.9 4958.1 5591.6 5598.0 5606.7 5610.0 5612.7 6602.5 6606.8 6609.1 6617.8 6620.4 1723.6 1746.7 1753.6 1754.3 1763.4 1203.3 1204.4 1204.6 1216.7 1237.8 6359.0 6375.1 6379.2 6380.4 6384.4 6817.5 6827.1 6827.4 6829.4 6833.4 6249.3 6288.1 6375.3 6379.6 6381.9 3672.9 3693.9 3694.7 3699.4 3699.8 6928.3 6936.3 6951.2 6953.8 6955.9 6821.8 6916.3 6958.5 6958.5 6961.1 6940.4 6947.2 6993.2 6998.2 7002.4 6782.5 6782.8 6790.0 6794.5 6816.2 6567.7 6629.8 6699.8 6789.6 6804.8 6997.4 7071.2 7072.8 7075.1 7084.8 4820.0 4820.5 4823.2 4825.5 4825.7 6682.4 6827.4 6836.0 6837.4 6843.2 6655.3 6673.2 6675.5 6676.6 6682.3 6542.2 6631.9 6657.8 6670.7 6675.9 6589.4 6593.1 6593.7 6595.8 6604.6 6716.6 6728.1 6732.3 6734.0 6734.9 6439.8 6537.6 6554.4 6569.9 6591.7 20:49:31 INFO - 2016-01-26 20:49:31,440 DEBUG : BROWSER_OUTPUT: 20:49:31 INFO - 2016-01-26 20:49:31,440 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14330.58 Median:46695.03 stddev:16892.52 (36.2%) stddev-sans-first:16909.14 20:49:31 INFO - 2016-01-26 20:49:31,440 DEBUG : BROWSER_OUTPUT: Values: 753.6 792.8 926.0 934.1 971.7 13830.4 14649.2 15470.0 15475.3 15527.7 14046.1 14058.4 14118.4 14139.2 14142.1 757.5 767.5 898.9 901.0 920.7 917.5 924.2 930.7 931.7 931.8 785.4 964.2 973.7 973.8 974.1 789.9 938.2 949.7 953.5 953.7 707.6 708.5 708.9 712.4 712.5 13002.7 13192.4 13274.6 13296.4 13359.7 12168.5 12802.0 12845.2 13070.6 13144.3 10280.6 10420.4 10483.0 10533.3 10534.2 3776.4 3781.8 3816.2 3818.0 3832.1 48199.5 48618.3 49981.0 50078.7 50243.7 9439.1 9843.8 9962.9 10041.0 10215.6 19492.9 19596.2 19604.4 19635.5 19667.0 7710.3 7762.0 7861.3 7887.1 8130.3 3896.8 3915.5 3995.5 4020.3 4042.1 3332.8 3347.3 3355.2 3365.7 3372.1 540.1 552.3 555.2 556.4 563.2 638.9 681.9 710.1 731.0 739.0 8418.7 8790.1 8906.4 9122.6 9201.9 595.6 705.3 719.1 738.4 766.6 4055.0 4109.7 4114.7 4181.8 4197.6 61270.4 62500.0 62532.7 62569.1 62733.1 53766.5 54772.5 55245.7 55297.4 55431.2 49261.5 49507.3 49674.8 50049.7 50312.5 8240.1 8305.4 8365.7 8502.1 8551.4 34593.8 35319.5 35419.5 36294.6 36488.0 41643.4 45789.3 46236.0 46300.8 47089.3 10162.2 10363.1 10921.1 10935.8 11166.7 11116.2 11252.2 11449.3 11641.2 11794.0 10372.1 10395.5 10485.3 10619.5 10872.1 10854.9 11140.6 11388.9 11756.5 12082.4 11214.8 11298.2 11374.4 11757.9 11794.4 10238.9 10544.0 10725.6 10763.9 10849.3 8889.6 9340.2 9682.2 9838.9 9898.6 20:49:31 INFO - 2016-01-26 20:49:31,440 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 20:49:31 INFO - 2016-01-26 20:49:31,440 DEBUG : BROWSER_OUTPUT: 20:49:32 INFO - 2016-01-26 20:49:32,022 INFO : Browser exited with error code: 0 20:49:32 INFO - 2016-01-26 20:49:32,040 INFO : Completed test dromaeo_css (00:18:29) 20:49:32 INFO - 2016-01-26 20:49:32,040 INFO : Starting test kraken 20:49:32 INFO - 2016-01-26 20:49:32,040 DEBUG : operating with platform_type : linux_ 20:49:32 INFO - 2016-01-26 20:49:32,040 INFO : Initialising browser for kraken test... 20:49:32 INFO - 2016-01-26 20:49:32,047 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp5YYvOM/profile http://localhost:35816/getInfo.html 20:49:35 INFO - 2016-01-26 20:49:35,441 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 20:49:35 INFO - 2016-01-26 20:49:35,442 DEBUG : BROWSER_OUTPUT: colorDepth:24 20:49:35 INFO - 2016-01-26 20:49:35,442 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 20:49:35 INFO - 2016-01-26 20:49:35,442 DEBUG : BROWSER_OUTPUT: __metrics 20:49:35 INFO - 2016-01-26 20:49:35,631 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] 20:49:36 INFO - 2016-01-26 20:49:36,466 INFO : Browser initialized. 20:49:36 INFO - 2016-01-26 20:49:36,467 INFO : Running cycle 1/1 for kraken test... 20:49:36 INFO - 2016-01-26 20:49:36,467 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp5YYvOM/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 20:49:48 INFO - 2016-01-26 20:49:48,228 DEBUG : BROWSER_OUTPUT: RSS: Main: 134176768 20:49:48 INFO - 2016-01-26 20:49:48,228 DEBUG : BROWSER_OUTPUT: 20:49:50 INFO - 2016-01-26 20:49:50,663 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35816/tests/kraken/driver.html?testName=audio-beat-detection) 20:49:51 INFO - 2016-01-26 20:49:51,225 DEBUG : BROWSER_OUTPUT: RSS: Main: 166285312 20:49:51 INFO - 2016-01-26 20:49:51,225 DEBUG : BROWSER_OUTPUT: 20:49:53 INFO - 2016-01-26 20:49:53,766 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35816/tests/kraken/driver.html?testName=audio-dft) 20:49:54 INFO - 2016-01-26 20:49:54,331 DEBUG : BROWSER_OUTPUT: RSS: Main: 164868096 20:49:54 INFO - 2016-01-26 20:49:54,332 DEBUG : BROWSER_OUTPUT: 20:49:57 INFO - 2016-01-26 20:49:57,134 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35816/tests/kraken/driver.html?testName=audio-fft) 20:49:57 INFO - 2016-01-26 20:49:57,703 DEBUG : BROWSER_OUTPUT: RSS: Main: 160731136 20:49:57 INFO - 2016-01-26 20:49:57,704 DEBUG : BROWSER_OUTPUT: 20:49:59 INFO - 2016-01-26 20:49:59,660 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35816/tests/kraken/driver.html?testName=audio-oscillator) 20:50:00 INFO - 2016-01-26 20:50:00,233 DEBUG : BROWSER_OUTPUT: RSS: Main: 161984512 20:50:00 INFO - 2016-01-26 20:50:00,233 DEBUG : BROWSER_OUTPUT: 20:50:02 INFO - 2016-01-26 20:50:02,433 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35816/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:50:02 INFO - 2016-01-26 20:50:02,994 DEBUG : BROWSER_OUTPUT: RSS: Main: 161226752 20:50:02 INFO - 2016-01-26 20:50:02,994 DEBUG : BROWSER_OUTPUT: 20:50:05 INFO - 2016-01-26 20:50:05,856 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35816/tests/kraken/driver.html?testName=imaging-darkroom) 20:50:06 INFO - 2016-01-26 20:50:06,429 DEBUG : BROWSER_OUTPUT: RSS: Main: 160006144 20:50:06 INFO - 2016-01-26 20:50:06,430 DEBUG : BROWSER_OUTPUT: 20:50:09 INFO - 2016-01-26 20:50:09,735 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35816/tests/kraken/driver.html?testName=imaging-desaturate) 20:50:10 INFO - 2016-01-26 20:50:10,304 DEBUG : BROWSER_OUTPUT: RSS: Main: 160186368 20:50:10 INFO - 2016-01-26 20:50:10,304 DEBUG : BROWSER_OUTPUT: 20:50:13 INFO - 2016-01-26 20:50:13,026 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35816/tests/kraken/driver.html?testName=json-parse-financial) 20:50:13 INFO - 2016-01-26 20:50:13,595 DEBUG : BROWSER_OUTPUT: RSS: Main: 162004992 20:50:13 INFO - 2016-01-26 20:50:13,595 DEBUG : BROWSER_OUTPUT: 20:50:15 INFO - 2016-01-26 20:50:15,148 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35816/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:50:15 INFO - 2016-01-26 20:50:15,711 DEBUG : BROWSER_OUTPUT: RSS: Main: 167694336 20:50:15 INFO - 2016-01-26 20:50:15,712 DEBUG : BROWSER_OUTPUT: 20:50:17 INFO - 2016-01-26 20:50:17,455 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35816/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:50:18 INFO - 2016-01-26 20:50:18,057 DEBUG : BROWSER_OUTPUT: RSS: Main: 164020224 20:50:18 INFO - 2016-01-26 20:50:18,057 DEBUG : BROWSER_OUTPUT: 20:50:20 INFO - 2016-01-26 20:50:20,008 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35816/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:50:20 INFO - 2016-01-26 20:50:20,578 DEBUG : BROWSER_OUTPUT: RSS: Main: 169824256 20:50:20 INFO - 2016-01-26 20:50:20,578 DEBUG : BROWSER_OUTPUT: 20:50:22 INFO - 2016-01-26 20:50:22,932 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35816/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:50:23 INFO - 2016-01-26 20:50:23,513 DEBUG : BROWSER_OUTPUT: RSS: Main: 175394816 20:50:23 INFO - 2016-01-26 20:50:23,513 DEBUG : BROWSER_OUTPUT: 20:50:26 INFO - 2016-01-26 20:50:26,009 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35816/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:50:26 INFO - 2016-01-26 20:50:26,576 DEBUG : BROWSER_OUTPUT: RSS: Main: 174374912 20:50:26 INFO - 2016-01-26 20:50:26,576 DEBUG : BROWSER_OUTPUT: 20:50:28 INFO - 2016-01-26 20:50:28,196 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35816/tests/kraken/driver.html?testName=ai-astar) 20:50:28 INFO - 2016-01-26 20:50:28,446 DEBUG : BROWSER_OUTPUT: RSS: Main: 233070592 20:50:28 INFO - 2016-01-26 20:50:28,446 DEBUG : BROWSER_OUTPUT: 20:50:28 INFO - 2016-01-26 20:50:28,446 DEBUG : BROWSER_OUTPUT: __start_tp_report 20:50:28 INFO - 2016-01-26 20:50:28,448 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 20:50:28 INFO - 2016-01-26 20:50:28,448 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 20:50:28 INFO - 2016-01-26 20:50:28,448 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 20:50:28 INFO - 2016-01-26 20:50:28,449 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;105;109;110;111;98;109;108;110 20:50:28 INFO - 2016-01-26 20:50:28,449 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;147;127;147;161;123;143;149;120;158;122 20:50:28 INFO - 2016-01-26 20:50:28,449 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;161;161;161;161;161;160;162;160;162 20:50:28 INFO - 2016-01-26 20:50:28,449 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;71;139;73;71;70;70;156;70 20:50:28 INFO - 2016-01-26 20:50:28,449 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;92;135;91;97;91;136;92;93;141 20:50:28 INFO - 2016-01-26 20:50:28,449 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;109;109;109;109;109 20:50:28 INFO - 2016-01-26 20:50:28,450 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;145;146;144;145;145;145;145;145 20:50:28 INFO - 2016-01-26 20:50:28,450 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;94;96;95;95;95;95;94;95 20:50:28 INFO - 2016-01-26 20:50:28,450 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;56;54;54;60;55;55;56;54;54 20:50:28 INFO - 2016-01-26 20:50:28,450 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;64;66;65;65;64;65;64;64 20:50:28 INFO - 2016-01-26 20:50:28,450 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;78;78;78;78;77;77;78;78;78;77 20:50:28 INFO - 2016-01-26 20:50:28,450 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;117;123;111;116;115;117;111;132;115;118 20:50:28 INFO - 2016-01-26 20:50:28,450 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;147;141;150;144;144;134;143;141;142;152 20:50:28 INFO - 2016-01-26 20:50:28,450 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;64;65;62;65;64;61;62;64;63 20:50:28 INFO - 2016-01-26 20:50:28,451 DEBUG : BROWSER_OUTPUT: __end_tp_report 20:50:28 INFO - 2016-01-26 20:50:28,451 DEBUG : BROWSER_OUTPUT: __start_cc_report 20:50:28 INFO - 2016-01-26 20:50:28,451 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,901 20:50:28 INFO - 2016-01-26 20:50:28,451 DEBUG : BROWSER_OUTPUT: __end_cc_report 20:50:28 INFO - 2016-01-26 20:50:28,451 DEBUG : BROWSER_OUTPUT: __startTimestamp1453870228446__endTimestamp 20:50:28 INFO - 2016-01-26 20:50:28,451 DEBUG : BROWSER_OUTPUT: 20:50:28 INFO - 2016-01-26 20:50:28,455 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 20:50:28 INFO - 2016-01-26 20:50:28,455 DEBUG : BROWSER_OUTPUT: Number of tests: 14 20:50:28 INFO - 2016-01-26 20:50:28,455 DEBUG : BROWSER_OUTPUT: 20:50:28 INFO - 2016-01-26 20:50:28,455 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.80 Median:110.00 stddev:3.82 (3.5%) stddev-sans-first:4.06 20:50:28 INFO - 2016-01-26 20:50:28,455 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 105.0 109.0 110.0 111.0 98.0 109.0 108.0 110.0 20:50:28 INFO - 2016-01-26 20:50:28,455 DEBUG : BROWSER_OUTPUT: 20:50:28 INFO - 2016-01-26 20:50:28,455 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:139.70 Median:147.00 stddev:15.40 (10.5%) stddev-sans-first:16.10 20:50:28 INFO - 2016-01-26 20:50:28,455 DEBUG : BROWSER_OUTPUT: Values: 147.0 127.0 147.0 161.0 123.0 143.0 149.0 120.0 158.0 122.0 20:50:28 INFO - 2016-01-26 20:50:28,455 DEBUG : BROWSER_OUTPUT: 20:50:28 INFO - 2016-01-26 20:50:28,455 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 20:50:28 INFO - 2016-01-26 20:50:28,455 DEBUG : BROWSER_OUTPUT: Values: 161.0 161.0 161.0 161.0 161.0 161.0 160.0 162.0 160.0 162.0 20:50:28 INFO - 2016-01-26 20:50:28,455 DEBUG : BROWSER_OUTPUT: 20:50:28 INFO - 2016-01-26 20:50:28,455 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:86.20 Median:71.00 stddev:32.57 (45.9%) stddev-sans-first:34.08 20:50:28 INFO - 2016-01-26 20:50:28,455 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 71.0 139.0 73.0 71.0 70.0 70.0 156.0 70.0 20:50:28 INFO - 2016-01-26 20:50:28,456 DEBUG : BROWSER_OUTPUT: 20:50:28 INFO - 2016-01-26 20:50:28,456 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.20 Median:92.00 stddev:21.61 (23.5%) stddev-sans-first:22.46 20:50:28 INFO - 2016-01-26 20:50:28,456 DEBUG : BROWSER_OUTPUT: Values: 94.0 92.0 135.0 91.0 97.0 91.0 136.0 92.0 93.0 141.0 20:50:28 INFO - 2016-01-26 20:50:28,456 DEBUG : BROWSER_OUTPUT: 20:50:28 INFO - 2016-01-26 20:50:28,456 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 20:50:28 INFO - 2016-01-26 20:50:28,456 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 20:50:28 INFO - 2016-01-26 20:50:28,456 DEBUG : BROWSER_OUTPUT: 20:50:28 INFO - 2016-01-26 20:50:28,456 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.00 Median:145.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 20:50:28 INFO - 2016-01-26 20:50:28,456 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 145.0 146.0 144.0 145.0 145.0 145.0 145.0 145.0 20:50:28 INFO - 2016-01-26 20:50:28,456 DEBUG : BROWSER_OUTPUT: 20:50:28 INFO - 2016-01-26 20:50:28,456 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 20:50:28 INFO - 2016-01-26 20:50:28,456 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 94.0 96.0 95.0 95.0 95.0 95.0 94.0 95.0 20:50:28 INFO - 2016-01-26 20:50:28,456 DEBUG : BROWSER_OUTPUT: 20:50:28 INFO - 2016-01-26 20:50:28,456 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.00 stddev:1.83 (3.3%) stddev-sans-first:1.94 20:50:28 INFO - 2016-01-26 20:50:28,456 DEBUG : BROWSER_OUTPUT: Values: 55.0 56.0 54.0 54.0 60.0 55.0 55.0 56.0 54.0 54.0 20:50:28 INFO - 2016-01-26 20:50:28,456 DEBUG : BROWSER_OUTPUT: 20:50:28 INFO - 2016-01-26 20:50:28,457 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.50 Median:64.50 stddev:0.71 (1.1%) stddev-sans-first:0.73 20:50:28 INFO - 2016-01-26 20:50:28,457 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 64.0 66.0 65.0 65.0 64.0 65.0 64.0 64.0 20:50:28 INFO - 2016-01-26 20:50:28,457 DEBUG : BROWSER_OUTPUT: 20:50:28 INFO - 2016-01-26 20:50:28,457 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.70 Median:78.00 stddev:0.48 (0.6%) stddev-sans-first:0.50 20:50:28 INFO - 2016-01-26 20:50:28,457 DEBUG : BROWSER_OUTPUT: Values: 78.0 78.0 78.0 78.0 77.0 77.0 78.0 78.0 78.0 77.0 20:50:28 INFO - 2016-01-26 20:50:28,457 DEBUG : BROWSER_OUTPUT: 20:50:28 INFO - 2016-01-26 20:50:28,457 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:117.50 Median:117.00 stddev:6.15 (5.3%) stddev-sans-first:6.52 20:50:28 INFO - 2016-01-26 20:50:28,457 DEBUG : BROWSER_OUTPUT: Values: 117.0 123.0 111.0 116.0 115.0 117.0 111.0 132.0 115.0 118.0 20:50:28 INFO - 2016-01-26 20:50:28,457 DEBUG : BROWSER_OUTPUT: 20:50:28 INFO - 2016-01-26 20:50:28,457 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.80 Median:144.00 stddev:5.07 (3.5%) stddev-sans-first:5.25 20:50:28 INFO - 2016-01-26 20:50:28,457 DEBUG : BROWSER_OUTPUT: Values: 147.0 141.0 150.0 144.0 144.0 134.0 143.0 141.0 142.0 152.0 20:50:28 INFO - 2016-01-26 20:50:28,457 DEBUG : BROWSER_OUTPUT: 20:50:28 INFO - 2016-01-26 20:50:28,457 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:64.00 stddev:1.35 (2.1%) stddev-sans-first:1.41 20:50:28 INFO - 2016-01-26 20:50:28,457 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 65.0 62.0 65.0 64.0 61.0 62.0 64.0 63.0 20:50:28 INFO - 2016-01-26 20:50:28,457 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 20:50:28 INFO - 2016-01-26 20:50:28,458 DEBUG : BROWSER_OUTPUT: 20:50:29 INFO - 2016-01-26 20:50:29,102 INFO : Browser exited with error code: 0 20:50:29 INFO - 2016-01-26 20:50:29,153 INFO : Completed test kraken (00:00:57) 20:50:29 INFO - 2016-01-26 20:50:29,153 INFO : Starting test v8_7 20:50:29 INFO - 2016-01-26 20:50:29,153 DEBUG : operating with platform_type : linux_ 20:50:29 INFO - 2016-01-26 20:50:29,153 INFO : Initialising browser for v8_7 test... 20:50:29 INFO - 2016-01-26 20:50:29,159 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOhjXqL/profile http://localhost:35816/getInfo.html 20:50:32 INFO - 2016-01-26 20:50:32,953 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 20:50:32 INFO - 2016-01-26 20:50:32,954 DEBUG : BROWSER_OUTPUT: colorDepth:24 20:50:32 INFO - 2016-01-26 20:50:32,954 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 20:50:32 INFO - 2016-01-26 20:50:32,954 DEBUG : BROWSER_OUTPUT: __metrics 20:50:33 INFO - 2016-01-26 20:50:33,135 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] 20:50:33 INFO - 2016-01-26 20:50:33,829 INFO : Browser initialized. 20:50:33 INFO - 2016-01-26 20:50:33,830 INFO : Running cycle 1/1 for v8_7 test... 20:50:33 INFO - 2016-01-26 20:50:33,830 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOhjXqL/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 20:50:45 INFO - 2016-01-26 20:50:45,579 DEBUG : BROWSER_OUTPUT: RSS: Main: 133967872 20:50:45 INFO - 2016-01-26 20:50:45,579 DEBUG : BROWSER_OUTPUT: 20:51:06 INFO - 2016-01-26 20:51:06,921 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35816/tests/v8_7/run.html (next: http://localhost:35816/tests/v8_7/run.html) 20:51:07 INFO - 2016-01-26 20:51:07,178 DEBUG : BROWSER_OUTPUT: RSS: Main: 423628800 20:51:07 INFO - 2016-01-26 20:51:07,179 DEBUG : BROWSER_OUTPUT: 20:51:07 INFO - 2016-01-26 20:51:07,179 DEBUG : BROWSER_OUTPUT: __start_tp_report 20:51:07 INFO - 2016-01-26 20:51:07,179 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 20:51:07 INFO - 2016-01-26 20:51:07,179 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 20:51:07 INFO - 2016-01-26 20:51:07,179 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 20:51:07 INFO - 2016-01-26 20:51:07,179 DEBUG : BROWSER_OUTPUT: |0;Richards;171.40898183064792 20:51:07 INFO - 2016-01-26 20:51:07,179 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.77179106936893 20:51:07 INFO - 2016-01-26 20:51:07,179 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.3476310625175 20:51:07 INFO - 2016-01-26 20:51:07,180 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5170.103092783505 20:51:07 INFO - 2016-01-26 20:51:07,180 DEBUG : BROWSER_OUTPUT: |4;RayTrace;948.7666034155598 20:51:07 INFO - 2016-01-26 20:51:07,180 DEBUG : BROWSER_OUTPUT: |5;Earley;554.016620498615 20:51:07 INFO - 2016-01-26 20:51:07,180 DEBUG : BROWSER_OUTPUT: |6;Boyer;11120.87912087912 20:51:07 INFO - 2016-01-26 20:51:07,180 DEBUG : BROWSER_OUTPUT: |7;RegExp;23904.761904761905 20:51:07 INFO - 2016-01-26 20:51:07,180 DEBUG : BROWSER_OUTPUT: |8;Splay;524.1090146750524 20:51:07 INFO - 2016-01-26 20:51:07,180 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 20:51:07 INFO - 2016-01-26 20:51:07,180 DEBUG : BROWSER_OUTPUT: __end_tp_report 20:51:07 INFO - 2016-01-26 20:51:07,181 DEBUG : BROWSER_OUTPUT: __startTimestamp1453870267173__endTimestamp 20:51:07 INFO - 2016-01-26 20:51:07,181 DEBUG : BROWSER_OUTPUT: 20:51:07 INFO - 2016-01-26 20:51:07,181 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 20:51:07 INFO - 2016-01-26 20:51:07,181 DEBUG : BROWSER_OUTPUT: Number of tests: 10 20:51:07 INFO - 2016-01-26 20:51:07,181 DEBUG : BROWSER_OUTPUT: 20:51:07 INFO - 2016-01-26 20:51:07,181 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:171.41 Median:171.41 stddev:0.00 (0.0%) 20:51:07 INFO - 2016-01-26 20:51:07,181 DEBUG : BROWSER_OUTPUT: Values: 171.4 20:51:07 INFO - 2016-01-26 20:51:07,181 DEBUG : BROWSER_OUTPUT: 20:51:07 INFO - 2016-01-26 20:51:07,182 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.77 Median:164.77 stddev:0.00 (0.0%) 20:51:07 INFO - 2016-01-26 20:51:07,182 DEBUG : BROWSER_OUTPUT: Values: 164.8 20:51:07 INFO - 2016-01-26 20:51:07,182 DEBUG : BROWSER_OUTPUT: 20:51:07 INFO - 2016-01-26 20:51:07,182 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.35 Median:280.35 stddev:0.00 (0.0%) 20:51:07 INFO - 2016-01-26 20:51:07,183 DEBUG : BROWSER_OUTPUT: Values: 280.3 20:51:07 INFO - 2016-01-26 20:51:07,183 DEBUG : BROWSER_OUTPUT: 20:51:07 INFO - 2016-01-26 20:51:07,183 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5170.10 Median:5170.10 stddev:0.00 (0.0%) 20:51:07 INFO - 2016-01-26 20:51:07,183 DEBUG : BROWSER_OUTPUT: Values: 5170.1 20:51:07 INFO - 2016-01-26 20:51:07,183 DEBUG : BROWSER_OUTPUT: 20:51:07 INFO - 2016-01-26 20:51:07,183 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:948.77 Median:948.77 stddev:0.00 (0.0%) 20:51:07 INFO - 2016-01-26 20:51:07,183 DEBUG : BROWSER_OUTPUT: Values: 948.8 20:51:07 INFO - 2016-01-26 20:51:07,184 DEBUG : BROWSER_OUTPUT: 20:51:07 INFO - 2016-01-26 20:51:07,184 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:554.02 Median:554.02 stddev:0.00 (0.0%) 20:51:07 INFO - 2016-01-26 20:51:07,184 DEBUG : BROWSER_OUTPUT: Values: 554.0 20:51:07 INFO - 2016-01-26 20:51:07,184 DEBUG : BROWSER_OUTPUT: 20:51:07 INFO - 2016-01-26 20:51:07,184 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:11120.88 Median:11120.88 stddev:0.00 (0.0%) 20:51:07 INFO - 2016-01-26 20:51:07,184 DEBUG : BROWSER_OUTPUT: Values: 11120.9 20:51:07 INFO - 2016-01-26 20:51:07,184 DEBUG : BROWSER_OUTPUT: 20:51:07 INFO - 2016-01-26 20:51:07,185 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23904.76 Median:23904.76 stddev:0.00 (0.0%) 20:51:07 INFO - 2016-01-26 20:51:07,185 DEBUG : BROWSER_OUTPUT: Values: 23904.8 20:51:07 INFO - 2016-01-26 20:51:07,185 DEBUG : BROWSER_OUTPUT: 20:51:07 INFO - 2016-01-26 20:51:07,185 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:524.11 Median:524.11 stddev:0.00 (0.0%) 20:51:07 INFO - 2016-01-26 20:51:07,185 DEBUG : BROWSER_OUTPUT: Values: 524.1 20:51:07 INFO - 2016-01-26 20:51:07,185 DEBUG : BROWSER_OUTPUT: 20:51:07 INFO - 2016-01-26 20:51:07,185 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 20:51:07 INFO - 2016-01-26 20:51:07,186 DEBUG : BROWSER_OUTPUT: Values: 4835.7 20:51:07 INFO - 2016-01-26 20:51:07,186 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 20:51:07 INFO - 2016-01-26 20:51:07,186 DEBUG : BROWSER_OUTPUT: 20:51:07 INFO - 2016-01-26 20:51:07,838 INFO : Browser exited with error code: 0 20:51:07 INFO - 2016-01-26 20:51:07,852 INFO : Completed test v8_7 (00:00:38) 20:51:08 INFO - 2016-01-26 20:51:08,225 INFO : Completed test suite (00:20:06) 20:51:08 INFO - 2016-01-26 20:51:08,226 DEBUG : Working with test: dromaeo_css 20:51:08 INFO - 2016-01-26 20:51:08,226 DEBUG : Generating results file: dromaeo_css 20:51:08 INFO - 2016-01-26 20:51:08,227 DEBUG : Working with test: kraken 20:51:08 INFO - 2016-01-26 20:51:08,227 DEBUG : Generating results file: kraken 20:51:08 INFO - 2016-01-26 20:51:08,227 INFO : javascript benchmark 20:51:08 INFO - 2016-01-26 20:51:08,227 DEBUG : Working with test: v8_7 20:51:08 INFO - 2016-01-26 20:51:08,227 DEBUG : Generating results file: v8_7 20:51:08 INFO - 2016-01-26 20:51:08,228 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 20:51:08 INFO - 2016-01-26 20:51:08,348 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 20:51:08 INFO - 2016-01-26 20:51:08,348 DEBUG : process_Request line: dromaeo_css 7412.18 graph.html#tests=[[72,53,35]] 20:51:08 INFO - 2016-01-26 20:51:08,348 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 20:51:08 INFO - 2016-01-26 20:51:08,476 DEBUG : process_Request line: kraken 1472.10 graph.html#tests=[[232,53,35]] 20:51:08 INFO - 2016-01-26 20:51:08,476 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 20:51:08 INFO - 2016-01-26 20:51:08,501 DEBUG : process_Request line: v8_7 23082.21 graph.html#tests=[[230,53,35]] 20:51:08 INFO - 2016-01-26 20:51:08,502 INFO : javascript benchmark 20:51:08 INFO - 2016-01-26 20:51:08,506 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3330.883419080332, 3396.9320613587715, 3434.8969530914064, 3440.5355277037606, 3463.774854634448, 5628.2120503129545, 5641.717914104289, 5674.31908171019, 5678.119891416832, 5699.943000569998, 5657.61388459828, 5686.488216060554, 5702.857428564288, 5714.599978001542, 5730.398308177644, 4901.509849015088, 5119.436861945183, 5177.48225177483, 5240.292560504321, 5266.841994740144, 5127.154019586782, 5143.691378517301, 5151.716655583941, 5151.948480515203, 5165.431802501741, 5063.696178229299, 5139.640225184238, 5159.664621799571, 5162.974185129069, 5186.377634683843, 5183.092958732225, 5229.712365819878, 5231.659942103751, 5232.843014709565, 5237.6071794615555, 5069.087564238435, 5116.616253780981, 5199.792008319662, 5207.140821764404, 5253.763580638881, 5285.048691235576, 5310.867228319285, 5391.48780865821, 5420.756065977001, 5424.620276580641, 6104.786332478387, 6115.663638499881, 6140.0789881517685, 6152.077188421727, 6152.538559608002, 6112.633242005494, 6179.443850053517, 6186.721597528122, 6190.009598464269, 6194.163787888622, 5537.36320323164, 5634.690092044936, 5644.153376993443, 5714.028615135437, 5743.741531631045, 6146.5390095743, 6211.192544969125, 6233.252009758858, 6239.0, 6246.0, 5787.565932554991, 6074.240719910012, 6077.544184186204, 6085.57400981927, 6125.509959203253, 5916.230889984275, 6003.039513677791, 6004.519638428916, 6043.214382130383, 6082.817515474454, 6075.119107729354, 6076.635401875901, 6120.693965301717, 6121.969390153021, 6123.846903827351, 5783.595148339576, 5961.791337303173, 6104.542159338067, 6107.144999700045, 6109.816705498843, 4588.977055114703, 4656.11533808582, 4657.6506761992305, 4662.393888794435, 4683.461401938753, 4181.540030596632, 4189.664826813909, 4198.244316023145, 4200.558941311179, 4225.070484493468, 5603.159526071122, 5667.121596152603, 5727.284089488814, 5819.272590926134, 5830.737616807254, 6055.485283750842, 6061.727222275008, 6079.392060793885, 6083.361247068994, 6089.756409743649, 5725.914111288334, 5762.74067666956, 5764.106563482583, 5764.337101233328, 5769.1923130761725, 5112.182050871843, 5149.536541711264, 5229.4770522948165, 5255.159174532057, 5278.472152784768, 6199.814005579839, 6360.128873155407, 6377.298497165308, 6390.616563006328, 6408.423241908251, 6200.3489633589625, 6232.470240029648, 6233.625982440886, 6235.625862448268, 6236.563440559117, 5975.342712301556, 6121.755129794758, 6150.969245153746, 6227.9377206227355, 6242.875142497215, 6014.977453832947, 6040.395960403925, 6060.969695151496, 6102.877942441038, 6109.053096770008, 6171.876562468636, 6188.783392581238, 6200.472959798468, 6206.410391012847, 6208.875822483616, 5761.078227483583, 5838.503727183237, 5859.384764599826, 5924.496417804363, 5960.403959604178, 5904.232449781501, 5981.491573216325, 5990.0, 5991.910121348271, 5992.250968628921, 5688.175214593944, 5832.445917637818, 5878.2064421302575, 5878.559108066997, 5903.173555702291, 5900.380460051804, 5952.404759524013, 6053.636781793107, 6056.830753590621, 6092.664903430176, 5885.205497257986, 5924.674142922181, 5928.4960778334325, 5935.9109613356695, 5944.346121926498], "unit": "ms", "name": "dojo.html", "value": 5610.237472618779}, {"lowerIsBetter": false, "replicates": [621.9830577006594, 625.9937400625995, 641.4034947498827, 646.166445285582, 650.6063831382016, 32615.0, 32967.17582060447, 33016.66983330166, 40100.79949600248, 41059.794701026454, 43926.560734392646, 44332.3350149748, 44500.0, 44511.10977780443, 44528.0, 8691.869621955675, 8908.8663670045, 8914.242289405409, 8918.509481978488, 8924.973628032762, 8202.466839655433, 8397.118302578234, 10672.252942294042, 10852.131829453625, 10892.292001019909, 10637.893621063768, 10725.570977160904, 10747.62383316584, 10748.0, 10749.516271767789, 8177.427580069398, 10556.366618002945, 10632.946835265775, 10673.83989240162, 10676.252662313642, 7767.650455729506, 10681.359118452918, 10913.563457461692, 10929.180311476672, 10934.289271197425, 42040.579594204275, 42138.0, 42327.36508952368, 42463.787681061396, 42473.0, 3662.688671462929, 3753.6997040236715, 3764.4353346997896, 3817.48463957365, 4328.09110086879, 32614.0, 33072.17319567006, 33240.83379583111, 34029.31941361184, 34083.0, 3865.130345672201, 3938.251732170907, 3959.802009899522, 3980.0, 4009.7794621295816, 290491.9999999979, 291442.5427872868, 292567.0, 293655.0, 294214.0, 55481.44518554763, 56321.15518267229, 63226.367736322965, 63682.36317636857, 65119.67440162864, 63018.684906575174, 63141.36858631447, 63240.102796916166, 63572.0, 63648.36351636425, 17217.31130754756, 17250.223739931735, 17306.307747689953, 17330.39343622967, 17484.213210405564, 12671.619851404472, 12945.288009159398, 13047.282399467931, 13145.0, 13305.800412993813, 20117.692349997204, 20457.38627841167, 20477.897610511853, 20558.588828223652, 20614.896925515277, 7700.345470634947, 7708.267714567107, 7757.534547927143, 7764.417668674872, 7818.6090695465, 1005.4419797012582, 1026.5226669598671, 1028.4908970059869, 1064.5422468338534, 1085.8154113800672, 29494.852525737235, 29606.259843504085, 29765.57788068061, 29967.06861118668, 29975.0, 1131.2590253384046, 1140.988590114105, 1192.9821052684217, 1205.7287110400089, 1235.1292338901053, 13574.864251357558, 15643.452479163174, 15705.293261803246, 15848.603784905239, 16773.24520396585, 357089.0, 360418.0, 360992.0, 364192.0, 364816.0, 49026.26460603094, 49220.75389623101, 54294.0, 54760.452395476335, 56509.152362714594, 1413.547664747271, 1430.470725831449, 1432.649000994763, 1434.4333988074652, 1438.6187660269827, 27635.30911727223, 27639.170824875288, 28399.574006389506, 28447.43105137927, 28563.571546426403, 47803.5219647813, 48116.0, 48157.75921120372, 48196.7590162047, 48220.5177948216, 267036.0, 267824.0, 268907.0, 269257.0, 269945.0, 32771.34457310888, 32989.34021319512, 33107.50338744873, 33320.50019249665, 34740.47889281713, 41490.37764433596, 41826.79086604548, 41866.37200441935, 41968.370474443516, 42686.0, 34849.47725784166, 34909.65090349064, 34910.301793963474, 35102.64897351096, 35474.29051418906, 35150.29699406049, 35245.647543525265, 35448.29103417969, 35671.46492802662, 35964.46053309255, 36035.63964360323, 36112.63887361198, 36152.819235903655, 36776.816115919246, 38680.0, 32987.835060824546, 33430.49271304613, 34251.486227706104, 34544.30911381808, 34728.826355868056, 25696.96848664025, 26097.73902260953, 26114.347141321618, 26132.8693356532, 26406.867965660047], "unit": "ms", "name": "ext.html", "value": 19496.73327594228}, {"lowerIsBetter": false, "replicates": [374.3411595591759, 380.51294343240664, 380.5300453939386, 382.4416352125895, 384.91916697493525, 15062.849371506309, 17012.4045658402, 17677.0, 18435.262589496422, 18481.79868308559, 17796.0, 18054.909725451354, 18434.631307373846, 19221.0, 19226.807731922676, 5460.481254280837, 5560.471755183251, 5570.972145139269, 5579.442055794409, 5584.134459158817, 4261.829526818923, 4392.97803510982, 4393.868183954486, 4396.780160991953, 5516.172574113875, 5915.378885217055, 6092.6953652317425, 6109.083637454373, 6109.236345456818, 6109.694515274241, 5866.0, 5991.790287339944, 6019.8194054178, 6021.187139736124, 6028.999999999978, 5284.339457567804, 5370.301860758115, 5377.542908852753, 5379.300690910157, 5390.083685773388, 22083.33749987503, 22167.77832221673, 22359.217427390126, 22414.32757017292, 22417.55164896709, 2314.3751187179537, 2357.104300365867, 2380.8106660317894, 2391.4141035446255, 2459.9016039358403, 13679.726405471834, 13787.379567919468, 14111.576652700436, 14215.431382744679, 14509.782353264709, 1831.0, 1914.5596512802133, 1937.5446770009178, 1955.9804401956083, 1967.4196112146951, 59785.7010714948, 60664.696676516774, 62230.68884655548, 64257.357426426075, 64302.35697643011, 10251.7437064074, 10501.632442864464, 10648.094911932572, 10667.893321066846, 10701.625443109453, 14064.156150630995, 14141.222232777296, 14147.151170929777, 14150.0, 14165.929170354082, 8344.66621335152, 8374.748757537283, 8385.496870187686, 8394.706185283485, 8490.830183396421, 7741.8838717419285, 7766.378689704923, 7778.416618753616, 7855.8821617675785, 7901.288884000468, 6313.463355614732, 6314.116023756677, 6339.207599050119, 6383.904241436382, 6412.551121421456, 1691.8392752688476, 1701.0, 1716.8709254243565, 1777.2180240694054, 1783.3669047488215, 926.3284119013796, 1009.8333774927202, 1010.9898901011043, 1076.3272954403499, 1079.1744315598573, 14846.777298340534, 18469.90765046166, 18495.352662656744, 18555.536111597317, 18852.622947541247, 1049.1239814754613, 1051.64244156987, 1081.599808071003, 1102.3771569063454, 1131.3947038334452, 10134.442605656612, 10234.33476824004, 10365.01532354425, 10510.316829406063, 10925.90740925901, 663486.0, 670677.0, 671508.0, 688667.0, 699894.0, 81171.3808986174, 85864.0, 88544.0, 89206.55396723104, 89422.55288723514, 1109.5229051507768, 1192.4753108632174, 1194.9283043017447, 1196.4257156564724, 1198.2870192235605, 15937.8406215941, 16035.0, 16038.117903514953, 16142.0, 16406.343746250495, 34093.31813363769, 34915.0, 35074.64925350714, 36034.0, 36279.818600907885, 46720.06559868716, 51661.7416912913, 52920.0, 53269.467305328, 55999.16001260066, 9996.800063998533, 10267.640632577824, 10650.307729997525, 10665.573377064831, 10685.786284274116, 18072.0, 18101.818981810015, 18188.636227275645, 18643.0, 19059.332923347618, 13989.300534973414, 14091.859081409464, 14409.279536023367, 14791.92604036973, 15624.0, 15091.396344146111, 15502.612434689223, 15584.766228506809, 15816.446424375552, 15889.841101588836, 11526.193166478266, 14181.290935453393, 15398.307076181374, 15646.0, 15922.522324330288, 9928.950355248178, 10336.689899302732, 10358.0, 10371.222158338003, 10490.423026733602, 12236.0, 15560.922195389403, 15873.12697801632, 16092.195390230207, 16192.271347789143], "unit": "ms", "name": "jquery.html", "value": 9619.168028642334}, {"lowerIsBetter": false, "replicates": [1569.6311366828793, 1574.984250157498, 1783.3936461603053, 1857.823506766857, 1884.4817675139338, 7494.775156745306, 7626.389888808896, 7968.083670377909, 8194.262516373532, 8201.671933122676, 7777.533347999124, 7883.80290492738, 7988.840223195532, 7993.760187194379, 7995.600219989006, 2361.315218586612, 2407.157494876788, 2408.0127147869375, 2408.4821763320883, 2409.963850542243, 2131.8720876747366, 2181.945451363713, 2377.72656144543, 2442.072012635196, 2453.1046168148678, 2393.437542177596, 2401.4236583220077, 2413.070967677448, 2416.226807421626, 2417.746136655652, 2411.855288682676, 2433.476802487456, 2434.3914021494625, 2436.9634455483183, 2440.73151953285, 2382.9642555361684, 2438.536678031186, 2440.438699099217, 2441.0, 2447.5104979004273, 8175.672973081129, 8252.21603947624, 8255.752327430127, 8257.545834979073, 8261.17388261175, 4847.709137451729, 4967.7019378837385, 4974.701517908901, 4975.751212439363, 4979.153543897546, 3266.477363621809, 3332.150301673082, 3401.353742788887, 3404.3361544498835, 3416.24842534642, 1096.8080585897435, 1101.091599430473, 1145.4959817680278, 1174.7474293026955, 1184.2006645514243, 11691.0, 11711.297322160697, 11727.0, 11746.412679365998, 11750.764984700429, 4617.861464156081, 4625.953740462553, 4650.976745116321, 4692.835750748707, 4692.835750748707, 9902.80194396108, 9903.356281841658, 9908.504574771232, 9946.104850563484, 9956.701298961043, 4991.750412479434, 5012.598992080625, 5019.372578427697, 5022.472640372708, 5134.947335796152, 4439.067795762926, 4490.932636010462, 4493.59557639814, 4494.213512635276, 4498.595126438636, 3406.5741782277214, 4055.371417430244, 4058.1072164123843, 4072.898177545585, 4085.9387109193385, 1211.5335595795507, 1277.310252463678, 1279.3922886628964, 1292.3150730113055, 1327.3296985022503, 1152.7694461107812, 1381.6683995841126, 1406.0157889477202, 1409.8660627240395, 1419.666378401075, 7669.501482403737, 7693.730719424793, 7725.072991241087, 7787.727429539939, 7792.259735325135, 1166.7433164703752, 1174.4186627619383, 1177.5643012085584, 1181.1318680769627, 1192.7793358228755, 5858.589898707135, 5947.405259474018, 5970.31341395745, 5977.790777322773, 5987.101934709828, 9481.288903332139, 9912.207023438108, 9912.55393507294, 10029.899701003042, 10047.0, 9903.60385584583, 9937.850932236022, 9962.950185249027, 9963.850542241873, 9967.302288839712, 7455.664495097733, 7754.302112809987, 7755.3407960322875, 7756.92243077562, 7768.0, 7253.854922901618, 7292.744753933587, 7313.122425308997, 7330.010448589373, 7352.338289553966, 8799.076097009722, 8869.911300886908, 8943.955280223558, 8986.55067246622, 9012.68455604077, 8855.070217627057, 9055.411398259092, 9213.032631573857, 9240.676576319796, 9296.95351523238, 7061.329173728487, 7119.1457025157315, 7119.893201601876, 7120.287971202838, 7123.786286411416, 7664.3485303747575, 7682.961585192038, 7715.807104822425, 7913.44605877583, 7925.326347260317, 7329.890051649123, 7340.596267205355, 7390.334869861738, 7428.517146385468, 7513.436492262993, 7222.711091556279, 7281.599512026889, 7392.334689877936, 7576.583287919218, 7613.276738709814, 7483.214262502359, 7649.143385404535, 7685.961570192113, 7725.49784264021, 7726.18875018115, 6770.661466926733, 6816.352446517573, 6827.21487028998, 6844.726210951506, 6844.863102738017, 6920.9307906920285, 6959.547629404073, 6971.2331643518155, 6972.546784458994, 6976.895346569704], "unit": "ms", "name": "mootools.html", "value": 4786.370138234937}, {"lowerIsBetter": false, "replicates": [366.15783697495755, 369.2301551265611, 370.108039624505, 371.8884334699591, 378.08692008798755, 6067.362926892679, 6073.969630151849, 6104.359042300561, 6107.175531303278, 6149.754009839601, 6017.819465416033, 6077.78727744529, 6086.8478288042825, 6110.20567326247, 6180.165677633529, 4754.38193034905, 4758.809647614108, 4774.069056533977, 4785.28220766886, 4811.085893680207, 4593.265077587586, 4693.577578017977, 4725.243960966246, 4729.739864307479, 4800.135975524404, 4536.8865778355475, 4627.652926030544, 4697.647676424264, 4787.904241915159, 4791.640626952975, 4765.547273009059, 4779.1875381185455, 4784.976075119602, 4786.688865223767, 4791.832285869978, 4251.341042138473, 4253.213155566199, 4254.234237837188, 4263.062126332202, 4266.210751011073, 5824.621399609012, 5837.474627283523, 5855.268091488564, 5861.589688721791, 5867.589268751189, 5501.449855014506, 5614.438556144353, 5662.716864156817, 5666.206731057655, 5671.943280567224, 6525.086487891699, 6545.639989800559, 6601.966990165019, 6606.273309935903, 6608.0, 4518.367428560005, 4575.816967321303, 4630.536946305376, 4765.142274390609, 4768.427788665348, 6706.731730730765, 6759.864802703918, 6784.253732089466, 6785.321467853225, 6795.252522222551, 4824.951750482521, 4884.096442158212, 4887.437944636341, 4889.584385327287, 4893.608511319086, 4866.902661946741, 4952.653314267966, 4954.405471343462, 4957.925631115536, 4958.132326842788, 5591.636543624732, 5597.972010139923, 5606.6636001840025, 5609.971950140223, 5612.691301978349, 6602.504812139109, 6606.8348291293105, 6609.1077704510235, 6617.7683781067435, 6620.3710647488415, 1723.620803423245, 1746.6681330547156, 1753.6405036967392, 1754.271977129477, 1763.3916298876868, 1203.2539825308365, 1204.3677069538562, 1204.6386084174887, 1216.7019080325376, 1237.826704261404, 6359.014352775327, 6375.10748495211, 6379.170707807955, 6380.361963803675, 6384.3934826191435, 6817.454603631698, 6827.112475378269, 6827.385535301847, 6829.385355317946, 6833.384995350343, 6249.281332646713, 6288.088227207029, 6375.2987171411105, 6379.649119298483, 6381.936180638134, 3672.8714494992546, 3693.926121477555, 3694.722895782827, 3699.445083237482, 3699.7780133192095, 6928.272531384233, 6936.271691472423, 6951.16586009682, 6953.826154346182, 6955.89566156508, 6821.829454263584, 6916.3429474199875, 6958.47811414146, 6958.547694399847, 6961.060256865258, 6940.444764418834, 6947.235804061549, 6993.160820701447, 6998.195207551095, 7002.404795592432, 6782.457403407617, 6782.796516104525, 6790.049393085071, 6794.524383293122, 6816.182058153054, 6567.671616419065, 6629.806634805781, 6699.76550820719, 6789.558678685869, 6804.76183333581, 6997.370236678723, 7071.151461824614, 7072.787816365517, 7075.115610548682, 7084.822879428056, 4820.0, 4820.469748327751, 4823.2282834746275, 4825.4933232011535, 4825.662203645851, 6682.431993280431, 6827.351401616838, 6835.97460380927, 6837.384635382839, 6843.213030501357, 6655.301193374626, 6673.1992160941, 6675.465962722873, 6676.599404035774, 6682.298358672269, 6542.247641521286, 6631.900521492278, 6657.833554161185, 6670.699818508276, 6675.866482670416, 6589.374009469093, 6593.076969224217, 6593.670316484253, 6595.835104122435, 6604.570702904296, 6716.597004179765, 6728.125343705287, 6732.259451460238, 6733.966330168318, 6734.855074637226, 6439.774607888701, 6537.5750576212395, 6554.410103090745, 6569.868602628017, 6591.736330546724], "unit": "ms", "name": "prototype.html", "value": 5027.305755359792}, {"lowerIsBetter": false, "replicates": [753.6382536382537, 792.7780221537971, 925.9860452804177, 934.1405906565959, 971.689059500959, 13830.446782128716, 14649.19429431381, 15470.0, 15475.30361133749, 15527.689446211069, 14046.08700434471, 14058.367373468192, 14118.43526258951, 14139.151650900927, 14142.080764750284, 757.4849102610223, 767.4589414462819, 898.9011208767031, 901.0403919825364, 920.7421921861855, 917.499962520428, 924.1728652855691, 930.6696122876383, 931.7018554062703, 931.8276118917988, 785.4344871692401, 964.2382517810921, 973.6640858903667, 973.8052389522088, 974.0697633759743, 789.917058708836, 938.1650331205233, 949.7388218240039, 953.4946478366477, 953.7329547726648, 707.5789905006513, 708.4544900426642, 708.9432845372357, 712.4264966701803, 712.4798896805361, 13002.739945201138, 13192.406341714646, 13274.601761947157, 13296.401661925236, 13359.666008349772, 12168.51325946961, 12802.03984701151, 12845.229286242762, 13070.607881763563, 13144.277064761434, 10280.588776449007, 10420.374777513298, 10483.0, 10533.26267161299, 10534.20993425496, 3776.3580191367532, 3781.810909454516, 3816.160444702161, 3818.0, 3832.06114501946, 48199.5180048202, 48618.27072593914, 49981.0, 50078.7496062521, 50243.74878125586, 9439.103285187895, 9843.75390615226, 9962.850557241647, 10041.0, 10215.642452514126, 19492.902535487232, 19596.21615135407, 19604.411867643994, 19635.509112272306, 19667.0, 7710.267524585155, 7762.029746281715, 7861.2531809478005, 7887.092984306878, 8130.3495720342235, 3896.7661940283674, 3915.530136383652, 3995.4606128172904, 4020.2562525932794, 4042.110735638155, 3332.7833690810503, 3347.313800670857, 3355.177981394574, 3365.6802603752603, 3372.072680013016, 540.0980362794144, 552.3427121725215, 555.2420945409567, 556.3990889838964, 563.2115038945509, 638.9488840892717, 681.9215790184193, 710.1052673631261, 730.9524880882726, 738.9704411823575, 8418.663253469793, 8790.120987901286, 8906.376553641312, 9122.635094596275, 9201.907980920105, 595.559286128262, 705.301751266253, 719.0948567786086, 738.3539403022355, 766.5898744171841, 4055.0, 4109.732867363612, 4114.7119701621195, 4181.790910454465, 4197.622214000755, 61270.38729612736, 62500.0, 62532.68733656302, 62569.06146407808, 62733.05900411498, 53766.46233537693, 54772.45227547674, 55245.72377138169, 55297.44702552923, 55431.168532472046, 49261.507384925695, 49507.25739113916, 49674.75162624236, 50049.74975125101, 50312.49687503078, 8240.13478584764, 8305.379178147963, 8365.749027529184, 8502.14978502145, 8551.35864810154, 34593.82703086468, 35319.470207947415, 35419.46870796889, 36294.63705362913, 36488.0, 41643.37534937039, 45789.31316030329, 46236.0, 46300.7684961573, 47089.2936605958, 10162.237832162766, 10363.06732394088, 10921.071708904832, 10935.781284374427, 11166.665000050012, 11116.16628752863, 11252.156088293574, 11449.313041217554, 11641.243319184226, 11794.0, 10372.066514013775, 10395.480225988822, 10485.26603137773, 10619.522121504706, 10872.07587355052, 10854.945725271324, 11140.610078647209, 11388.94305528467, 11756.529738810352, 12082.39588020613, 11214.831777523506, 11298.209125361145, 11374.43127843621, 11757.882421175678, 11794.410279485819, 10238.897611023795, 10544.0, 10725.570977161138, 10763.94618026905, 10849.349039057683, 8889.555522223734, 9340.159385655325, 9682.177014953808, 9838.852417213604, 9898.604055837686], "unit": "ms", "name": "yui.html", "value": 6549.634097640275}], "name": "dromaeo_css", "value": 7412.180526452722}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 105.0, 109.0, 110.0, 111.0, 98.0, 109.0, 108.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 107.8}, {"lowerIsBetter": true, "replicates": [147.0, 127.0, 147.0, 161.0, 123.0, 143.0, 149.0, 120.0, 158.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 139.7}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 161.0, 161.0, 161.0, 161.0, 160.0, 162.0, 160.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 161.0}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 71.0, 139.0, 73.0, 71.0, 70.0, 70.0, 156.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 86.2}, {"lowerIsBetter": true, "replicates": [94.0, 92.0, 135.0, 91.0, 97.0, 91.0, 136.0, 92.0, 93.0, 141.0], "unit": "ms", "name": "audio-oscillator", "value": 106.2}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 146.0, 144.0, 145.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.0}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 94.0, 96.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.0}, {"lowerIsBetter": true, "replicates": [55.0, 56.0, 54.0, 54.0, 60.0, 55.0, 55.0, 56.0, 54.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 55.3}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 64.0, 66.0, 65.0, 65.0, 64.0, 65.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.5}, {"lowerIsBetter": true, "replicates": [78.0, 78.0, 78.0, 78.0, 77.0, 77.0, 78.0, 78.0, 78.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.7}, {"lowerIsBetter": true, "replicates": [117.0, 123.0, 111.0, 116.0, 115.0, 117.0, 111.0, 132.0, 115.0, 118.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 117.5}, {"lowerIsBetter": true, "replicates": [147.0, 141.0, 150.0, 144.0, 144.0, 134.0, 143.0, 141.0, 142.0, 152.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 143.8}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 65.0, 62.0, 65.0, 64.0, 61.0, 62.0, 64.0, 63.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 63.4}], "name": "kraken", "value": 1472.1}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [171.40898183064792], "unit": "score", "name": "Richards", "value": 205.95186799999996}, {"lowerIsBetter": false, "replicates": [164.77179106936893], "unit": "score", "name": "DeltaBlue", "value": 401.2701420000001}, {"lowerIsBetter": false, "replicates": [280.3476310625175], "unit": "score", "name": "Encrypt", "value": 949.4676269999999}, {"lowerIsBetter": false, "replicates": [5170.103092783505], "unit": "score", "name": "Decrypt", "value": 51.48466001994015}, {"lowerIsBetter": false, "replicates": [948.7666034155598], "unit": "score", "name": "RayTrace", "value": 779.9484060000001}, {"lowerIsBetter": false, "replicates": [554.016620498615], "unit": "score", "name": "Earley", "value": 1202.9657150000005}, {"lowerIsBetter": false, "replicates": [11120.87912087912], "unit": "score", "name": "Boyer", "value": 59.92898517786557}, {"lowerIsBetter": false, "replicates": [23904.761904761905], "unit": "score", "name": "RegExp", "value": 38.10893426294821}, {"lowerIsBetter": false, "replicates": [524.1090146750524], "unit": "score", "name": "Splay", "value": 155.48482799999994}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 23082.211027791363}]} 20:51:08 INFO - RETURN: dromaeo_css: 7412.18 20:51:08 INFO - RETURN: kraken: 1472.10 20:51:08 INFO - RETURN: v8_7: 23082.21 20:51:08 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23082.21"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1472.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7412.18"}}} 20:51:08 INFO - Return code: 0 20:51:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 20:51:08 INFO - # TBPL SUCCESS # 20:51:08 INFO - Running post-action listener: _resource_record_post_action 20:51:08 INFO - Running post-run listener: _resource_record_post_run 20:51:09 INFO - Total resource usage - Wall time: 1222s; CPU: 13.0%; Read bytes: 4096; Write bytes: 511987712; Read time: 72; Write time: 418048 20:51:09 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 4096; Write bytes: 246558720; Read time: 72; Write time: 287036 20:51:09 INFO - run-tests - Wall time: 1208s; CPU: 13.0%; Read bytes: 0; Write bytes: 265428992; Read time: 0; Write time: 131012 20:51:09 INFO - Running post-run listener: _upload_blobber_files 20:51:09 INFO - Blob upload gear active. 20:51:09 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 20:51:09 INFO - Copying logs to upload dir... 20:51:09 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1255.532939 ========= master_lag: 0.19 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-01-26 20:51:09.174097) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-26 20:51:09.178886) ========= 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=82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009932 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453860259/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.02 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-26 20:51:09.209539) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 20:51:09.209904) ========= 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=82e07478c745e68b1fe4678a000001bc-1453868150.401135-55852261 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004574 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 20:51:09.259301) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-26 20:51:09.262682) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-26 20:51:09.263014) ========= ========= Total master_lag: 0.43 =========