builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1452034829.19 results: success (0) buildid: 20160105124501 builduid: 2fab31b8de0547ba87792ce9f219ab0b revision: 557dc9dd4dbd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-05 15:00:29.185954) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-05 15:00:29.186439) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-05 15:00:29.186748) ========= 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=c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006145 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-05 15:00:29.210253) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-05 15:00:29.210556) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-05 15:00:29.279507) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 15:00:29.279863) ========= 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=c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004550 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 15:00:29.360911) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-05 15:00:29.361454) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-05 15:00:29.362026) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 15:00:29.362517) ========= 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=c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-05 15:00:29-- 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% 22.7M=0.001s 2016-01-05 15:00:29 (22.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.111680 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 15:00:29.495752) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 15:00:29.496066) ========= 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=c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022091 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 15:00:29.550208) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-05 15:00:29.550589) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 557dc9dd4dbd --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 557dc9dd4dbd --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=c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-05 15:00:29,702 Setting DEBUG logging. 2016-01-05 15:00:29,702 attempt 1/10 2016-01-05 15:00:29,702 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/557dc9dd4dbd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-05 15:00:30,705 unpacking tar archive at: mozilla-beta-557dc9dd4dbd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.295954 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-05 15:00:30.863239) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-05 15:00:30.863566) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-05 15:00:30.874177) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-05 15:00:30.874597) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-05 15:00:30.875053) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-01-05 15:00:30.875350) ========= /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=c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:00:31 INFO - MultiFileLogger online at 20160105 15:00:31 in /builds/slave/test-pgo 15:00:31 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 15:00:31 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 15:00:31 INFO - {'append_to_log': False, 15:00:31 INFO - 'base_work_dir': '/builds/slave/test-pgo', 15:00:31 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:00:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 15:00:31 INFO - 'branch': 'Mozilla-Beta', 15:00:31 INFO - 'buildbot_json_path': 'buildprops.json', 15:00:31 INFO - 'config_files': ('talos/linux_config.py',), 15:00:31 INFO - 'default_actions': ('clobber', 15:00:31 INFO - 'read-buildbot-config', 15:00:31 INFO - 'download-and-extract', 15:00:31 INFO - 'populate-webroot', 15:00:31 INFO - 'create-virtualenv', 15:00:31 INFO - 'install', 15:00:31 INFO - 'run-tests'), 15:00:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:00:31 INFO - 'download_minidump_stackwalk': True, 15:00:31 INFO - 'download_symbols': 'ondemand', 15:00:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:00:31 INFO - 'tooltool.py': '/tools/tooltool.py', 15:00:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:00:31 INFO - '/tools/misc-python/virtualenv.py')}, 15:00:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:00:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:00:31 INFO - 'installer_path': 'installer.exe', 15:00:31 INFO - 'log_level': 'info', 15:00:31 INFO - 'log_name': 'talos', 15:00:31 INFO - 'log_to_console': True, 15:00:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:00:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:00:31 INFO - 'opt_config_files': (), 15:00:31 INFO - 'pip_index': False, 15:00:31 INFO - 'sps_profile': False, 15:00:31 INFO - 'sps_profile_interval': 0, 15:00:31 INFO - 'suite': 'dromaeojs', 15:00:31 INFO - 'system_bits': '32', 15:00:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:00:31 INFO - 'title': 'talos-linux64-ix-004', 15:00:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:00:31 INFO - 'use_talos_json': True, 15:00:31 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 15:00:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:00:31 INFO - 'work_dir': 'build'} 15:00:31 INFO - ##### 15:00:31 INFO - ##### Running clobber step. 15:00:31 INFO - ##### 15:00:31 INFO - Running pre-action listener: _resource_record_pre_action 15:00:31 INFO - Running main action method: clobber 15:00:31 INFO - rmtree: /builds/slave/test-pgo/build 15:00:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 15:00:32 INFO - Running post-action listener: _resource_record_post_action 15:00:32 INFO - ##### 15:00:32 INFO - ##### Running read-buildbot-config step. 15:00:32 INFO - ##### 15:00:32 INFO - Running pre-action listener: _resource_record_pre_action 15:00:32 INFO - Running main action method: read_buildbot_config 15:00:32 INFO - Using buildbot properties: 15:00:32 INFO - { 15:00:32 INFO - "properties": { 15:00:32 INFO - "buildnumber": 64, 15:00:32 INFO - "product": "firefox", 15:00:32 INFO - "script_repo_revision": "production", 15:00:32 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 15:00:32 INFO - "repository": "", 15:00:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 15:00:32 INFO - "buildid": "20160105124501", 15:00:32 INFO - "slavename": "talos-linux64-ix-004", 15:00:32 INFO - "pgo_build": "True", 15:00:32 INFO - "basedir": "/builds/slave/test-pgo", 15:00:32 INFO - "project": "", 15:00:32 INFO - "platform": "ubuntu64_hw", 15:00:32 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:00:32 INFO - "slavebuilddir": "test", 15:00:32 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 15:00:32 INFO - "branch": "mozilla-beta", 15:00:32 INFO - "repo_path": "releases/mozilla-beta", 15:00:32 INFO - "stage_platform": "linux64-pgo", 15:00:32 INFO - "builduid": "2fab31b8de0547ba87792ce9f219ab0b", 15:00:32 INFO - "revision": "557dc9dd4dbd" 15:00:32 INFO - }, 15:00:32 INFO - "sourcestamp": { 15:00:32 INFO - "repository": "", 15:00:32 INFO - "hasPatch": false, 15:00:32 INFO - "project": "", 15:00:32 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 15:00:32 INFO - "changes": [ 15:00:32 INFO - { 15:00:32 INFO - "category": null, 15:00:32 INFO - "files": [ 15:00:32 INFO - { 15:00:32 INFO - "url": null, 15:00:32 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/firefox-44.0.en-US.linux-x86_64.tar.bz2" 15:00:32 INFO - } 15:00:32 INFO - ], 15:00:32 INFO - "repository": "", 15:00:32 INFO - "rev": "557dc9dd4dbd", 15:00:32 INFO - "who": "sendchange", 15:00:32 INFO - "when": 1452034788, 15:00:32 INFO - "number": 7420580, 15:00:32 INFO - "comments": "Bug 1236651 - don_t log complete FxA webchannel messages by default. r=nalexander a=sylvestre", 15:00:32 INFO - "project": "", 15:00:32 INFO - "at": "Tue 05 Jan 2016 14:59:48", 15:00:32 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 15:00:32 INFO - "revlink": "", 15:00:32 INFO - "properties": [ 15:00:32 INFO - [ 15:00:32 INFO - "buildid", 15:00:32 INFO - "20160105124501", 15:00:32 INFO - "Change" 15:00:32 INFO - ], 15:00:32 INFO - [ 15:00:32 INFO - "builduid", 15:00:32 INFO - "2fab31b8de0547ba87792ce9f219ab0b", 15:00:32 INFO - "Change" 15:00:32 INFO - ], 15:00:32 INFO - [ 15:00:32 INFO - "pgo_build", 15:00:32 INFO - "True", 15:00:32 INFO - "Change" 15:00:32 INFO - ] 15:00:32 INFO - ], 15:00:32 INFO - "revision": "557dc9dd4dbd" 15:00:32 INFO - } 15:00:32 INFO - ], 15:00:32 INFO - "revision": "557dc9dd4dbd" 15:00:32 INFO - } 15:00:32 INFO - } 15:00:32 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/firefox-44.0.en-US.linux-x86_64.tar.bz2. 15:00:32 INFO - Running post-action listener: _resource_record_post_action 15:00:32 INFO - ##### 15:00:32 INFO - ##### Running download-and-extract step. 15:00:32 INFO - ##### 15:00:32 INFO - Running pre-action listener: _resource_record_pre_action 15:00:32 INFO - Running main action method: download_and_extract 15:00:32 INFO - mkdir: /builds/slave/test-pgo/build/tests 15:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:00:32 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/test_packages.json 15:00:32 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 15:00:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 15:00:32 INFO - Downloaded 1270 bytes. 15:00:32 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 15:00:32 INFO - Using the following test package requirements: 15:00:32 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 15:00:32 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:00:32 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 15:00:32 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:00:32 INFO - u'jsshell-linux-x86_64.zip'], 15:00:32 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:00:32 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 15:00:32 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 15:00:32 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:00:32 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 15:00:32 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:00:32 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 15:00:32 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:00:32 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 15:00:32 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 15:00:32 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:00:32 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 15:00:32 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:00:32 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:00:32 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:00:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:00:34 INFO - Downloaded 22572188 bytes. 15:00:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 15:00:34 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:00:34 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 15:00:35 INFO - Return code: 0 15:00:35 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:00:35 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')]} 15:00:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:00:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:00:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:00:36 INFO - Downloaded 22572188 bytes. 15:00:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 15:00:36 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:00:36 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 15:00:37 INFO - Return code: 0 15:00:37 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')]} 15:00:37 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 15:00:37 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/firefox-44.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 15:00:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/firefox-44.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:00:40 INFO - Downloaded 11131970 bytes. 15:00:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 15:00:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 15:00:40 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 15:00:40 INFO - Return code: 0 15:00: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')]} 15:00:40 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/firefox-44.0.en-US.linux-x86_64.tar.bz2 15:00:40 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/firefox-44.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 15:00:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/firefox-44.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:00:50 INFO - Downloaded 56628047 bytes. 15:00:50 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/firefox-44.0.en-US.linux-x86_64.tar.bz2 15:00:50 INFO - mkdir: /builds/slave/test-pgo/properties 15:00:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 15:00:50 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 15:00:50 INFO - Contents: 15:00:50 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/firefox-44.0.en-US.linux-x86_64.tar.bz2 15:00:50 INFO - Running post-action listener: _resource_record_post_action 15:00:50 INFO - Running post-action listener: set_extra_try_arguments 15:00:50 INFO - ##### 15:00:50 INFO - ##### Running populate-webroot step. 15:00:50 INFO - ##### 15:00:50 INFO - Running pre-action listener: _resource_record_pre_action 15:00:50 INFO - Running main action method: populate_webroot 15:00:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:00:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:00:50 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 15:00:50 INFO - '../fennec_ids.txt'], 15:00:50 INFO - 'tests': ['tcheck2']}, 15:00:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:00:50 INFO - '--tppagecycles', 15:00:50 INFO - '7'], 15:00:50 INFO - 'tests': ['tsvgm']}}, 15:00:50 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 15:00:50 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 15:00:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:00:50 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:00:50 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 15:00:50 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:00:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:00:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:00:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:00:50 INFO - 'talos_options': ['--e10s'], 15:00:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:00:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:00:50 INFO - 'tests': ['damp', 'tps']}, 15:00:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:00:50 INFO - 'talos_options': ['--e10s'], 15:00:50 INFO - 'tests': ['damp', 'tps']}, 15:00:50 INFO - 'g3': {'tests': ['dromaeo_dom']}, 15:00:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:00:50 INFO - 'other': {'tests': ['a11yr', 15:00:50 INFO - 'ts_paint', 15:00:50 INFO - 'tpaint', 15:00:50 INFO - 'sessionrestore', 15:00:50 INFO - 'sessionrestore_no_auto_restore']}, 15:00:50 INFO - 'other-e10s': {'talos_options': ['--e10s'], 15:00:50 INFO - 'tests': ['a11yr', 15:00:50 INFO - 'ts_paint', 15:00:50 INFO - 'tpaint', 15:00:50 INFO - 'sessionrestore', 15:00:50 INFO - 'sessionrestore_no_auto_restore']}, 15:00:50 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 15:00:50 INFO - 'tests': ['a11yr', 15:00:50 INFO - 'ts_paint', 15:00:50 INFO - 'tpaint', 15:00:50 INFO - 'sessionrestore', 15:00:50 INFO - 'sessionrestore_no_auto_restore']}, 15:00:50 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 15:00:50 INFO - 'tests': ['a11yr', 15:00:50 INFO - 'ts_paint', 15:00:50 INFO - 'tpaint', 15:00:50 INFO - 'sessionrestore', 15:00:50 INFO - 'sessionrestore_no_auto_restore']}, 15:00:50 INFO - 'other_l64': {'tests': ['a11yr', 15:00:50 INFO - 'ts_paint', 15:00:50 INFO - 'tpaint', 15:00:50 INFO - 'sessionrestore', 15:00:50 INFO - 'sessionrestore_no_auto_restore']}, 15:00:50 INFO - 'other_nol64': {'tests': ['a11yr', 15:00:50 INFO - 'ts_paint', 15:00:50 INFO - 'tpaint', 15:00:50 INFO - 'sessionrestore', 15:00:50 INFO - 'sessionrestore_no_auto_restore']}, 15:00:50 INFO - 'svgr': {'tests': ['tsvgx', 15:00:50 INFO - 'tsvgr_opacity', 15:00:50 INFO - 'tart', 15:00:50 INFO - 'tscrollx', 15:00:50 INFO - 'cart']}, 15:00:50 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 15:00:50 INFO - 'tests': ['tsvgx', 15:00:50 INFO - 'tsvgr_opacity', 15:00:50 INFO - 'tart', 15:00:50 INFO - 'tscrollx', 15:00:50 INFO - 'cart']}, 15:00:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:00:50 INFO - 'tests': ['tp5o']}, 15:00:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:00:50 INFO - 'talos_options': ['--e10s'], 15:00:50 INFO - 'tests': ['tp5o']}, 15:00:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:00:50 INFO - 'talos_options': ['--xperf_path', 15:00:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:00:50 INFO - 'tests': ['tp5n']}, 15:00:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:00:50 INFO - 'talos_options': ['--e10s', 15:00:50 INFO - '--xperf_path', 15:00:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:00:50 INFO - 'tests': ['tp5n']}}, 15:00:50 INFO - 'talos.zip': {'path': '', 15:00:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:00:50 INFO - Running post-action listener: _resource_record_post_action 15:00:50 INFO - ##### 15:00:50 INFO - ##### Running create-virtualenv step. 15:00:50 INFO - ##### 15:00:50 INFO - Running pre-action listener: _resource_record_pre_action 15:00:50 INFO - Running main action method: create_virtualenv 15:00:50 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 15:00:50 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 15:00:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 15:00:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:00:50 INFO - Using real prefix '/usr' 15:00:50 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 15:00:50 INFO - Installing distribute.............................................................................................................................................................................................done. 15:00:52 INFO - Installing pip.................done. 15:00:52 INFO - Return code: 0 15:00:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 15:00:52 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')]} 15:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:52 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')]} 15:00:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefbb860f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14eb600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157a350>, '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 0x157b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157b6e0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010', '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 15:00:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 15:00:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:00:52 INFO - Using env: {'DISPLAY': ':0', 15:00:52 INFO - 'HOME': '/home/cltbld', 15:00:52 INFO - 'LANG': 'en_US.UTF-8', 15:00:52 INFO - 'LANGUAGE': 'en_US:en', 15:00:52 INFO - 'LOGNAME': 'cltbld', 15:00:52 INFO - 'MAIL': '/var/mail/cltbld', 15:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:52 INFO - 'MOZ_NO_REMOTE': '1', 15:00:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:00:52 INFO - 'NO_EM_RESTART': '1', 15:00:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:00:52 INFO - 'PWD': '/builds/slave/test-pgo', 15:00:52 INFO - 'SHELL': '/bin/bash', 15:00:52 INFO - 'SHLVL': '1', 15:00:52 INFO - 'TERM': 'linux', 15:00:52 INFO - 'TMOUT': '86400', 15:00:52 INFO - 'USER': 'cltbld', 15:00:52 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010', 15:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:52 INFO - '_': '/tools/buildbot/bin/python'} 15:00:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:52 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 15:00:52 INFO - Cleaning up... 15:00:52 INFO - Return code: 0 15:00:52 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 15:00:52 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')]} 15:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:52 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')]} 15:00:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefbb860f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14eb600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157a350>, '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 0x157b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157b6e0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010', '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 15:00:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 15:00:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 15:00:52 INFO - Using env: {'DISPLAY': ':0', 15:00:52 INFO - 'HOME': '/home/cltbld', 15:00:52 INFO - 'LANG': 'en_US.UTF-8', 15:00:52 INFO - 'LANGUAGE': 'en_US:en', 15:00:52 INFO - 'LOGNAME': 'cltbld', 15:00:52 INFO - 'MAIL': '/var/mail/cltbld', 15:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:52 INFO - 'MOZ_NO_REMOTE': '1', 15:00:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:00:52 INFO - 'NO_EM_RESTART': '1', 15:00:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:00:52 INFO - 'PWD': '/builds/slave/test-pgo', 15:00:52 INFO - 'SHELL': '/bin/bash', 15:00:52 INFO - 'SHLVL': '1', 15:00:52 INFO - 'TERM': 'linux', 15:00:52 INFO - 'TMOUT': '86400', 15:00:52 INFO - 'USER': 'cltbld', 15:00:52 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010', 15:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:52 INFO - '_': '/tools/buildbot/bin/python'} 15:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:53 INFO - Downloading/unpacking psutil>=0.7.1 15:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:54 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 15:00:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 15:00:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 15:00:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:00:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:00:55 INFO - Installing collected packages: psutil 15:00:55 INFO - Running setup.py install for psutil 15:00:55 INFO - building 'psutil._psutil_linux' extension 15:00:55 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 15:00:55 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 15:00:55 INFO - building 'psutil._psutil_posix' extension 15:00:55 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 15:00:55 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 15:00:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:00:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:00:55 INFO - Successfully installed psutil 15:00:55 INFO - Cleaning up... 15:00:55 INFO - Return code: 0 15:00:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 15:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:00:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:55 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fefbb860f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14eb600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157a350>, '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 0x157b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157b6e0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010', '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 15:00:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 15:00:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 15:00:55 INFO - Using env: {'DISPLAY': ':0', 15:00:55 INFO - 'HOME': '/home/cltbld', 15:00:55 INFO - 'LANG': 'en_US.UTF-8', 15:00:55 INFO - 'LANGUAGE': 'en_US:en', 15:00:55 INFO - 'LOGNAME': 'cltbld', 15:00:55 INFO - 'MAIL': '/var/mail/cltbld', 15:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:55 INFO - 'MOZ_NO_REMOTE': '1', 15:00:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:00:55 INFO - 'NO_EM_RESTART': '1', 15:00:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:00:55 INFO - 'PWD': '/builds/slave/test-pgo', 15:00:55 INFO - 'SHELL': '/bin/bash', 15:00:55 INFO - 'SHLVL': '1', 15:00:55 INFO - 'TERM': 'linux', 15:00:55 INFO - 'TMOUT': '86400', 15:00:55 INFO - 'USER': 'cltbld', 15:00:55 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010', 15:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:55 INFO - '_': '/tools/buildbot/bin/python'} 15:00:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:55 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:00:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 15:00:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:00:57 INFO - Installing collected packages: mozsystemmonitor 15:00:57 INFO - Running setup.py install for mozsystemmonitor 15:00:57 INFO - Successfully installed mozsystemmonitor 15:00:57 INFO - Cleaning up... 15:00:57 INFO - Return code: 0 15:00:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 15:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:00:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefbb860f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14eb600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157a350>, '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 0x157b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157b6e0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010', '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 15:00:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 15:00:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:00:57 INFO - Using env: {'DISPLAY': ':0', 15:00:57 INFO - 'HOME': '/home/cltbld', 15:00:57 INFO - 'LANG': 'en_US.UTF-8', 15:00:57 INFO - 'LANGUAGE': 'en_US:en', 15:00:57 INFO - 'LOGNAME': 'cltbld', 15:00:57 INFO - 'MAIL': '/var/mail/cltbld', 15:00:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:57 INFO - 'MOZ_NO_REMOTE': '1', 15:00:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:00:57 INFO - 'NO_EM_RESTART': '1', 15:00:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:00:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:00:57 INFO - 'PWD': '/builds/slave/test-pgo', 15:00:57 INFO - 'SHELL': '/bin/bash', 15:00:57 INFO - 'SHLVL': '1', 15:00:57 INFO - 'TERM': 'linux', 15:00:57 INFO - 'TMOUT': '86400', 15:00:57 INFO - 'USER': 'cltbld', 15:00:57 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010', 15:00:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:57 INFO - '_': '/tools/buildbot/bin/python'} 15:00:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:57 INFO - Downloading/unpacking blobuploader==1.2.4 15:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:59 INFO - Downloading blobuploader-1.2.4.tar.gz 15:00:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 15:00:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:00:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 15:00:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 15:01:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:00 INFO - Downloading docopt-0.6.1.tar.gz 15:01:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 15:01:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 15:01:00 INFO - Installing collected packages: blobuploader, requests, docopt 15:01:00 INFO - Running setup.py install for blobuploader 15:01:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:01:00 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 15:01:00 INFO - Running setup.py install for requests 15:01:00 INFO - Running setup.py install for docopt 15:01:00 INFO - Successfully installed blobuploader requests docopt 15:01:00 INFO - Cleaning up... 15:01:00 INFO - Return code: 0 15:01:00 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:01: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')]} 15:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01: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')]} 15:01:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:00 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefbb860f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14eb600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157a350>, '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 0x157b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157b6e0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010', '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 15:01:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 15:01:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:01:00 INFO - Using env: {'DISPLAY': ':0', 15:01:00 INFO - 'HOME': '/home/cltbld', 15:01:00 INFO - 'LANG': 'en_US.UTF-8', 15:01:00 INFO - 'LANGUAGE': 'en_US:en', 15:01:00 INFO - 'LOGNAME': 'cltbld', 15:01:00 INFO - 'MAIL': '/var/mail/cltbld', 15:01:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:00 INFO - 'MOZ_NO_REMOTE': '1', 15:01:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:01:00 INFO - 'NO_EM_RESTART': '1', 15:01:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:01:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:01:00 INFO - 'PWD': '/builds/slave/test-pgo', 15:01:00 INFO - 'SHELL': '/bin/bash', 15:01:00 INFO - 'SHLVL': '1', 15:01:00 INFO - 'TERM': 'linux', 15:01:00 INFO - 'TMOUT': '86400', 15:01:00 INFO - 'USER': 'cltbld', 15:01:00 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010', 15:01:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:00 INFO - '_': '/tools/buildbot/bin/python'} 15:01:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:01:01 INFO - Running setup.py (path:/tmp/pip-F9w4At-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:01:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:01:01 INFO - Running setup.py (path:/tmp/pip-vvdIKP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:01:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:01:01 INFO - Running setup.py (path:/tmp/pip-dcXJnQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:01:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:01:01 INFO - Running setup.py (path:/tmp/pip-SqKs7Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:01:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:01:01 INFO - Running setup.py (path:/tmp/pip-0f6Iou-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:01:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:01:01 INFO - Running setup.py (path:/tmp/pip-FYNR5n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:01:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:01:01 INFO - Running setup.py (path:/tmp/pip-tvt1Xr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:01:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:01:01 INFO - Running setup.py (path:/tmp/pip-IaPMHI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:01:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:01:01 INFO - Running setup.py (path:/tmp/pip-mLtQMj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:01:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:01:01 INFO - Running setup.py (path:/tmp/pip-WHcmTi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:01:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:01:01 INFO - Running setup.py (path:/tmp/pip-pfA43L-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:01:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:01:01 INFO - Running setup.py (path:/tmp/pip-3yYEBe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:01:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:01:01 INFO - Running setup.py (path:/tmp/pip-N3FkL6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:01:02 INFO - Running setup.py (path:/tmp/pip-qq074p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:01:02 INFO - Running setup.py (path:/tmp/pip-1mia1Y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:01:02 INFO - Running setup.py (path:/tmp/pip-nSvdxr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:01:02 INFO - Running setup.py (path:/tmp/pip-VruSEQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:01:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:01:02 INFO - Running setup.py install for manifestparser 15:01:02 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 15:01:02 INFO - Running setup.py install for mozcrash 15:01:02 INFO - Running setup.py install for mozdebug 15:01:02 INFO - Running setup.py install for mozdevice 15:01:02 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 15:01:02 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 15:01:02 INFO - Running setup.py install for mozfile 15:01:02 INFO - Running setup.py install for mozhttpd 15:01:02 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 15:01:02 INFO - Running setup.py install for mozinfo 15:01:03 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 15:01:03 INFO - Running setup.py install for mozInstall 15:01:03 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 15:01:03 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 15:01:03 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 15:01:03 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 15:01:03 INFO - Running setup.py install for mozleak 15:01:03 INFO - Running setup.py install for mozlog 15:01:03 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 15:01:03 INFO - Running setup.py install for moznetwork 15:01:03 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 15:01:03 INFO - Running setup.py install for mozprocess 15:01:03 INFO - Running setup.py install for mozprofile 15:01:03 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 15:01:03 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 15:01:03 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 15:01:03 INFO - Running setup.py install for mozrunner 15:01:03 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 15:01:03 INFO - Running setup.py install for mozscreenshot 15:01:03 INFO - Running setup.py install for moztest 15:01:03 INFO - Running setup.py install for mozversion 15:01:04 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 15:01:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:01:04 INFO - Cleaning up... 15:01:04 INFO - Return code: 0 15:01:04 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:04 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7fefbb860f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14eb600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157a350>, '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 0x157b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157b6e0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010', '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 15:01:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 15:01:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:01:04 INFO - Using env: {'DISPLAY': ':0', 15:01:04 INFO - 'HOME': '/home/cltbld', 15:01:04 INFO - 'LANG': 'en_US.UTF-8', 15:01:04 INFO - 'LANGUAGE': 'en_US:en', 15:01:04 INFO - 'LOGNAME': 'cltbld', 15:01:04 INFO - 'MAIL': '/var/mail/cltbld', 15:01:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:04 INFO - 'MOZ_NO_REMOTE': '1', 15:01:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:01:04 INFO - 'NO_EM_RESTART': '1', 15:01:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:01:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:01:04 INFO - 'PWD': '/builds/slave/test-pgo', 15:01:04 INFO - 'SHELL': '/bin/bash', 15:01:04 INFO - 'SHLVL': '1', 15:01:04 INFO - 'TERM': 'linux', 15:01:04 INFO - 'TMOUT': '86400', 15:01:04 INFO - 'USER': 'cltbld', 15:01:04 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010', 15:01:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:04 INFO - '_': '/tools/buildbot/bin/python'} 15:01:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:01:04 INFO - Running setup.py (path:/tmp/pip-3RAjnZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:01:04 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)) 15:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:01:04 INFO - Running setup.py (path:/tmp/pip-6R7jDo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:01:04 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)) 15:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:01:04 INFO - Running setup.py (path:/tmp/pip-h8EWif-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:01:04 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)) 15:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:01:04 INFO - Running setup.py (path:/tmp/pip-ayE9ZM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:01:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 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)) 15:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:01:04 INFO - Running setup.py (path:/tmp/pip-A89d6n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:01:04 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)) 15:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:01:04 INFO - Running setup.py (path:/tmp/pip-uECtJD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:01:04 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)) 15:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:01:04 INFO - Running setup.py (path:/tmp/pip-SGKrY6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:01:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 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)) 15:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:01:04 INFO - Running setup.py (path:/tmp/pip-yM8zGv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:01:04 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)) 15:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:01:04 INFO - Running setup.py (path:/tmp/pip-s56j6C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:01:04 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)) 15:01:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:01:04 INFO - Running setup.py (path:/tmp/pip-mao6z3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 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)) 15:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:01:05 INFO - Running setup.py (path:/tmp/pip-Zhr_Pw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:01:05 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)) 15:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:01:05 INFO - Running setup.py (path:/tmp/pip-K05y6E-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:01:05 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)) 15:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:01:05 INFO - Running setup.py (path:/tmp/pip-I0EKaD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:01:05 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)) 15:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:01:05 INFO - Running setup.py (path:/tmp/pip-2ipKLZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:01:05 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)) 15:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:01:05 INFO - Running setup.py (path:/tmp/pip-fcmZxQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:01:05 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)) 15:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:01:05 INFO - Running setup.py (path:/tmp/pip-aiStg4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:01:05 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)) 15:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:01:05 INFO - Running setup.py (path:/tmp/pip-rziUcs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:01:05 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)) 15:01:05 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)) 15:01:05 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)) 15:01:05 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)) 15:01:05 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.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 15:01:05 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.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 15:01:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 15:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:07 INFO - Downloading blessings-1.5.1.tar.gz 15:01:07 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 15:01:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 15:01:07 INFO - Installing collected packages: blessings 15:01:07 INFO - Running setup.py install for blessings 15:01:07 INFO - Successfully installed blessings 15:01:07 INFO - Cleaning up... 15:01:07 INFO - Return code: 0 15:01:07 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 15:01:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:01:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:01:07 INFO - Reading from file tmpfile_stdout 15:01:07 INFO - Current package versions: 15:01:07 INFO - argparse == 1.2.1 15:01:07 INFO - blessings == 1.5.1 15:01:07 INFO - blobuploader == 1.2.4 15:01:07 INFO - docopt == 0.6.1 15:01:07 INFO - manifestparser == 1.1 15:01:07 INFO - mozInstall == 1.12 15:01:07 INFO - mozcrash == 0.16 15:01:07 INFO - mozdebug == 0.1 15:01:07 INFO - mozdevice == 0.46 15:01:07 INFO - mozfile == 1.2 15:01:07 INFO - mozhttpd == 0.7 15:01:07 INFO - mozinfo == 0.8 15:01:07 INFO - mozleak == 0.1 15:01:07 INFO - mozlog == 3.0 15:01:07 INFO - moznetwork == 0.27 15:01:07 INFO - mozprocess == 0.22 15:01:07 INFO - mozprofile == 0.27 15:01:07 INFO - mozrunner == 6.11 15:01:07 INFO - mozscreenshot == 0.1 15:01:07 INFO - mozsystemmonitor == 0.0 15:01:07 INFO - moztest == 0.7 15:01:07 INFO - mozversion == 1.4 15:01:07 INFO - psutil == 3.1.1 15:01:07 INFO - requests == 1.2.3 15:01:07 INFO - wsgiref == 0.1.2 15:01:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefbb860f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14eb600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157a350>, '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 0x157b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157b6e0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010', '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 15:01:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 15:01:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:01:07 INFO - Using env: {'DISPLAY': ':0', 15:01:07 INFO - 'HOME': '/home/cltbld', 15:01:07 INFO - 'LANG': 'en_US.UTF-8', 15:01:07 INFO - 'LANGUAGE': 'en_US:en', 15:01:07 INFO - 'LOGNAME': 'cltbld', 15:01:07 INFO - 'MAIL': '/var/mail/cltbld', 15:01:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:07 INFO - 'MOZ_NO_REMOTE': '1', 15:01:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:01:07 INFO - 'NO_EM_RESTART': '1', 15:01:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:01:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:01:07 INFO - 'PWD': '/builds/slave/test-pgo', 15:01:07 INFO - 'SHELL': '/bin/bash', 15:01:07 INFO - 'SHLVL': '1', 15:01:07 INFO - 'TERM': 'linux', 15:01:07 INFO - 'TMOUT': '86400', 15:01:07 INFO - 'USER': 'cltbld', 15:01:07 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010', 15:01:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:07 INFO - '_': '/tools/buildbot/bin/python'} 15:01:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:07 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)) 15:01:07 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)) 15:01:07 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)) 15:01:07 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)) 15:01:07 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)) 15:01:07 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)) 15:01:07 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)) 15:01:07 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)) 15:01:07 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)) 15:01:07 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)) 15:01:07 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)) 15:01:07 INFO - Cleaning up... 15:01:07 INFO - Return code: 0 15:01:07 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 15:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefbb860f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14eb600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157a350>, '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 0x157b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157b6e0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010', '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 15:01:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 15:01:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:01:07 INFO - Using env: {'DISPLAY': ':0', 15:01:07 INFO - 'HOME': '/home/cltbld', 15:01:07 INFO - 'LANG': 'en_US.UTF-8', 15:01:07 INFO - 'LANGUAGE': 'en_US:en', 15:01:07 INFO - 'LOGNAME': 'cltbld', 15:01:07 INFO - 'MAIL': '/var/mail/cltbld', 15:01:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:07 INFO - 'MOZ_NO_REMOTE': '1', 15:01:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:01:07 INFO - 'NO_EM_RESTART': '1', 15:01:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:01:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:01:07 INFO - 'PWD': '/builds/slave/test-pgo', 15:01:07 INFO - 'SHELL': '/bin/bash', 15:01:07 INFO - 'SHLVL': '1', 15:01:07 INFO - 'TERM': 'linux', 15:01:07 INFO - 'TMOUT': '86400', 15:01:07 INFO - 'USER': 'cltbld', 15:01:07 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010', 15:01:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:07 INFO - '_': '/tools/buildbot/bin/python'} 15:01:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:08 INFO - Downloading/unpacking jsonschema 15:01:08 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 15:01:08 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 15:01:08 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 15:01:08 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 15:01:08 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 15:01:08 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 15:01:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:01:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 15:01:09 INFO - Downloading/unpacking functools32 (from jsonschema) 15:01:09 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 15:01:09 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 15:01:09 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 15:01:09 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 15:01:09 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 15:01:09 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 15:01:10 INFO - Downloading functools32-3.2.3-2.tar.gz 15:01:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 15:01:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 15:01:10 INFO - warning: no files found matching '*.txt' 15:01:10 INFO - no previously-included directories found matching 'build' 15:01:10 INFO - no previously-included directories found matching 'dist' 15:01:10 INFO - no previously-included directories found matching '.git*' 15:01:10 INFO - Installing collected packages: jsonschema, functools32 15:01:10 INFO - Running setup.py install for functools32 15:01:11 INFO - warning: no files found matching '*.txt' 15:01:11 INFO - no previously-included directories found matching 'build' 15:01:11 INFO - no previously-included directories found matching 'dist' 15:01:11 INFO - no previously-included directories found matching '.git*' 15:01:11 INFO - Successfully installed jsonschema functools32 15:01:11 INFO - Cleaning up... 15:01:11 INFO - Return code: 0 15:01:11 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 15:01:11 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')]} 15:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:11 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')]} 15:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:11 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefbb860f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14eb600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157a350>, '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 0x157b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157b6e0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010', '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 15:01:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 15:01:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:01:11 INFO - Using env: {'DISPLAY': ':0', 15:01:11 INFO - 'HOME': '/home/cltbld', 15:01:11 INFO - 'LANG': 'en_US.UTF-8', 15:01:11 INFO - 'LANGUAGE': 'en_US:en', 15:01:11 INFO - 'LOGNAME': 'cltbld', 15:01:11 INFO - 'MAIL': '/var/mail/cltbld', 15:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:11 INFO - 'MOZ_NO_REMOTE': '1', 15:01:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:01:11 INFO - 'NO_EM_RESTART': '1', 15:01:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:01:11 INFO - 'PWD': '/builds/slave/test-pgo', 15:01:11 INFO - 'SHELL': '/bin/bash', 15:01:11 INFO - 'SHLVL': '1', 15:01:11 INFO - 'TERM': 'linux', 15:01:11 INFO - 'TMOUT': '86400', 15:01:11 INFO - 'USER': 'cltbld', 15:01:11 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010', 15:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:11 INFO - '_': '/tools/buildbot/bin/python'} 15:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:11 INFO - Downloading/unpacking flake8 15:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:01:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 15:01:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:01:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 15:01:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 15:01:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 15:01:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 15:01:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:01:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:01:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:01:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:01:13 INFO - no previously-included directories found matching 'docs/_build' 15:01:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:01:13 INFO - Running setup.py install for pep8 15:01:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:01:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:01:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:01:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:01:13 INFO - no previously-included directories found matching 'docs/_build' 15:01:13 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 15:01:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:01:13 INFO - Cleaning up... 15:01:14 INFO - Return code: 0 15:01:14 INFO - Running post-action listener: _resource_record_post_action 15:01:14 INFO - Running post-action listener: _start_resource_monitoring 15:01:14 INFO - Starting resource monitoring. 15:01:14 INFO - ##### 15:01:14 INFO - ##### Running install step. 15:01:14 INFO - ##### 15:01:14 INFO - Running pre-action listener: _resource_record_pre_action 15:01:14 INFO - Running main action method: install 15:01:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:01:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:01:14 INFO - Reading from file tmpfile_stdout 15:01:14 INFO - Detecting whether we're running mozinstall >=1.0... 15:01:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 15:01:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 15:01:14 INFO - Reading from file tmpfile_stdout 15:01:14 INFO - Output received: 15:01:14 INFO - Usage: mozinstall [options] installer 15:01:14 INFO - Options: 15:01:14 INFO - -h, --help show this help message and exit 15:01:14 INFO - -d DEST, --destination=DEST 15:01:14 INFO - Directory to install application into. [default: 15:01:14 INFO - "/builds/slave/test-pgo"] 15:01:14 INFO - --app=APP Application being installed. [default: firefox] 15:01:14 INFO - mkdir: /builds/slave/test-pgo/build/application 15:01:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 15:01:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 15:01:28 INFO - Reading from file tmpfile_stdout 15:01:28 INFO - Output received: 15:01:28 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 15:01:28 INFO - Running post-action listener: _resource_record_post_action 15:01:28 INFO - ##### 15:01:28 INFO - ##### Running run-tests step. 15:01:28 INFO - ##### 15:01:28 INFO - Running pre-action listener: _resource_record_pre_action 15:01:28 INFO - Running main action method: run_tests 15:01:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 15:01:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 15:01:28 INFO - Python 2.7.3 15:01:28 INFO - Return code: 0 15:01:28 INFO - grabbing minidump binary from tooltool 15:01: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')]} 15:01:28 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 0x157a350>, '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 0x157b250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157b6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 15:01:28 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 15:01:28 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 15:01:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:01:28 INFO - Return code: 0 15:01:28 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 15:01:28 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 15:01:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 15:01:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 15:01:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 15:01:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 15:01:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 15:01:29 INFO - Return code: 0 15:01:29 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/1452026701/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 15:01:29 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/1452026701/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --webServer localhost 15:01:29 INFO - Using env: {'DISPLAY': ':0', 15:01:29 INFO - 'HOME': '/home/cltbld', 15:01:29 INFO - 'LANG': 'en_US.UTF-8', 15:01:29 INFO - 'LANGUAGE': 'en_US:en', 15:01:29 INFO - 'LOGNAME': 'cltbld', 15:01:29 INFO - 'MAIL': '/var/mail/cltbld', 15:01:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:01:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 15:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:29 INFO - 'MOZ_NO_REMOTE': '1', 15:01:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:01:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:01:29 INFO - 'NO_EM_RESTART': '1', 15:01:29 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:01:29 INFO - 'PWD': '/builds/slave/test-pgo', 15:01:29 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 15:01:29 INFO - 'SHELL': '/bin/bash', 15:01:29 INFO - 'SHLVL': '1', 15:01:29 INFO - 'TERM': 'linux', 15:01:29 INFO - 'TMOUT': '86400', 15:01:29 INFO - 'USER': 'cltbld', 15:01:29 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010', 15:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:29 INFO - '_': '/tools/buildbot/bin/python'} 15:01:29 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/1452026701/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost'] with output_timeout 3600 15:01:30 INFO - mozversion INFO | application_buildid: 20160105124501 15:01:30 INFO - mozversion INFO | application_changeset: 557dc9dd4dbd4f4de0d0666b830b03c3a235d621 15:01:30 INFO - mozversion INFO | application_display_name: Nightly 15:01:30 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:01:30 INFO - mozversion INFO | application_name: Firefox 15:01:30 INFO - mozversion INFO | application_remotingname: firefox 15:01:30 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:01:30 INFO - mozversion INFO | application_vendor: Mozilla 15:01:30 INFO - mozversion INFO | application_version: 44.0 15:01:30 INFO - mozversion INFO | platform_buildid: 20160105124501 15:01:30 INFO - mozversion INFO | platform_changeset: 557dc9dd4dbd4f4de0d0666b830b03c3a235d621 15:01:30 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:01:30 INFO - mozversion INFO | platform_version: 44.0 15:01:30 INFO - 2016-01-05 15:01:30,019 DEBUG : using testdate: 1452034890 15:01:30 INFO - 2016-01-05 15:01:30,019 DEBUG : actual date: 1452034890 15:01:30 INFO - 2016-01-05 15:01:30,030 INFO : starting webserver on 'localhost:57766' 15:01:30 INFO - 2016-01-05 15:01:30,031 INFO : Starting test suite talos-linux64-ix-004 15:01:30 INFO - 2016-01-05 15:01:30,031 INFO : Starting test dromaeo_css 15:01:30 INFO - 2016-01-05 15:01:30,031 DEBUG : operating with platform_type : linux_ 15:01:30 INFO - 2016-01-05 15:01:30,032 INFO : Initialising browser for dromaeo_css test... 15:01:30 INFO - 2016-01-05 15:01:30,044 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpVKkr_Y/profile http://localhost:57766/getInfo.html 15:01:34 INFO - 2016-01-05 15:01:34,363 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:01:34 INFO - 2016-01-05 15:01:34,364 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:01:34 INFO - 2016-01-05 15:01:34,364 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:01:34 INFO - 2016-01-05 15:01:34,364 DEBUG : BROWSER_OUTPUT: __metrics 15:01:34 INFO - 2016-01-05 15:01:34,586 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] 15:01:35 INFO - 2016-01-05 15:01:35,873 INFO : Browser initialized. 15:01:35 INFO - 2016-01-05 15:01:35,874 INFO : Running cycle 1/1 for dromaeo_css test... 15:01:35 INFO - 2016-01-05 15:01:35,874 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpVKkr_Y/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:01:47 INFO - 2016-01-05 15:01:47,420 DEBUG : BROWSER_OUTPUT: RSS: Main: 128974848 15:01:47 INFO - 2016-01-05 15:01:47,420 DEBUG : BROWSER_OUTPUT: 15:02:38 INFO - 2016-01-05 15:02:38,184 DEBUG : BROWSER_OUTPUT: 1452034958180 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] 15:04:46 INFO - 2016-01-05 15:04:46,217 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57766/tests/dromaeo/cssquery-ext.html) 15:04:47 INFO - 2016-01-05 15:04:47,201 DEBUG : BROWSER_OUTPUT: RSS: Main: 216563712 15:04:47 INFO - 2016-01-05 15:04:47,201 DEBUG : BROWSER_OUTPUT: 15:07:49 INFO - 2016-01-05 15:07:49,189 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/dromaeo/cssquery-ext.html (next: http://localhost:57766/tests/dromaeo/cssquery-jquery.html) 15:07:49 INFO - 2016-01-05 15:07:49,743 DEBUG : BROWSER_OUTPUT: RSS: Main: 207749120 15:07:49 INFO - 2016-01-05 15:07:49,743 DEBUG : BROWSER_OUTPUT: 15:10:51 INFO - 2016-01-05 15:10:51,413 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57766/tests/dromaeo/cssquery-mootools.html) 15:10:51 INFO - 2016-01-05 15:10:51,975 DEBUG : BROWSER_OUTPUT: RSS: Main: 209342464 15:10:51 INFO - 2016-01-05 15:10:51,975 DEBUG : BROWSER_OUTPUT: 15:13:53 INFO - 2016-01-05 15:13:53,679 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57766/tests/dromaeo/cssquery-prototype.html) 15:13:54 INFO - 2016-01-05 15:13:54,248 DEBUG : BROWSER_OUTPUT: RSS: Main: 190451712 15:13:54 INFO - 2016-01-05 15:13:54,248 DEBUG : BROWSER_OUTPUT: 15:16:56 INFO - 2016-01-05 15:16:56,617 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57766/tests/dromaeo/cssquery-yui.html) 15:16:57 INFO - 2016-01-05 15:16:57,180 DEBUG : BROWSER_OUTPUT: RSS: Main: 191885312 15:16:57 INFO - 2016-01-05 15:16:57,180 DEBUG : BROWSER_OUTPUT: 15:19:59 INFO - 2016-01-05 15:19:59,174 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/dromaeo/cssquery-yui.html (next: http://localhost:57766/tests/dromaeo/cssquery-dojo.html) 15:19:59 INFO - 2016-01-05 15:19:59,433 DEBUG : BROWSER_OUTPUT: RSS: Main: 233926656 15:19:59 INFO - 2016-01-05 15:19:59,433 DEBUG : BROWSER_OUTPUT: 15:19:59 INFO - 2016-01-05 15:19:59,433 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:19:59 INFO - 2016-01-05 15:19:59,433 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:19:59 INFO - 2016-01-05 15:19:59,433 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:19:59 INFO - 2016-01-05 15:19:59,433 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:19:59 INFO - 2016-01-05 15:19:59,433 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3220.355928814237;3326.883559075433;3338.315645292715;3355;3370.4438767603347;5436.130219164935;5484.095124304485;5485.0401179793525;5493.395726470085;5494.615376923617;5504.944950550494;5536.667799932007;5544.223808666779;5558.44415558444;5569.303837020372;4845.345878306436;5051.3180720602795;5068.315777370063;5109.386873575176;5119.052975199581;5009.874753131183;5027.648064635477;5047.49525047496;5052.317937078484;5056.064628043824;4958.2562615607585;5030.522100400456;5032.396112466491;5035.194368900977;5067.7719502622285;5072.619553533481;5102.770375333082;5105.004524117761;5121.539061484447;5130.640855140142;5018.723970181639;5021.748912554358;5070.163423035195;5077.619178561623;5090.032893750175;5159.458256883011;5215.921761173586;5268.60485463588;5290.60320475962;5324.866878328034;5889.1460738193155;5924.911126333109;5926.170336152942;5930.258717660293;5937.435943585352;5682.687452190128;5939.049752039653;5953.136795164677;5957.851053723648;5964.642121472682;5460.099083651193;5537.861553461155;5646.378898321223;5647.661140331593;5697.515711164556;6074.787382441594;6083.8479038023515;6091.269047714302;6094.542909281733;6095.786647467317;5647.858803529863;5742.397048309951;5898.174255604219;5915.58590898633;5924.052151655801;5873.58884878063;5959.553033522417;5975.790847320323;5981.222441082631;6008.849778755479;5936.990711579042;5938.346781853993;5948.851278718067;5950.67271300074;5963.314218864799;5745.683987380734;5853.7365818538265;5895.67573783446;5966.791162309299;5971.611845230046;4604.907901841944;4611.123886461562;4611.262198048296;4611.8847028823875;4630.027694184192;4115.9176816463505;4121.340585506305;4131.876043718693;4135.958640413617;4156.937645935313;5402.594805389612;5521.365043020104;5585.1901474286005;5699.145128230715;5732.254806875079;6015.849603759941;6031.8793624127275;6040.154378387029;6048.425399587032;6053.273607167168;5644.8306550803545;5709.25779648643;5727.885442291131;5728.885422291614;5735.426457354315;4995.825146119869;5092.9745351273;5122.948770512322;5211.713355765393;5230.660007099526;6124.693765311717;6129.448349648553;6132.356102609251;6137.570370074052;6142.600730952474;5635.985522605806;5958;5978.8206353809455;5984.102384642339;5986.161937328865;6032.75868965254;6109.144719739151;6234.906476402767;6248;6280.340564240689;5923.111533270044;5935.052064918035;5939.4951429129005;5939.524838013035;5983.850403739767;5688.772449102155;5797.594168408171;5982.611130276518;5998.160257563857;6010;5800.680962546931;5899.46904778572;5900.1149827526215;5906.468417842415;5907.113932909926;5889.411058894248;5905.261842269716;5905.586608937333;5906.202662640489;5906.232189815297;5504.697241651747;5620.241267428844;5631.324241091095;5777.653340799566;5797.4782269595935;5773.422657734193;5776.971115144397;5800.970995144998;5804.158397032491;5867.20792692981;5716.485516303553;5734.340550836707;5736.397678243724;5737.942620573741;5756.827295181151 15:19:59 INFO - 2016-01-05 15:19:59,433 DEBUG : BROWSER_OUTPUT: |1;ext.html;562.212901937288;599.1731410653299;601.4707057789145;603.4538742438092;604.1753007145245;35476.46785298219;44386;45224.54775452244;45255.547444525546;45333.773331133336;48412;48844.267335989985;48893.51106488934;48915.75542122293;49037.26444103341;8166.428350015502;8309.875351869727;9098.954505227464;9272.304577156707;10718.356898586071;10957.452127393639;11013.724656883602;11036.668899932974;11095.611653592126;11106.389148596823;11097.33415995043;11177.049950754195;11214.10287177024;11218.887811121866;11229.719257018558;11021.779564408707;11033.958717637262;11058.778824423467;11130.16523760717;11133.7216569586;10873.075788557982;11005.724856878642;11011.339319640847;11011.944940275247;11012.50443730034;44353.78231088433;44486;44908;45023.32465013027;45027;3601.8019008954498;3729.8321575529167;3761.37937240355;3787.715921305913;4193.433886425354;33302.16744581381;33346.666533334595;34036.14909627279;34049.829750851335;35552.822235888656;3905.863294784669;3923.136909879822;3995.10110225197;4038.1519880825063;4048.0284731664483;340257;341789.2910535456;341984;342891;344480;60900.69549652223;62579.37420625827;75469.24530754732;76923.61538192273;77010;72261.6386918062;72553;72827;73082.24965377233;73287.63356183186;19100;19165.904170479058;19254.22983080689;19268.22927082929;19313.51716207106;13655.180689158682;13672.794908076386;13673.86326136726;13752.449902004008;13799.51701690436;22906.65640015401;23225.419364515685;23312.3006309811;23328.53342933166;23376;8717.607707653171;8931.73204803857;8956.373053886166;8995.865062024075;9103.408278462011;966.8839739231337;1013.8834034086027;1123.9269447486051;1169.473736818435;1211.5214490276292;29727.702722972925;30463.847680761897;30468.238294042825;30539.847300763355;31339.686603133676;1118.5693507999315;1130.796456637813;1221.3587866370049;1249.506444954238;1258.572085490919;13818.309084545732;16130.435434759962;16143.838561614468;16231.541524742202;16586.253618587194;264967;265474;265924;267556;268064;64441.35558644447;64802.351976480575;64911;64972.67513662496;65184.348156518776;1336.7259711759066;1429.5997120806192;1437.022824479364;1463.5389852196524;1485.049568276304;33736.49395259024;33777.83111084429;34000.48999264964;34203;34221.657783422845;56308.43691563196;57375.713121435794;57424.71287643535;57445.42554574568;57478.7126064367;350629;353421;354560;354775;355995;37284;37464;37619.62380376161;37915.81042094772;52056.21915671192;49294.26058609196;49409.25886111783;50085;50575;50623;41487.170256594094;42114.57885421229;42371.364429534195;42952.78523607362;43050;41630.58369416267;41996;42099;42764.572354277305;42945.35581966335;42010;42177.57822421737;42248.36627450652;42966.57033429626;43821;35226.47160292649;35734.02779856442;35985.820070900525;36005.27989440249;36038.8198059008;28052.726421950952;28225.294367640974;28397.620629205256;28505.572416414187;28650 15:19:59 INFO - 2016-01-05 15:19:59,433 DEBUG : BROWSER_OUTPUT: |2;jquery.html;385.7396257526169;388.7881104797886;389.2176724783185;389.39956777643897;392.16468921197844;13885.004195468895;13915.582532524015;18085.276588936442;20453.18187272509;20459.488512787168;13286.867131328683;13724.519641812538;13726.245056521888;18702.345417910376;19322.613547729074;4875.049365373745;4991.475895031024;4994.350734404522;4999.150144475429;4999.675021123633;4927.827526036589;4939.110960027194;4940;4953;4954.529319714621;3821.808909554525;3874.612538746117;4898.191798353268;4899.755012249391;4902.436219834729;5257.421683614799;5339.145736682151;5394.703291318976;5395.109806881879;5395.136778115522;4425.845095421677;4673.392458980345;4674.929876051862;4741.31250968611;4744.335792988984;15471.613209669737;15555.300011499396;15565.844341556665;15835.762463563056;15848.76226856598;2105.336818902041;2173.521825198454;2215.29110684581;2233.3634914049417;2241.2827895073583;11155.107591392669;11171.385573793437;11248;11301.830472542919;11356.602518911799;1801.846843018345;1858.6840237159715;1896.3173257627243;1902.7906930237664;1917.0702209428416;64586;66452.66773666149;67680.6615966922;67975;68523.31476685219;7107.857842843114;7400.888986665204;7582.203868593829;7625.6568454419685;7649.158592554814;9138.725838224864;9192.356535042616;9223.86164207524;9229.400088994194;9249.306302027375;6284.842878927972;6340.714667839959;6362.77730279438;6403.167588213502;6405.327440618761;6767.966160169266;6774.356436138559;6804.863902721918;6813;6842.04211410403;3418.8461519231696;3478.217401084786;3513.4905438711244;3529.7882127072457;3534.823258837048;1268.5179631740064;1273.8089286607083;1287.3691890973544;1296.950817708019;1325.9602211933463;456.64838074682973;474.49466318370827;491.9237518184687;498.12827551784375;541.0531569752933;12694.682632934066;12714.87285127137;13136.40886160125;13158.539451119164;13213.933930330288;468.42149944818124;521.2155705663029;526.8314139475331;532.421370636066;574.8821491594213;7021;7113.64431778409;7177.856442871113;7205.89191162133;7207.639618019078;434976;435394;437507;439349;615078;72437;75285;78348.60825695835;79727.60136199281;83191;556.9025420551387;569.2201683693366;569.2770181869085;571.325835514089;573.0315766354847;11502.137339699724;11573.305601663928;11676.649700508999;11751.823722644338;11858.169928105295;35385;35887.64112358843;35919.46120808242;36176;36547.63452365442;50445.49554504408;50599.241011384125;50729;50954;52101.7394913023;7780.299773020455;7838.098618658699;7845.097813751491;7849.5682737447705;7919.049714034353;13014;13211.537596184087;14117.576472705836;14133.434662613381;14245.928770356082;11749.060075194135;11916.344601047025;11928.343941082976;12158.209716368408;12646.177998430072;9731.318807683394;10735.89264107349;11291.43542822872;11509.136814739095;11790.351530665896;11502.53989840397;11595.710107247387;11721.296722196696;11813.822792657946;12014.159008869296;7395.260473952561;7412.518186317872;7447.404207663266;7451.701931922879;7451.850962980819;11496.195266331277;11578.536858525564;11626.825597615873;12005.63983080509;12103.818442723543 15:19:59 INFO - 2016-01-05 15:19:59,433 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1568.929398177082;1601.847824456676;1620.570548804567;1807.3583877723408;1910.560571068655;7100.112485939258;7221.133463984316;7888.960555197224;7999.600019999006;8004.83990320194;7830.41271904608;7946.721864734735;8022.077461091962;8034.477758945664;8035.517868927854;2406.7833894949495;2428.550718117145;2432.355425812152;2437.5124975004983;2438.2563318187945;2082.4793801549613;2130.893455327235;2392.6530653055297;2456.091246238894;2481.4292712676097;2371.288613415977;2413.9155129570468;2415.10641062807;2417.359399759065;2417.456072383717;2410.9638355424595;2451.497443024176;2460.7539246075517;2461.113998960373;2462.630605409185;2399.124319623342;2411.1440438644204;2424.090965887792;2427.1990243219693;2431.7689819467123;8164.510129392196;8224.83550328996;8235.217654322889;8240.917590824074;8244.134365891556;5098.745062746885;5173.379194496647;5182.714950677711;5185.066687996159;5207.9479205207845;3213.453712868818;3213.5661685672367;3232.5474433579316;3241.205904553376;3259.788113772621;1166.9591564295208;1179.5812486567318;1188.114854433444;1195.3784032303154;1203.9157258991875;10796.622118225903;10835.620753273677;10866;10870.836937445945;10880.836787448194;4507.88730281739;4533.365328853963;4554.726716397026;4579.152856721517;4589.839355622604;9453.952730236304;9476.384035037838;9486.715398538056;9523.809523809485;9538.189253913357;4916.336294600254;4920.581750551171;4923.064617722621;4926.630502712311;4926.6551341406475;4450.287953927421;4500.594946454835;4500.909981800346;4518.209313370147;4531.864044078683;3650.543682039745;3985.80070996449;4020.3969404589548;4041.818118184689;4045.8179381927885;1223.1560223445795;1275.5280546197966;1311.4491913396396;1317.960461186166;1321.5969129415428;1262.4003598290738;1319.4524272427027;1321.6761893336002;1381.647679841634;1456.3300881594373;7389.224131466226;7440.330370266702;7462.888056679154;7763.146053934062;7888.566128862853;1204.265398107154;1223.62067758995;1226.3316492511603;1232.279116716713;1248.2697621891114;6295.401936815995;6354.618722876642;6379.075034120026;6401.967990160112;6410.294867564564;9705.6603018894;9791.461469619097;9797.412155270707;9809.901900981042;9845.852312215322;9493.145616894513;9654.806903862023;9698.75753106178;9700.417974921527;9734.951325243328;7724.729634462767;7761;7768.883466748003;7777.727779527576;7779.961100194463;7097.219305876246;7212.278772122746;7428.071491063617;7431.8142046449275;7436.256374362521;8659.82680346377;8774.429662071945;8801.735947921317;8811;8825.823483530165;8797.296216302808;8967.461952282883;8973.775655608662;8977.192052715287;8979.281657467256;7118.394936430462;7145.892811607934;7158.463115266271;7162.534435261691;7163.713451461883;7007.579545227302;7173.928260717535;7610.847783044419;7702.807429814299;7737.613119343898;7409.296116868889;7438.628068596657;7449.553026818191;7489.925100748923;7607.543547387175;7129.144502659714;7134.2152363238965;7158.534695244793;7294.963525182552;7321.890171647323;7448.813779655336;7512.774616761506;7550.093988721389;7552.320291173821;7587.544747315179;6728.226253980659;6775.661216939035;6776.322367763184;6782.118324617768;6786.694598743167;6889.586624802528;6897.586144831326;6910.170779506491;6910.93089069123;6921.377076062977 15:19:59 INFO - 2016-01-05 15:19:59,433 DEBUG : BROWSER_OUTPUT: |4;prototype.html;361.142287068213;361.5065435680297;362.5649220934877;365.73118757713087;366.91194113412774;5911.674857882815;5990.131430942511;6003.819885403434;6004.069369247763;6026.608270462417;5848.590598658096;5908.940910590893;5922;5934.287885453751;5961.910571341423;4657.790399432017;4657.88355291117;4658.138244424776;4661.58045775881;4665.0669866026765;4530.275155975045;4589.219832628444;4611;4657.371254880599;4677.321788340689;4604.815807367701;4606.976965115186;4608.470025947009;4621.798332433575;4624.445066592014;4604.769761511928;4618.237990731525;4628.652851036186;4629.444466663988;4632.235681112612;4104.897377565555;4110.91778164438;4115.794210289503;4115.938260926088;4124.298869192245;5611.831645050655;5696.515796157331;5708.457696518865;5764.481196692318;5797.246357973437;5461.590380721422;5461.69960652164;5465.125579907196;5466;5472.671639701631;6120.234970628671;6388.297287298394;6392.55252132351;6395.16862807832;6397.52018598607;4504.031633198878;4561.521040290788;4645.488996210414;4645.814167433333;4651.023285110097;6611.140551728245;6625.436837868787;6631.336866313378;6634.867302653968;6641.966790166067;4718.197906355859;4743.430788305425;4748.905021899612;4751.809927602857;4754.072955773591;4740.336352910595;4816.181249187646;4816.831410900601;4821.2045012572535;4838.080764654775;5463.535599474009;5464.617476776588;5466.098093814556;5471.671699698031;5476.616636835383;6424.453921416732;6426.614403135827;6426.967865160645;6428.678566071678;6434.067060276234;1727.464486009341;1751.4658029301186;1754.649070185968;1754.798198207197;1754.815744346825;1126.566271985275;1136.1705954652984;1136.886311368873;1138.5901075612774;1198.3109711915508;6177.876442471125;6189.6286222825875;6196.659183744938;6196.783112591038;6196.783112591038;6485.286618471964;6518.57629254097;6521.706523206467;6522.64125473094;6533.673316334164;6110.114033465123;6162.599431036968;6162.630242185483;6171.13604095427;6173.629582225081;3547.4856145858707;3624.818759062089;3658.487811706363;3661.3958696814725;3662.6520480554304;6799.4900382471515;6809.148856392951;6817.659117044128;6817.931820681829;6828.283030281848;6789.694463749044;6790.354916282945;6790.830229244309;6791.320867913169;6795.456363490909;6656.800295991031;6720.327967203339;6785.321467853175;6795;6811.978203269549;6410.519211059096;6493.350664933469;6539.11721917554;6608.966955165193;6609.768658096944;6502.869942601027;6531.085648009377;6534.019897015486;6545.37818907203;6555.639439830855;6762.661866906733;6783.728650854108;6789.864202715819;6790.93209067903;6801.693923773343;4462.933056004098;4513.932291015703;4517.435320584927;4543.522930092425;4547.2269714148015;6514.837129071621;6594.175728033996;6606.042123892104;6608.107905432705;6612.867742645024;6463.030545418225;6463.482921366185;6471.288158302489;6472.546921715435;6475.320091390525;6339.270983836917;6451.903221451776;6467.191601049869;6469.191351081115;6469.45009674183;6338.397852204033;6344.873102537831;6346.3019067903415;6352.745890164527;6357.809265722036;6510.869782604416;6537.248216455168;6538.509611779041;6542.738290468328;6543.443807276435;6297.653629050446;6374.426301632875;6390.137331460193;6390.233172019201;6392.64840433762 15:19:59 INFO - 2016-01-05 15:19:59,433 DEBUG : BROWSER_OUTPUT: |5;yui.html;767.5855038279333;772.5712229712507;863.6070587882514;946.5976959792087;950.8954015058343;13424.932875335622;13779.035467517253;13782.44870205192;13890.097143685653;13942.651433714162;13072.607821765338;13111.34443277837;13145.211287322767;13171.60485185446;13208.273544955024;748.3825843678959;748.6443939128898;895.4448242089913;916.083916083916;929.2658799548349;917.1104029091771;917.6100157433098;922.3036607361432;923.9029901860308;926.3515539122608;951.5908159491416;959.9568019439107;960.2893858544696;960.4237457525498;961.1349785193312;781.577947908129;781.6443518199205;945.5461378538306;964.7781010367585;966.5891995901784;713.1192976673804;714.3178264757145;716.5664772812426;716.6810769207652;716.870963226619;14642.41430342785;14724.19016954067;14740.410383584644;14749.188794616291;14763.704725905422;12398.194117382342;12475;12530.498780048787;13378.39797209128;14158.929205353907;10306.123979461754;10387.792244155075;10398.896011039868;10672.146228301717;10963.890361096366;3390.5253264542985;3457.6023757267985;3464.2378676691087;3464.9653503464897;3465.636108208627;45200.54799452029;46497.30254046192;50936.49063509355;51250.487495124944;51425;8145;8149;8158.551279679556;8178.754637360888;8179.591020449073;12889.09776315649;12911.22532648044;12920.483180672667;12930.67673308156;12935;6955.304469553004;7297.1608265049135;7576.052993375828;7637.15991240963;7682.884756728654;4332.328489084197;4534.478534968521;4580.083983203373;4590.88522786933;4597.540245975376;3086.583311252952;3123.6876312369036;3131.232847952266;3136.5765621640785;3137.450946084426;484.8739327774733;495.7917674576614;496.1936852614501;549.1323708540497;568.1591244957486;683.3815397065664;702.9015937768717;711.8896571031395;732.783828770514;733.9229380915034;8652.610632521426;8967.237784788365;9060.864087038699;9310.627574896927;9448.433094014381;563.3493315220926;702.4205030849467;710.531049507333;712.6721708014268;742.3912391838749;3992.640662340345;4067.4102255172834;4155.792210389468;4217.472815898012;4244.1723863846255;69086;69124.65437672779;69957.65021174963;70683;71027;59974.40025599688;60924.390756091874;61456.692716536134;61465;62922.685386572775;56000.71999640057;56045.43954560484;56250.43749562534;56344.718276408355;56797.7160114205;7653.502522336031;7656.6554505048525;7666.19504951972;7727.304542591194;7807.102183248999;38939.80530097331;39450.40824387694;40238.79880600578;40533.59466405298;41370.37944430776;52968.73515632397;55071.72464137654;55097;55745.44254557403;56006.719966399905;7643.350315223268;7688.961555192188;8004.51972881629;8032.638531266224;8221.917780822354;11499.482523286304;11730.589429369931;11812.527498899948;11901.285922844658;11952.103592230444;10924.617638382619;11023.338599684044;11098.944505277423;11459.140564457532;11470.885291146982;11601.18791684574;11610.187286889837;11623.535058597561;11882.227655202385;11989;10565.735856603646;10962.835557466486;11089.27919685218;11098.500567474644;11497.367644779617;9994.250431217833;10024.699259022242;10147.238957078102;10333.638322658671;10402;9706.70879873605;9922.156616687662;10229.641962531276;10438.164946804085;10455.16358691318 15:19:59 INFO - 2016-01-05 15:19:59,434 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:19:59 INFO - 2016-01-05 15:19:59,434 DEBUG : BROWSER_OUTPUT: __start_cc_report 15:19:59 INFO - 2016-01-05 15:19:59,434 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,720 15:19:59 INFO - 2016-01-05 15:19:59,434 DEBUG : BROWSER_OUTPUT: __end_cc_report 15:19:59 INFO - 2016-01-05 15:19:59,434 DEBUG : BROWSER_OUTPUT: __startTimestamp1452035999426__endTimestamp 15:19:59 INFO - 2016-01-05 15:19:59,434 DEBUG : BROWSER_OUTPUT: 15:19:59 INFO - 2016-01-05 15:19:59,434 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:19:59 INFO - 2016-01-05 15:19:59,434 DEBUG : BROWSER_OUTPUT: Number of tests: 6 15:19:59 INFO - 2016-01-05 15:19:59,434 DEBUG : BROWSER_OUTPUT: 15:19:59 INFO - 2016-01-05 15:19:59,434 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5522.85 Median:5734.34 stddev:612.38 (10.7%) stddev-sans-first:587.01 15:19:59 INFO - 2016-01-05 15:19:59,436 DEBUG : BROWSER_OUTPUT: Values: 3220.4 3326.9 3338.3 3355.0 3370.4 5436.1 5484.1 5485.0 5493.4 5494.6 5504.9 5536.7 5544.2 5558.4 5569.3 4845.3 5051.3 5068.3 5109.4 5119.1 5009.9 5027.6 5047.5 5052.3 5056.1 4958.3 5030.5 5032.4 5035.2 5067.8 5072.6 5102.8 5105.0 5121.5 5130.6 5018.7 5021.7 5070.2 5077.6 5090.0 5159.5 5215.9 5268.6 5290.6 5324.9 5889.1 5924.9 5926.2 5930.3 5937.4 5682.7 5939.0 5953.1 5957.9 5964.6 5460.1 5537.9 5646.4 5647.7 5697.5 6074.8 6083.8 6091.3 6094.5 6095.8 5647.9 5742.4 5898.2 5915.6 5924.1 5873.6 5959.6 5975.8 5981.2 6008.8 5937.0 5938.3 5948.9 5950.7 5963.3 5745.7 5853.7 5895.7 5966.8 5971.6 4604.9 4611.1 4611.3 4611.9 4630.0 4115.9 4121.3 4131.9 4136.0 4156.9 5402.6 5521.4 5585.2 5699.1 5732.3 6015.8 6031.9 6040.2 6048.4 6053.3 5644.8 5709.3 5727.9 5728.9 5735.4 4995.8 5093.0 5122.9 5211.7 5230.7 6124.7 6129.4 6132.4 6137.6 6142.6 5636.0 5958.0 5978.8 5984.1 5986.2 6032.8 6109.1 6234.9 6248.0 6280.3 5923.1 5935.1 5939.5 5939.5 5983.9 5688.8 5797.6 5982.6 5998.2 6010.0 5800.7 5899.5 5900.1 5906.5 5907.1 5889.4 5905.3 5905.6 5906.2 5906.2 5504.7 5620.2 5631.3 5777.7 5797.5 5773.4 5777.0 5801.0 5804.2 5867.2 5716.5 5734.3 5736.4 5737.9 5756.8 15:19:59 INFO - 2016-01-05 15:19:59,436 DEBUG : BROWSER_OUTPUT: 15:19:59 INFO - 2016-01-05 15:19:59,436 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:52545.16 Median:195773.91 stddev:84394.83 (43.1%) stddev-sans-first:84541.33 15:19:59 INFO - 2016-01-05 15:19:59,436 DEBUG : BROWSER_OUTPUT: Values: 562.2 599.2 601.5 603.5 604.2 35476.5 44386.0 45224.5 45255.5 45333.8 48412.0 48844.3 48893.5 48915.8 49037.3 8166.4 8309.9 9099.0 9272.3 10718.4 10957.5 11013.7 11036.7 11095.6 11106.4 11097.3 11177.0 11214.1 11218.9 11229.7 11021.8 11034.0 11058.8 11130.2 11133.7 10873.1 11005.7 11011.3 11011.9 11012.5 44353.8 44486.0 44908.0 45023.3 45027.0 3601.8 3729.8 3761.4 3787.7 4193.4 33302.2 33346.7 34036.1 34049.8 35552.8 3905.9 3923.1 3995.1 4038.2 4048.0 340257.0 341789.3 341984.0 342891.0 344480.0 60900.7 62579.4 75469.2 76923.6 77010.0 72261.6 72553.0 72827.0 73082.2 73287.6 19100.0 19165.9 19254.2 19268.2 19313.5 13655.2 13672.8 13673.9 13752.4 13799.5 22906.7 23225.4 23312.3 23328.5 23376.0 8717.6 8931.7 8956.4 8995.9 9103.4 966.9 1013.9 1123.9 1169.5 1211.5 29727.7 30463.8 30468.2 30539.8 31339.7 1118.6 1130.8 1221.4 1249.5 1258.6 13818.3 16130.4 16143.8 16231.5 16586.3 264967.0 265474.0 265924.0 267556.0 268064.0 64441.4 64802.4 64911.0 64972.7 65184.3 1336.7 1429.6 1437.0 1463.5 1485.0 33736.5 33777.8 34000.5 34203.0 34221.7 56308.4 57375.7 57424.7 57445.4 57478.7 350629.0 353421.0 354560.0 354775.0 355995.0 37284.0 37464.0 37619.6 37915.8 52056.2 49294.3 49409.3 50085.0 50575.0 50623.0 41487.2 42114.6 42371.4 42952.8 43050.0 41630.6 41996.0 42099.0 42764.6 42945.4 42010.0 42177.6 42248.4 42966.6 43821.0 35226.5 35734.0 35985.8 36005.3 36038.8 28052.7 28225.3 28397.6 28505.6 28650.0 15:19:59 INFO - 2016-01-05 15:19:59,436 DEBUG : BROWSER_OUTPUT: 15:19:59 INFO - 2016-01-05 15:19:59,436 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26020.65 Median:2441.25 stddev:78551.98 (3217.7%) stddev-sans-first:78748.75 15:19:59 INFO - 2016-01-05 15:19:59,437 DEBUG : BROWSER_OUTPUT: Values: 385.7 388.8 389.2 389.4 392.2 13885.0 13915.6 18085.3 20453.2 20459.5 13286.9 13724.5 13726.2 18702.3 19322.6 4875.0 4991.5 4994.4 4999.2 4999.7 4927.8 4939.1 4940.0 4953.0 4954.5 3821.8 3874.6 4898.2 4899.8 4902.4 5257.4 5339.1 5394.7 5395.1 5395.1 4425.8 4673.4 4674.9 4741.3 4744.3 15471.6 15555.3 15565.8 15835.8 15848.8 2105.3 2173.5 2215.3 2233.4 2241.3 11155.1 11171.4 11248.0 11301.8 11356.6 1801.8 1858.7 1896.3 1902.8 1917.1 64586.0 66452.7 67680.7 67975.0 68523.3 7107.9 7400.9 7582.2 7625.7 7649.2 9138.7 9192.4 9223.9 9229.4 9249.3 6284.8 6340.7 6362.8 6403.2 6405.3 6768.0 6774.4 6804.9 6813.0 6842.0 3418.8 3478.2 3513.5 3529.8 3534.8 1268.5 1273.8 1287.4 1297.0 1326.0 456.6 474.5 491.9 498.1 541.1 12694.7 12714.9 13136.4 13158.5 13213.9 468.4 521.2 526.8 532.4 574.9 7021.0 7113.6 7177.9 7205.9 7207.6 434976.0 435394.0 437507.0 439349.0 615078.0 72437.0 75285.0 78348.6 79727.6 83191.0 556.9 569.2 569.3 571.3 573.0 11502.1 11573.3 11676.6 11751.8 11858.2 35385.0 35887.6 35919.5 36176.0 36547.6 50445.5 50599.2 50729.0 50954.0 52101.7 7780.3 7838.1 7845.1 7849.6 7919.0 13014.0 13211.5 14117.6 14133.4 14245.9 11749.1 11916.3 11928.3 12158.2 12646.2 9731.3 10735.9 11291.4 11509.1 11790.4 11502.5 11595.7 11721.3 11813.8 12014.2 7395.3 7412.5 7447.4 7451.7 7451.9 11496.2 11578.5 11626.8 12005.6 12103.8 15:19:59 INFO - 2016-01-05 15:19:59,437 DEBUG : BROWSER_OUTPUT: 15:19:59 INFO - 2016-01-05 15:19:59,437 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5721.31 Median:6751.94 stddev:2886.76 (42.8%) stddev-sans-first:2877.99 15:19:59 INFO - 2016-01-05 15:19:59,437 DEBUG : BROWSER_OUTPUT: Values: 1568.9 1601.8 1620.6 1807.4 1910.6 7100.1 7221.1 7889.0 7999.6 8004.8 7830.4 7946.7 8022.1 8034.5 8035.5 2406.8 2428.6 2432.4 2437.5 2438.3 2082.5 2130.9 2392.7 2456.1 2481.4 2371.3 2413.9 2415.1 2417.4 2417.5 2411.0 2451.5 2460.8 2461.1 2462.6 2399.1 2411.1 2424.1 2427.2 2431.8 8164.5 8224.8 8235.2 8240.9 8244.1 5098.7 5173.4 5182.7 5185.1 5207.9 3213.5 3213.6 3232.5 3241.2 3259.8 1167.0 1179.6 1188.1 1195.4 1203.9 10796.6 10835.6 10866.0 10870.8 10880.8 4507.9 4533.4 4554.7 4579.2 4589.8 9454.0 9476.4 9486.7 9523.8 9538.2 4916.3 4920.6 4923.1 4926.6 4926.7 4450.3 4500.6 4500.9 4518.2 4531.9 3650.5 3985.8 4020.4 4041.8 4045.8 1223.2 1275.5 1311.4 1318.0 1321.6 1262.4 1319.5 1321.7 1381.6 1456.3 7389.2 7440.3 7462.9 7763.1 7888.6 1204.3 1223.6 1226.3 1232.3 1248.3 6295.4 6354.6 6379.1 6402.0 6410.3 9705.7 9791.5 9797.4 9809.9 9845.9 9493.1 9654.8 9698.8 9700.4 9735.0 7724.7 7761.0 7768.9 7777.7 7780.0 7097.2 7212.3 7428.1 7431.8 7436.3 8659.8 8774.4 8801.7 8811.0 8825.8 8797.3 8967.5 8973.8 8977.2 8979.3 7118.4 7145.9 7158.5 7162.5 7163.7 7007.6 7173.9 7610.8 7702.8 7737.6 7409.3 7438.6 7449.6 7489.9 7607.5 7129.1 7134.2 7158.5 7295.0 7321.9 7448.8 7512.8 7550.1 7552.3 7587.5 6728.2 6775.7 6776.3 6782.1 6786.7 6889.6 6897.6 6910.2 6910.9 6921.4 15:19:59 INFO - 2016-01-05 15:19:59,437 DEBUG : BROWSER_OUTPUT: 15:19:59 INFO - 2016-01-05 15:19:59,438 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5387.59 Median:6115.17 stddev:1584.01 (25.9%) stddev-sans-first:1542.87 15:19:59 INFO - 2016-01-05 15:19:59,439 DEBUG : BROWSER_OUTPUT: Values: 361.1 361.5 362.6 365.7 366.9 5911.7 5990.1 6003.8 6004.1 6026.6 5848.6 5908.9 5922.0 5934.3 5961.9 4657.8 4657.9 4658.1 4661.6 4665.1 4530.3 4589.2 4611.0 4657.4 4677.3 4604.8 4607.0 4608.5 4621.8 4624.4 4604.8 4618.2 4628.7 4629.4 4632.2 4104.9 4110.9 4115.8 4115.9 4124.3 5611.8 5696.5 5708.5 5764.5 5797.2 5461.6 5461.7 5465.1 5466.0 5472.7 6120.2 6388.3 6392.6 6395.2 6397.5 4504.0 4561.5 4645.5 4645.8 4651.0 6611.1 6625.4 6631.3 6634.9 6642.0 4718.2 4743.4 4748.9 4751.8 4754.1 4740.3 4816.2 4816.8 4821.2 4838.1 5463.5 5464.6 5466.1 5471.7 5476.6 6424.5 6426.6 6427.0 6428.7 6434.1 1727.5 1751.5 1754.6 1754.8 1754.8 1126.6 1136.2 1136.9 1138.6 1198.3 6177.9 6189.6 6196.7 6196.8 6196.8 6485.3 6518.6 6521.7 6522.6 6533.7 6110.1 6162.6 6162.6 6171.1 6173.6 3547.5 3624.8 3658.5 3661.4 3662.7 6799.5 6809.1 6817.7 6817.9 6828.3 6789.7 6790.4 6790.8 6791.3 6795.5 6656.8 6720.3 6785.3 6795.0 6812.0 6410.5 6493.4 6539.1 6609.0 6609.8 6502.9 6531.1 6534.0 6545.4 6555.6 6762.7 6783.7 6789.9 6790.9 6801.7 4462.9 4513.9 4517.4 4543.5 4547.2 6514.8 6594.2 6606.0 6608.1 6612.9 6463.0 6463.5 6471.3 6472.5 6475.3 6339.3 6451.9 6467.2 6469.2 6469.5 6338.4 6344.9 6346.3 6352.7 6357.8 6510.9 6537.2 6538.5 6542.7 6543.4 6297.7 6374.4 6390.1 6390.2 6392.6 15:19:59 INFO - 2016-01-05 15:19:59,439 DEBUG : BROWSER_OUTPUT: 15:19:59 INFO - 2016-01-05 15:19:59,439 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14960.36 Median:26758.93 stddev:19033.12 (71.1%) stddev-sans-first:19056.68 15:19:59 INFO - 2016-01-05 15:19:59,439 DEBUG : BROWSER_OUTPUT: Values: 767.6 772.6 863.6 946.6 950.9 13424.9 13779.0 13782.4 13890.1 13942.7 13072.6 13111.3 13145.2 13171.6 13208.3 748.4 748.6 895.4 916.1 929.3 917.1 917.6 922.3 923.9 926.4 951.6 960.0 960.3 960.4 961.1 781.6 781.6 945.5 964.8 966.6 713.1 714.3 716.6 716.7 716.9 14642.4 14724.2 14740.4 14749.2 14763.7 12398.2 12475.0 12530.5 13378.4 14158.9 10306.1 10387.8 10398.9 10672.1 10963.9 3390.5 3457.6 3464.2 3465.0 3465.6 45200.5 46497.3 50936.5 51250.5 51425.0 8145.0 8149.0 8158.6 8178.8 8179.6 12889.1 12911.2 12920.5 12930.7 12935.0 6955.3 7297.2 7576.1 7637.2 7682.9 4332.3 4534.5 4580.1 4590.9 4597.5 3086.6 3123.7 3131.2 3136.6 3137.5 484.9 495.8 496.2 549.1 568.2 683.4 702.9 711.9 732.8 733.9 8652.6 8967.2 9060.9 9310.6 9448.4 563.3 702.4 710.5 712.7 742.4 3992.6 4067.4 4155.8 4217.5 4244.2 69086.0 69124.7 69957.7 70683.0 71027.0 59974.4 60924.4 61456.7 61465.0 62922.7 56000.7 56045.4 56250.4 56344.7 56797.7 7653.5 7656.7 7666.2 7727.3 7807.1 38939.8 39450.4 40238.8 40533.6 41370.4 52968.7 55071.7 55097.0 55745.4 56006.7 7643.4 7689.0 8004.5 8032.6 8221.9 11499.5 11730.6 11812.5 11901.3 11952.1 10924.6 11023.3 11098.9 11459.1 11470.9 11601.2 11610.2 11623.5 11882.2 11989.0 10565.7 10962.8 11089.3 11098.5 11497.4 9994.3 10024.7 10147.2 10333.6 10402.0 9706.7 9922.2 10229.6 10438.2 10455.2 15:19:59 INFO - 2016-01-05 15:19:59,439 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:19:59 INFO - 2016-01-05 15:19:59,439 DEBUG : BROWSER_OUTPUT: 15:20:00 INFO - 2016-01-05 15:20:00,029 INFO : Browser exited with error code: 0 15:20:00 INFO - 2016-01-05 15:20:00,047 INFO : Completed test dromaeo_css (00:18:30) 15:20:00 INFO - 2016-01-05 15:20:00,048 INFO : Starting test kraken 15:20:00 INFO - 2016-01-05 15:20:00,048 DEBUG : operating with platform_type : linux_ 15:20:00 INFO - 2016-01-05 15:20:00,048 INFO : Initialising browser for kraken test... 15:20:00 INFO - 2016-01-05 15:20:00,054 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBrdQHX/profile http://localhost:57766/getInfo.html 15:20:03 INFO - 2016-01-05 15:20:03,686 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:20:03 INFO - 2016-01-05 15:20:03,686 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:20:03 INFO - 2016-01-05 15:20:03,686 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:20:03 INFO - 2016-01-05 15:20:03,687 DEBUG : BROWSER_OUTPUT: __metrics 15:20:03 INFO - 2016-01-05 15:20:03,795 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] 15:20:04 INFO - 2016-01-05 15:20:04,921 INFO : Browser initialized. 15:20:04 INFO - 2016-01-05 15:20:04,922 INFO : Running cycle 1/1 for kraken test... 15:20:04 INFO - 2016-01-05 15:20:04,922 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBrdQHX/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:20:16 INFO - 2016-01-05 15:20:16,499 DEBUG : BROWSER_OUTPUT: RSS: Main: 128847872 15:20:16 INFO - 2016-01-05 15:20:16,499 DEBUG : BROWSER_OUTPUT: 15:20:18 INFO - 2016-01-05 15:20:18,955 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57766/tests/kraken/driver.html?testName=audio-beat-detection) 15:20:19 INFO - 2016-01-05 15:20:19,524 DEBUG : BROWSER_OUTPUT: RSS: Main: 163721216 15:20:19 INFO - 2016-01-05 15:20:19,525 DEBUG : BROWSER_OUTPUT: 15:20:22 INFO - 2016-01-05 15:20:22,073 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57766/tests/kraken/driver.html?testName=audio-dft) 15:20:22 INFO - 2016-01-05 15:20:22,646 DEBUG : BROWSER_OUTPUT: RSS: Main: 161427456 15:20:22 INFO - 2016-01-05 15:20:22,647 DEBUG : BROWSER_OUTPUT: 15:20:25 INFO - 2016-01-05 15:20:25,424 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57766/tests/kraken/driver.html?testName=audio-fft) 15:20:25 INFO - 2016-01-05 15:20:25,977 DEBUG : BROWSER_OUTPUT: RSS: Main: 158928896 15:20:25 INFO - 2016-01-05 15:20:25,978 DEBUG : BROWSER_OUTPUT: 15:20:27 INFO - 2016-01-05 15:20:27,917 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57766/tests/kraken/driver.html?testName=audio-oscillator) 15:20:28 INFO - 2016-01-05 15:20:28,484 DEBUG : BROWSER_OUTPUT: RSS: Main: 159330304 15:20:28 INFO - 2016-01-05 15:20:28,484 DEBUG : BROWSER_OUTPUT: 15:20:30 INFO - 2016-01-05 15:20:30,598 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57766/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:20:31 INFO - 2016-01-05 15:20:31,152 DEBUG : BROWSER_OUTPUT: RSS: Main: 158023680 15:20:31 INFO - 2016-01-05 15:20:31,152 DEBUG : BROWSER_OUTPUT: 15:20:34 INFO - 2016-01-05 15:20:34,021 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57766/tests/kraken/driver.html?testName=imaging-darkroom) 15:20:34 INFO - 2016-01-05 15:20:34,588 DEBUG : BROWSER_OUTPUT: RSS: Main: 157044736 15:20:34 INFO - 2016-01-05 15:20:34,588 DEBUG : BROWSER_OUTPUT: 15:20:37 INFO - 2016-01-05 15:20:37,990 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57766/tests/kraken/driver.html?testName=imaging-desaturate) 15:20:38 INFO - 2016-01-05 15:20:38,556 DEBUG : BROWSER_OUTPUT: RSS: Main: 157102080 15:20:38 INFO - 2016-01-05 15:20:38,557 DEBUG : BROWSER_OUTPUT: 15:20:41 INFO - 2016-01-05 15:20:41,260 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57766/tests/kraken/driver.html?testName=json-parse-financial) 15:20:41 INFO - 2016-01-05 15:20:41,827 DEBUG : BROWSER_OUTPUT: RSS: Main: 157171712 15:20:41 INFO - 2016-01-05 15:20:41,827 DEBUG : BROWSER_OUTPUT: 15:20:43 INFO - 2016-01-05 15:20:43,323 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57766/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:20:43 INFO - 2016-01-05 15:20:43,886 DEBUG : BROWSER_OUTPUT: RSS: Main: 160825344 15:20:43 INFO - 2016-01-05 15:20:43,886 DEBUG : BROWSER_OUTPUT: 15:20:45 INFO - 2016-01-05 15:20:45,600 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57766/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:20:46 INFO - 2016-01-05 15:20:46,190 DEBUG : BROWSER_OUTPUT: RSS: Main: 159641600 15:20:46 INFO - 2016-01-05 15:20:46,190 DEBUG : BROWSER_OUTPUT: 15:20:48 INFO - 2016-01-05 15:20:48,192 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57766/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:20:48 INFO - 2016-01-05 15:20:48,758 DEBUG : BROWSER_OUTPUT: RSS: Main: 164024320 15:20:48 INFO - 2016-01-05 15:20:48,758 DEBUG : BROWSER_OUTPUT: 15:20:51 INFO - 2016-01-05 15:20:51,057 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57766/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:20:51 INFO - 2016-01-05 15:20:51,631 DEBUG : BROWSER_OUTPUT: RSS: Main: 169062400 15:20:51 INFO - 2016-01-05 15:20:51,631 DEBUG : BROWSER_OUTPUT: 15:20:54 INFO - 2016-01-05 15:20:54,051 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57766/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:20:54 INFO - 2016-01-05 15:20:54,626 DEBUG : BROWSER_OUTPUT: RSS: Main: 166748160 15:20:54 INFO - 2016-01-05 15:20:54,626 DEBUG : BROWSER_OUTPUT: 15:20:56 INFO - 2016-01-05 15:20:56,225 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57766/tests/kraken/driver.html?testName=ai-astar) 15:20:56 INFO - 2016-01-05 15:20:56,483 DEBUG : BROWSER_OUTPUT: RSS: Main: 225361920 15:20:56 INFO - 2016-01-05 15:20:56,483 DEBUG : BROWSER_OUTPUT: 15:20:56 INFO - 2016-01-05 15:20:56,483 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:20:56 INFO - 2016-01-05 15:20:56,484 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:20:56 INFO - 2016-01-05 15:20:56,484 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:20:56 INFO - 2016-01-05 15:20:56,484 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:20:56 INFO - 2016-01-05 15:20:56,484 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;103;109;110;110;101;109;113;109 15:20:56 INFO - 2016-01-05 15:20:56,484 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;148;129;157;166;131;148;145;125;161;129 15:20:56 INFO - 2016-01-05 15:20:56,484 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;161;162;161;159;162;159;160;160;158 15:20:56 INFO - 2016-01-05 15:20:56,484 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;71;137;72;72;71;72;150;72 15:20:56 INFO - 2016-01-05 15:20:56,484 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;89;132;89;90;89;132;90;89;132 15:20:56 INFO - 2016-01-05 15:20:56,485 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;110;110;110;110;110;109;109;109 15:20:56 INFO - 2016-01-05 15:20:56,485 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;146;145;145;145;145;146;147;145 15:20:56 INFO - 2016-01-05 15:20:56,485 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;96;95;96;97;96;96;95;95 15:20:56 INFO - 2016-01-05 15:20:56,485 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;52;53;55;55;54;56;66;54;52;52 15:20:56 INFO - 2016-01-05 15:20:56,485 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;63;62;62;63;64;64;64;67;63 15:20:56 INFO - 2016-01-05 15:20:56,485 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;78;78;82;79;79;79;83;79;78;80 15:20:56 INFO - 2016-01-05 15:20:56,486 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;119;111;108;108;116;113;108;107;109;110 15:20:56 INFO - 2016-01-05 15:20:56,486 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;135;134;134;135;133;147;134;137;136;134 15:20:56 INFO - 2016-01-05 15:20:56,486 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;62;61;62;62;63;61;60;62;61 15:20:56 INFO - 2016-01-05 15:20:56,486 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:20:56 INFO - 2016-01-05 15:20:56,486 DEBUG : BROWSER_OUTPUT: __start_cc_report 15:20:56 INFO - 2016-01-05 15:20:56,487 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,871 15:20:56 INFO - 2016-01-05 15:20:56,487 DEBUG : BROWSER_OUTPUT: __end_cc_report 15:20:56 INFO - 2016-01-05 15:20:56,487 DEBUG : BROWSER_OUTPUT: __startTimestamp1452036056478__endTimestamp 15:20:56 INFO - 2016-01-05 15:20:56,487 DEBUG : BROWSER_OUTPUT: 15:20:56 INFO - 2016-01-05 15:20:56,487 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:20:56 INFO - 2016-01-05 15:20:56,487 DEBUG : BROWSER_OUTPUT: Number of tests: 14 15:20:56 INFO - 2016-01-05 15:20:56,488 DEBUG : BROWSER_OUTPUT: 15:20:56 INFO - 2016-01-05 15:20:56,488 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.20 Median:109.50 stddev:3.55 (3.2%) stddev-sans-first:3.77 15:20:56 INFO - 2016-01-05 15:20:56,488 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 103.0 109.0 110.0 110.0 101.0 109.0 113.0 109.0 15:20:56 INFO - 2016-01-05 15:20:56,488 DEBUG : BROWSER_OUTPUT: 15:20:56 INFO - 2016-01-05 15:20:56,488 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.90 Median:148.00 stddev:14.74 (10.0%) stddev-sans-first:15.56 15:20:56 INFO - 2016-01-05 15:20:56,489 DEBUG : BROWSER_OUTPUT: Values: 148.0 129.0 157.0 166.0 131.0 148.0 145.0 125.0 161.0 129.0 15:20:56 INFO - 2016-01-05 15:20:56,489 DEBUG : BROWSER_OUTPUT: 15:20:56 INFO - 2016-01-05 15:20:56,489 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.30 Median:161.00 stddev:1.34 (0.8%) stddev-sans-first:1.39 15:20:56 INFO - 2016-01-05 15:20:56,489 DEBUG : BROWSER_OUTPUT: Values: 161.0 161.0 162.0 161.0 159.0 162.0 159.0 160.0 160.0 158.0 15:20:56 INFO - 2016-01-05 15:20:56,489 DEBUG : BROWSER_OUTPUT: 15:20:56 INFO - 2016-01-05 15:20:56,490 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:85.90 Median:71.50 stddev:30.52 (42.7%) stddev-sans-first:31.89 15:20:56 INFO - 2016-01-05 15:20:56,490 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 71.0 137.0 72.0 72.0 71.0 72.0 150.0 72.0 15:20:56 INFO - 2016-01-05 15:20:56,490 DEBUG : BROWSER_OUTPUT: 15:20:56 INFO - 2016-01-05 15:20:56,490 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.10 Median:89.00 stddev:20.64 (23.2%) stddev-sans-first:21.34 15:20:56 INFO - 2016-01-05 15:20:56,490 DEBUG : BROWSER_OUTPUT: Values: 89.0 89.0 132.0 89.0 90.0 89.0 132.0 90.0 89.0 132.0 15:20:56 INFO - 2016-01-05 15:20:56,491 DEBUG : BROWSER_OUTPUT: 15:20:56 INFO - 2016-01-05 15:20:56,491 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.60 Median:110.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 15:20:56 INFO - 2016-01-05 15:20:56,491 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 110.0 110.0 110.0 110.0 110.0 109.0 109.0 109.0 15:20:56 INFO - 2016-01-05 15:20:56,491 DEBUG : BROWSER_OUTPUT: 15:20:56 INFO - 2016-01-05 15:20:56,491 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 15:20:56 INFO - 2016-01-05 15:20:56,491 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 146.0 145.0 145.0 145.0 145.0 146.0 147.0 145.0 15:20:56 INFO - 2016-01-05 15:20:56,491 DEBUG : BROWSER_OUTPUT: 15:20:56 INFO - 2016-01-05 15:20:56,491 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 15:20:56 INFO - 2016-01-05 15:20:56,491 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 96.0 95.0 96.0 97.0 96.0 96.0 95.0 95.0 15:20:56 INFO - 2016-01-05 15:20:56,491 DEBUG : BROWSER_OUTPUT: 15:20:56 INFO - 2016-01-05 15:20:56,491 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:54.90 Median:54.50 stddev:4.15 (7.6%) stddev-sans-first:4.27 15:20:56 INFO - 2016-01-05 15:20:56,491 DEBUG : BROWSER_OUTPUT: Values: 52.0 53.0 55.0 55.0 54.0 56.0 66.0 54.0 52.0 52.0 15:20:56 INFO - 2016-01-05 15:20:56,491 DEBUG : BROWSER_OUTPUT: 15:20:56 INFO - 2016-01-05 15:20:56,491 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.50 Median:63.50 stddev:1.43 (2.3%) stddev-sans-first:1.51 15:20:56 INFO - 2016-01-05 15:20:56,491 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 62.0 62.0 63.0 64.0 64.0 64.0 67.0 63.0 15:20:56 INFO - 2016-01-05 15:20:56,491 DEBUG : BROWSER_OUTPUT: 15:20:56 INFO - 2016-01-05 15:20:56,492 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:79.00 stddev:1.72 (2.2%) stddev-sans-first:1.73 15:20:56 INFO - 2016-01-05 15:20:56,492 DEBUG : BROWSER_OUTPUT: Values: 78.0 78.0 82.0 79.0 79.0 79.0 83.0 79.0 78.0 80.0 15:20:56 INFO - 2016-01-05 15:20:56,492 DEBUG : BROWSER_OUTPUT: 15:20:56 INFO - 2016-01-05 15:20:56,492 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:110.90 Median:110.50 stddev:3.96 (3.6%) stddev-sans-first:2.92 15:20:56 INFO - 2016-01-05 15:20:56,492 DEBUG : BROWSER_OUTPUT: Values: 119.0 111.0 108.0 108.0 116.0 113.0 108.0 107.0 109.0 110.0 15:20:56 INFO - 2016-01-05 15:20:56,492 DEBUG : BROWSER_OUTPUT: 15:20:56 INFO - 2016-01-05 15:20:56,492 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:135.90 Median:135.00 stddev:4.07 (3.0%) stddev-sans-first:4.30 15:20:56 INFO - 2016-01-05 15:20:56,492 DEBUG : BROWSER_OUTPUT: Values: 135.0 134.0 134.0 135.0 133.0 147.0 134.0 137.0 136.0 134.0 15:20:56 INFO - 2016-01-05 15:20:56,492 DEBUG : BROWSER_OUTPUT: 15:20:56 INFO - 2016-01-05 15:20:56,492 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:1.37 (2.2%) stddev-sans-first:0.88 15:20:56 INFO - 2016-01-05 15:20:56,492 DEBUG : BROWSER_OUTPUT: Values: 65.0 62.0 61.0 62.0 62.0 63.0 61.0 60.0 62.0 61.0 15:20:56 INFO - 2016-01-05 15:20:56,492 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:20:56 INFO - 2016-01-05 15:20:56,492 DEBUG : BROWSER_OUTPUT: 15:20:57 INFO - 2016-01-05 15:20:57,149 INFO : Browser exited with error code: 0 15:20:57 INFO - 2016-01-05 15:20:57,200 INFO : Completed test kraken (00:00:57) 15:20:57 INFO - 2016-01-05 15:20:57,200 INFO : Starting test v8_7 15:20:57 INFO - 2016-01-05 15:20:57,200 DEBUG : operating with platform_type : linux_ 15:20:57 INFO - 2016-01-05 15:20:57,200 INFO : Initialising browser for v8_7 test... 15:20:57 INFO - 2016-01-05 15:20:57,207 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRxlKTm/profile http://localhost:57766/getInfo.html 15:21:00 INFO - 2016-01-05 15:21:00,741 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:21:00 INFO - 2016-01-05 15:21:00,742 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:21:00 INFO - 2016-01-05 15:21:00,742 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:21:00 INFO - 2016-01-05 15:21:00,742 DEBUG : BROWSER_OUTPUT: __metrics 15:21:00 INFO - 2016-01-05 15:21:00,818 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] 15:21:01 INFO - 2016-01-05 15:21:01,985 INFO : Browser initialized. 15:21:01 INFO - 2016-01-05 15:21:01,986 INFO : Running cycle 1/1 for v8_7 test... 15:21:01 INFO - 2016-01-05 15:21:01,986 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRxlKTm/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:21:13 INFO - 2016-01-05 15:21:13,549 DEBUG : BROWSER_OUTPUT: RSS: Main: 128688128 15:21:13 INFO - 2016-01-05 15:21:13,550 DEBUG : BROWSER_OUTPUT: 15:21:34 INFO - 2016-01-05 15:21:34,913 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57766/tests/v8_7/run.html (next: http://localhost:57766/tests/v8_7/run.html) 15:21:35 INFO - 2016-01-05 15:21:35,164 DEBUG : BROWSER_OUTPUT: RSS: Main: 416559104 15:21:35 INFO - 2016-01-05 15:21:35,164 DEBUG : BROWSER_OUTPUT: 15:21:35 INFO - 2016-01-05 15:21:35,164 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:21:35 INFO - 2016-01-05 15:21:35,164 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:21:35 INFO - 2016-01-05 15:21:35,164 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:21:35 INFO - 2016-01-05 15:21:35,165 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:21:35 INFO - 2016-01-05 15:21:35,165 DEBUG : BROWSER_OUTPUT: |0;Richards;163.21201240411295 15:21:35 INFO - 2016-01-05 15:21:35,165 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.64846416382252 15:21:35 INFO - 2016-01-05 15:21:35,165 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.4076557697681 15:21:35 INFO - 2016-01-05 15:21:35,165 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4990.049751243781 15:21:35 INFO - 2016-01-05 15:21:35,166 DEBUG : BROWSER_OUTPUT: |4;RayTrace;956.0229445506692 15:21:35 INFO - 2016-01-05 15:21:35,166 DEBUG : BROWSER_OUTPUT: |5;Earley;557.4136008918617 15:21:35 INFO - 2016-01-05 15:21:35,166 DEBUG : BROWSER_OUTPUT: |6;Boyer;10562.5 15:21:35 INFO - 2016-01-05 15:21:35,166 DEBUG : BROWSER_OUTPUT: |7;RegExp;23904.761904761905 15:21:35 INFO - 2016-01-05 15:21:35,166 DEBUG : BROWSER_OUTPUT: |8;Splay;528.2722513089005 15:21:35 INFO - 2016-01-05 15:21:35,166 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 15:21:35 INFO - 2016-01-05 15:21:35,166 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:21:35 INFO - 2016-01-05 15:21:35,166 DEBUG : BROWSER_OUTPUT: __startTimestamp1452036095162__endTimestamp 15:21:35 INFO - 2016-01-05 15:21:35,166 DEBUG : BROWSER_OUTPUT: 15:21:35 INFO - 2016-01-05 15:21:35,166 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:21:35 INFO - 2016-01-05 15:21:35,166 DEBUG : BROWSER_OUTPUT: Number of tests: 10 15:21:35 INFO - 2016-01-05 15:21:35,167 DEBUG : BROWSER_OUTPUT: 15:21:35 INFO - 2016-01-05 15:21:35,167 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.21 Median:163.21 stddev:0.00 (0.0%) 15:21:35 INFO - 2016-01-05 15:21:35,167 DEBUG : BROWSER_OUTPUT: Values: 163.2 15:21:35 INFO - 2016-01-05 15:21:35,167 DEBUG : BROWSER_OUTPUT: 15:21:35 INFO - 2016-01-05 15:21:35,167 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.65 Median:170.65 stddev:0.00 (0.0%) 15:21:35 INFO - 2016-01-05 15:21:35,167 DEBUG : BROWSER_OUTPUT: Values: 170.6 15:21:35 INFO - 2016-01-05 15:21:35,167 DEBUG : BROWSER_OUTPUT: 15:21:35 INFO - 2016-01-05 15:21:35,167 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.41 Median:279.41 stddev:0.00 (0.0%) 15:21:35 INFO - 2016-01-05 15:21:35,167 DEBUG : BROWSER_OUTPUT: Values: 279.4 15:21:35 INFO - 2016-01-05 15:21:35,167 DEBUG : BROWSER_OUTPUT: 15:21:35 INFO - 2016-01-05 15:21:35,167 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4990.05 Median:4990.05 stddev:0.00 (0.0%) 15:21:35 INFO - 2016-01-05 15:21:35,168 DEBUG : BROWSER_OUTPUT: Values: 4990.0 15:21:35 INFO - 2016-01-05 15:21:35,168 DEBUG : BROWSER_OUTPUT: 15:21:35 INFO - 2016-01-05 15:21:35,168 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:956.02 Median:956.02 stddev:0.00 (0.0%) 15:21:35 INFO - 2016-01-05 15:21:35,168 DEBUG : BROWSER_OUTPUT: Values: 956.0 15:21:35 INFO - 2016-01-05 15:21:35,168 DEBUG : BROWSER_OUTPUT: 15:21:35 INFO - 2016-01-05 15:21:35,168 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:557.41 Median:557.41 stddev:0.00 (0.0%) 15:21:35 INFO - 2016-01-05 15:21:35,168 DEBUG : BROWSER_OUTPUT: Values: 557.4 15:21:35 INFO - 2016-01-05 15:21:35,168 DEBUG : BROWSER_OUTPUT: 15:21:35 INFO - 2016-01-05 15:21:35,168 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10562.50 Median:10562.50 stddev:0.00 (0.0%) 15:21:35 INFO - 2016-01-05 15:21:35,168 DEBUG : BROWSER_OUTPUT: Values: 10562.5 15:21:35 INFO - 2016-01-05 15:21:35,168 DEBUG : BROWSER_OUTPUT: 15:21:35 INFO - 2016-01-05 15:21:35,168 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23904.76 Median:23904.76 stddev:0.00 (0.0%) 15:21:35 INFO - 2016-01-05 15:21:35,168 DEBUG : BROWSER_OUTPUT: Values: 23904.8 15:21:35 INFO - 2016-01-05 15:21:35,168 DEBUG : BROWSER_OUTPUT: 15:21:35 INFO - 2016-01-05 15:21:35,168 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:528.27 Median:528.27 stddev:0.00 (0.0%) 15:21:35 INFO - 2016-01-05 15:21:35,168 DEBUG : BROWSER_OUTPUT: Values: 528.3 15:21:35 INFO - 2016-01-05 15:21:35,169 DEBUG : BROWSER_OUTPUT: 15:21:35 INFO - 2016-01-05 15:21:35,169 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 15:21:35 INFO - 2016-01-05 15:21:35,169 DEBUG : BROWSER_OUTPUT: Values: 4840.6 15:21:35 INFO - 2016-01-05 15:21:35,169 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:21:35 INFO - 2016-01-05 15:21:35,169 DEBUG : BROWSER_OUTPUT: 15:21:35 INFO - 2016-01-05 15:21:35,829 INFO : Browser exited with error code: 0 15:21:35 INFO - 2016-01-05 15:21:35,841 INFO : Completed test v8_7 (00:00:38) 15:21:36 INFO - 2016-01-05 15:21:36,215 INFO : Completed test suite (00:20:06) 15:21:36 INFO - 2016-01-05 15:21:36,216 DEBUG : Working with test: dromaeo_css 15:21:36 INFO - 2016-01-05 15:21:36,216 DEBUG : Generating results file: dromaeo_css 15:21:36 INFO - 2016-01-05 15:21:36,217 DEBUG : Working with test: kraken 15:21:36 INFO - 2016-01-05 15:21:36,217 DEBUG : Generating results file: kraken 15:21:36 INFO - 2016-01-05 15:21:36,217 INFO : javascript benchmark 15:21:36 INFO - 2016-01-05 15:21:36,217 DEBUG : Working with test: v8_7 15:21:36 INFO - 2016-01-05 15:21:36,218 DEBUG : Generating results file: v8_7 15:21:36 INFO - 2016-01-05 15:21:36,218 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:21:36 INFO - 2016-01-05 15:21:36,270 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 15:21:36 INFO - 2016-01-05 15:21:36,270 DEBUG : process_Request line: dromaeo_css 7130.70 graph.html#tests=[[72,53,35]] 15:21:36 INFO - 2016-01-05 15:21:36,271 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:21:36 INFO - 2016-01-05 15:21:36,300 DEBUG : process_Request line: kraken 1457.70 graph.html#tests=[[232,53,35]] 15:21:36 INFO - 2016-01-05 15:21:36,300 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:21:36 INFO - 2016-01-05 15:21:36,332 DEBUG : process_Request line: v8_7 23268.58 graph.html#tests=[[230,53,35]] 15:21:36 INFO - 2016-01-05 15:21:36,333 INFO : javascript benchmark 15:21:36 INFO - 2016-01-05 15:21:36,337 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3220.355928814237, 3326.883559075433, 3338.315645292715, 3355.0, 3370.4438767603347, 5436.130219164935, 5484.095124304485, 5485.0401179793525, 5493.395726470085, 5494.615376923617, 5504.944950550494, 5536.667799932007, 5544.223808666779, 5558.44415558444, 5569.303837020372, 4845.345878306436, 5051.3180720602795, 5068.315777370063, 5109.386873575176, 5119.052975199581, 5009.874753131183, 5027.648064635477, 5047.49525047496, 5052.317937078484, 5056.064628043824, 4958.2562615607585, 5030.522100400456, 5032.396112466491, 5035.194368900977, 5067.7719502622285, 5072.619553533481, 5102.770375333082, 5105.004524117761, 5121.539061484447, 5130.640855140142, 5018.723970181639, 5021.748912554358, 5070.163423035195, 5077.619178561623, 5090.032893750175, 5159.458256883011, 5215.921761173586, 5268.60485463588, 5290.60320475962, 5324.866878328034, 5889.1460738193155, 5924.911126333109, 5926.170336152942, 5930.258717660293, 5937.435943585352, 5682.687452190128, 5939.049752039653, 5953.136795164677, 5957.851053723648, 5964.642121472682, 5460.099083651193, 5537.861553461155, 5646.378898321223, 5647.661140331593, 5697.515711164556, 6074.787382441594, 6083.8479038023515, 6091.269047714302, 6094.542909281733, 6095.786647467317, 5647.858803529863, 5742.397048309951, 5898.174255604219, 5915.58590898633, 5924.052151655801, 5873.58884878063, 5959.553033522417, 5975.790847320323, 5981.222441082631, 6008.849778755479, 5936.990711579042, 5938.346781853993, 5948.851278718067, 5950.67271300074, 5963.314218864799, 5745.683987380734, 5853.7365818538265, 5895.67573783446, 5966.791162309299, 5971.611845230046, 4604.907901841944, 4611.123886461562, 4611.262198048296, 4611.8847028823875, 4630.027694184192, 4115.9176816463505, 4121.340585506305, 4131.876043718693, 4135.958640413617, 4156.937645935313, 5402.594805389612, 5521.365043020104, 5585.1901474286005, 5699.145128230715, 5732.254806875079, 6015.849603759941, 6031.8793624127275, 6040.154378387029, 6048.425399587032, 6053.273607167168, 5644.8306550803545, 5709.25779648643, 5727.885442291131, 5728.885422291614, 5735.426457354315, 4995.825146119869, 5092.9745351273, 5122.948770512322, 5211.713355765393, 5230.660007099526, 6124.693765311717, 6129.448349648553, 6132.356102609251, 6137.570370074052, 6142.600730952474, 5635.985522605806, 5958.0, 5978.8206353809455, 5984.102384642339, 5986.161937328865, 6032.75868965254, 6109.144719739151, 6234.906476402767, 6248.0, 6280.340564240689, 5923.111533270044, 5935.052064918035, 5939.4951429129005, 5939.524838013035, 5983.850403739767, 5688.772449102155, 5797.594168408171, 5982.611130276518, 5998.160257563857, 6010.0, 5800.680962546931, 5899.46904778572, 5900.1149827526215, 5906.468417842415, 5907.113932909926, 5889.411058894248, 5905.261842269716, 5905.586608937333, 5906.202662640489, 5906.232189815297, 5504.697241651747, 5620.241267428844, 5631.324241091095, 5777.653340799566, 5797.4782269595935, 5773.422657734193, 5776.971115144397, 5800.970995144998, 5804.158397032491, 5867.20792692981, 5716.485516303553, 5734.340550836707, 5736.397678243724, 5737.942620573741, 5756.827295181151], "unit": "ms", "name": "dojo.html", "value": 5483.429759515361}, {"lowerIsBetter": false, "replicates": [562.212901937288, 599.1731410653299, 601.4707057789145, 603.4538742438092, 604.1753007145245, 35476.46785298219, 44386.0, 45224.54775452244, 45255.547444525546, 45333.773331133336, 48412.0, 48844.267335989985, 48893.51106488934, 48915.75542122293, 49037.26444103341, 8166.428350015502, 8309.875351869727, 9098.954505227464, 9272.304577156707, 10718.356898586071, 10957.452127393639, 11013.724656883602, 11036.668899932974, 11095.611653592126, 11106.389148596823, 11097.33415995043, 11177.049950754195, 11214.10287177024, 11218.887811121866, 11229.719257018558, 11021.779564408707, 11033.958717637262, 11058.778824423467, 11130.16523760717, 11133.7216569586, 10873.075788557982, 11005.724856878642, 11011.339319640847, 11011.944940275247, 11012.50443730034, 44353.78231088433, 44486.0, 44908.0, 45023.32465013027, 45027.0, 3601.8019008954498, 3729.8321575529167, 3761.37937240355, 3787.715921305913, 4193.433886425354, 33302.16744581381, 33346.666533334595, 34036.14909627279, 34049.829750851335, 35552.822235888656, 3905.863294784669, 3923.136909879822, 3995.10110225197, 4038.1519880825063, 4048.0284731664483, 340257.0, 341789.2910535456, 341984.0, 342891.0, 344480.0, 60900.69549652223, 62579.37420625827, 75469.24530754732, 76923.61538192273, 77010.0, 72261.6386918062, 72553.0, 72827.0, 73082.24965377233, 73287.63356183186, 19100.0, 19165.904170479058, 19254.22983080689, 19268.22927082929, 19313.51716207106, 13655.180689158682, 13672.794908076386, 13673.86326136726, 13752.449902004008, 13799.51701690436, 22906.65640015401, 23225.419364515685, 23312.3006309811, 23328.53342933166, 23376.0, 8717.607707653171, 8931.73204803857, 8956.373053886166, 8995.865062024075, 9103.408278462011, 966.8839739231337, 1013.8834034086027, 1123.9269447486051, 1169.473736818435, 1211.5214490276292, 29727.702722972925, 30463.847680761897, 30468.238294042825, 30539.847300763355, 31339.686603133676, 1118.5693507999315, 1130.796456637813, 1221.3587866370049, 1249.506444954238, 1258.572085490919, 13818.309084545732, 16130.435434759962, 16143.838561614468, 16231.541524742202, 16586.253618587194, 264967.0, 265474.0, 265924.0, 267556.0, 268064.0, 64441.35558644447, 64802.351976480575, 64911.0, 64972.67513662496, 65184.348156518776, 1336.7259711759066, 1429.5997120806192, 1437.022824479364, 1463.5389852196524, 1485.049568276304, 33736.49395259024, 33777.83111084429, 34000.48999264964, 34203.0, 34221.657783422845, 56308.43691563196, 57375.713121435794, 57424.71287643535, 57445.42554574568, 57478.7126064367, 350629.0, 353421.0, 354560.0, 354775.0, 355995.0, 37284.0, 37464.0, 37619.62380376161, 37915.81042094772, 52056.21915671192, 49294.26058609196, 49409.25886111783, 50085.0, 50575.0, 50623.0, 41487.170256594094, 42114.57885421229, 42371.364429534195, 42952.78523607362, 43050.0, 41630.58369416267, 41996.0, 42099.0, 42764.572354277305, 42945.35581966335, 42010.0, 42177.57822421737, 42248.36627450652, 42966.57033429626, 43821.0, 35226.47160292649, 35734.02779856442, 35985.820070900525, 36005.27989440249, 36038.8198059008, 28052.726421950952, 28225.294367640974, 28397.620629205256, 28505.572416414187, 28650.0], "unit": "ms", "name": "ext.html", "value": 21285.406866000285}, {"lowerIsBetter": false, "replicates": [385.7396257526169, 388.7881104797886, 389.2176724783185, 389.39956777643897, 392.16468921197844, 13885.004195468895, 13915.582532524015, 18085.276588936442, 20453.18187272509, 20459.488512787168, 13286.867131328683, 13724.519641812538, 13726.245056521888, 18702.345417910376, 19322.613547729074, 4875.049365373745, 4991.475895031024, 4994.350734404522, 4999.150144475429, 4999.675021123633, 4927.827526036589, 4939.110960027194, 4940.0, 4953.0, 4954.529319714621, 3821.808909554525, 3874.612538746117, 4898.191798353268, 4899.755012249391, 4902.436219834729, 5257.421683614799, 5339.145736682151, 5394.703291318976, 5395.109806881879, 5395.136778115522, 4425.845095421677, 4673.392458980345, 4674.929876051862, 4741.31250968611, 4744.335792988984, 15471.613209669737, 15555.300011499396, 15565.844341556665, 15835.762463563056, 15848.76226856598, 2105.336818902041, 2173.521825198454, 2215.29110684581, 2233.3634914049417, 2241.2827895073583, 11155.107591392669, 11171.385573793437, 11248.0, 11301.830472542919, 11356.602518911799, 1801.846843018345, 1858.6840237159715, 1896.3173257627243, 1902.7906930237664, 1917.0702209428416, 64586.0, 66452.66773666149, 67680.6615966922, 67975.0, 68523.31476685219, 7107.857842843114, 7400.888986665204, 7582.203868593829, 7625.6568454419685, 7649.158592554814, 9138.725838224864, 9192.356535042616, 9223.86164207524, 9229.400088994194, 9249.306302027375, 6284.842878927972, 6340.714667839959, 6362.77730279438, 6403.167588213502, 6405.327440618761, 6767.966160169266, 6774.356436138559, 6804.863902721918, 6813.0, 6842.04211410403, 3418.8461519231696, 3478.217401084786, 3513.4905438711244, 3529.7882127072457, 3534.823258837048, 1268.5179631740064, 1273.8089286607083, 1287.3691890973544, 1296.950817708019, 1325.9602211933463, 456.64838074682973, 474.49466318370827, 491.9237518184687, 498.12827551784375, 541.0531569752933, 12694.682632934066, 12714.87285127137, 13136.40886160125, 13158.539451119164, 13213.933930330288, 468.42149944818124, 521.2155705663029, 526.8314139475331, 532.421370636066, 574.8821491594213, 7021.0, 7113.64431778409, 7177.856442871113, 7205.89191162133, 7207.639618019078, 434976.0, 435394.0, 437507.0, 439349.0, 615078.0, 72437.0, 75285.0, 78348.60825695835, 79727.60136199281, 83191.0, 556.9025420551387, 569.2201683693366, 569.2770181869085, 571.325835514089, 573.0315766354847, 11502.137339699724, 11573.305601663928, 11676.649700508999, 11751.823722644338, 11858.169928105295, 35385.0, 35887.64112358843, 35919.46120808242, 36176.0, 36547.63452365442, 50445.49554504408, 50599.241011384125, 50729.0, 50954.0, 52101.7394913023, 7780.299773020455, 7838.098618658699, 7845.097813751491, 7849.5682737447705, 7919.049714034353, 13014.0, 13211.537596184087, 14117.576472705836, 14133.434662613381, 14245.928770356082, 11749.060075194135, 11916.344601047025, 11928.343941082976, 12158.209716368408, 12646.177998430072, 9731.318807683394, 10735.89264107349, 11291.43542822872, 11509.136814739095, 11790.351530665896, 11502.53989840397, 11595.710107247387, 11721.296722196696, 11813.822792657946, 12014.159008869296, 7395.260473952561, 7412.518186317872, 7447.404207663266, 7451.701931922879, 7451.850962980819, 11496.195266331277, 11578.536858525564, 11626.825597615873, 12005.63983080509, 12103.818442723543], "unit": "ms", "name": "jquery.html", "value": 7489.526575493209}, {"lowerIsBetter": false, "replicates": [1568.929398177082, 1601.847824456676, 1620.570548804567, 1807.3583877723408, 1910.560571068655, 7100.112485939258, 7221.133463984316, 7888.960555197224, 7999.600019999006, 8004.83990320194, 7830.41271904608, 7946.721864734735, 8022.077461091962, 8034.477758945664, 8035.517868927854, 2406.7833894949495, 2428.550718117145, 2432.355425812152, 2437.5124975004983, 2438.2563318187945, 2082.4793801549613, 2130.893455327235, 2392.6530653055297, 2456.091246238894, 2481.4292712676097, 2371.288613415977, 2413.9155129570468, 2415.10641062807, 2417.359399759065, 2417.456072383717, 2410.9638355424595, 2451.497443024176, 2460.7539246075517, 2461.113998960373, 2462.630605409185, 2399.124319623342, 2411.1440438644204, 2424.090965887792, 2427.1990243219693, 2431.7689819467123, 8164.510129392196, 8224.83550328996, 8235.217654322889, 8240.917590824074, 8244.134365891556, 5098.745062746885, 5173.379194496647, 5182.714950677711, 5185.066687996159, 5207.9479205207845, 3213.453712868818, 3213.5661685672367, 3232.5474433579316, 3241.205904553376, 3259.788113772621, 1166.9591564295208, 1179.5812486567318, 1188.114854433444, 1195.3784032303154, 1203.9157258991875, 10796.622118225903, 10835.620753273677, 10866.0, 10870.836937445945, 10880.836787448194, 4507.88730281739, 4533.365328853963, 4554.726716397026, 4579.152856721517, 4589.839355622604, 9453.952730236304, 9476.384035037838, 9486.715398538056, 9523.809523809485, 9538.189253913357, 4916.336294600254, 4920.581750551171, 4923.064617722621, 4926.630502712311, 4926.6551341406475, 4450.287953927421, 4500.594946454835, 4500.909981800346, 4518.209313370147, 4531.864044078683, 3650.543682039745, 3985.80070996449, 4020.3969404589548, 4041.818118184689, 4045.8179381927885, 1223.1560223445795, 1275.5280546197966, 1311.4491913396396, 1317.960461186166, 1321.5969129415428, 1262.4003598290738, 1319.4524272427027, 1321.6761893336002, 1381.647679841634, 1456.3300881594373, 7389.224131466226, 7440.330370266702, 7462.888056679154, 7763.146053934062, 7888.566128862853, 1204.265398107154, 1223.62067758995, 1226.3316492511603, 1232.279116716713, 1248.2697621891114, 6295.401936815995, 6354.618722876642, 6379.075034120026, 6401.967990160112, 6410.294867564564, 9705.6603018894, 9791.461469619097, 9797.412155270707, 9809.901900981042, 9845.852312215322, 9493.145616894513, 9654.806903862023, 9698.75753106178, 9700.417974921527, 9734.951325243328, 7724.729634462767, 7761.0, 7768.883466748003, 7777.727779527576, 7779.961100194463, 7097.219305876246, 7212.278772122746, 7428.071491063617, 7431.8142046449275, 7436.256374362521, 8659.82680346377, 8774.429662071945, 8801.735947921317, 8811.0, 8825.823483530165, 8797.296216302808, 8967.461952282883, 8973.775655608662, 8977.192052715287, 8979.281657467256, 7118.394936430462, 7145.892811607934, 7158.463115266271, 7162.534435261691, 7163.713451461883, 7007.579545227302, 7173.928260717535, 7610.847783044419, 7702.807429814299, 7737.613119343898, 7409.296116868889, 7438.628068596657, 7449.553026818191, 7489.925100748923, 7607.543547387175, 7129.144502659714, 7134.2152363238965, 7158.534695244793, 7294.963525182552, 7321.890171647323, 7448.813779655336, 7512.774616761506, 7550.093988721389, 7552.320291173821, 7587.544747315179, 6728.226253980659, 6775.661216939035, 6776.322367763184, 6782.118324617768, 6786.694598743167, 6889.586624802528, 6897.586144831326, 6910.170779506491, 6910.93089069123, 6921.377076062977], "unit": "ms", "name": "mootools.html", "value": 4758.131008285698}, {"lowerIsBetter": false, "replicates": [361.142287068213, 361.5065435680297, 362.5649220934877, 365.73118757713087, 366.91194113412774, 5911.674857882815, 5990.131430942511, 6003.819885403434, 6004.069369247763, 6026.608270462417, 5848.590598658096, 5908.940910590893, 5922.0, 5934.287885453751, 5961.910571341423, 4657.790399432017, 4657.88355291117, 4658.138244424776, 4661.58045775881, 4665.0669866026765, 4530.275155975045, 4589.219832628444, 4611.0, 4657.371254880599, 4677.321788340689, 4604.815807367701, 4606.976965115186, 4608.470025947009, 4621.798332433575, 4624.445066592014, 4604.769761511928, 4618.237990731525, 4628.652851036186, 4629.444466663988, 4632.235681112612, 4104.897377565555, 4110.91778164438, 4115.794210289503, 4115.938260926088, 4124.298869192245, 5611.831645050655, 5696.515796157331, 5708.457696518865, 5764.481196692318, 5797.246357973437, 5461.590380721422, 5461.69960652164, 5465.125579907196, 5466.0, 5472.671639701631, 6120.234970628671, 6388.297287298394, 6392.55252132351, 6395.16862807832, 6397.52018598607, 4504.031633198878, 4561.521040290788, 4645.488996210414, 4645.814167433333, 4651.023285110097, 6611.140551728245, 6625.436837868787, 6631.336866313378, 6634.867302653968, 6641.966790166067, 4718.197906355859, 4743.430788305425, 4748.905021899612, 4751.809927602857, 4754.072955773591, 4740.336352910595, 4816.181249187646, 4816.831410900601, 4821.2045012572535, 4838.080764654775, 5463.535599474009, 5464.617476776588, 5466.098093814556, 5471.671699698031, 5476.616636835383, 6424.453921416732, 6426.614403135827, 6426.967865160645, 6428.678566071678, 6434.067060276234, 1727.464486009341, 1751.4658029301186, 1754.649070185968, 1754.798198207197, 1754.815744346825, 1126.566271985275, 1136.1705954652984, 1136.886311368873, 1138.5901075612774, 1198.3109711915508, 6177.876442471125, 6189.6286222825875, 6196.659183744938, 6196.783112591038, 6196.783112591038, 6485.286618471964, 6518.57629254097, 6521.706523206467, 6522.64125473094, 6533.673316334164, 6110.114033465123, 6162.599431036968, 6162.630242185483, 6171.13604095427, 6173.629582225081, 3547.4856145858707, 3624.818759062089, 3658.487811706363, 3661.3958696814725, 3662.6520480554304, 6799.4900382471515, 6809.148856392951, 6817.659117044128, 6817.931820681829, 6828.283030281848, 6789.694463749044, 6790.354916282945, 6790.830229244309, 6791.320867913169, 6795.456363490909, 6656.800295991031, 6720.327967203339, 6785.321467853175, 6795.0, 6811.978203269549, 6410.519211059096, 6493.350664933469, 6539.11721917554, 6608.966955165193, 6609.768658096944, 6502.869942601027, 6531.085648009377, 6534.019897015486, 6545.37818907203, 6555.639439830855, 6762.661866906733, 6783.728650854108, 6789.864202715819, 6790.93209067903, 6801.693923773343, 4462.933056004098, 4513.932291015703, 4517.435320584927, 4543.522930092425, 4547.2269714148015, 6514.837129071621, 6594.175728033996, 6606.042123892104, 6608.107905432705, 6612.867742645024, 6463.030545418225, 6463.482921366185, 6471.288158302489, 6472.546921715435, 6475.320091390525, 6339.270983836917, 6451.903221451776, 6467.191601049869, 6469.191351081115, 6469.45009674183, 6338.397852204033, 6344.873102537831, 6346.3019067903415, 6352.745890164527, 6357.809265722036, 6510.869782604416, 6537.248216455168, 6538.509611779041, 6542.738290468328, 6543.443807276435, 6297.653629050446, 6374.426301632875, 6390.137331460193, 6390.233172019201, 6392.64840433762], "unit": "ms", "name": "prototype.html", "value": 4885.564171220894}, {"lowerIsBetter": false, "replicates": [767.5855038279333, 772.5712229712507, 863.6070587882514, 946.5976959792087, 950.8954015058343, 13424.932875335622, 13779.035467517253, 13782.44870205192, 13890.097143685653, 13942.651433714162, 13072.607821765338, 13111.34443277837, 13145.211287322767, 13171.60485185446, 13208.273544955024, 748.3825843678959, 748.6443939128898, 895.4448242089913, 916.083916083916, 929.2658799548349, 917.1104029091771, 917.6100157433098, 922.3036607361432, 923.9029901860308, 926.3515539122608, 951.5908159491416, 959.9568019439107, 960.2893858544696, 960.4237457525498, 961.1349785193312, 781.577947908129, 781.6443518199205, 945.5461378538306, 964.7781010367585, 966.5891995901784, 713.1192976673804, 714.3178264757145, 716.5664772812426, 716.6810769207652, 716.870963226619, 14642.41430342785, 14724.19016954067, 14740.410383584644, 14749.188794616291, 14763.704725905422, 12398.194117382342, 12475.0, 12530.498780048787, 13378.39797209128, 14158.929205353907, 10306.123979461754, 10387.792244155075, 10398.896011039868, 10672.146228301717, 10963.890361096366, 3390.5253264542985, 3457.6023757267985, 3464.2378676691087, 3464.9653503464897, 3465.636108208627, 45200.54799452029, 46497.30254046192, 50936.49063509355, 51250.487495124944, 51425.0, 8145.0, 8149.0, 8158.551279679556, 8178.754637360888, 8179.591020449073, 12889.09776315649, 12911.22532648044, 12920.483180672667, 12930.67673308156, 12935.0, 6955.304469553004, 7297.1608265049135, 7576.052993375828, 7637.15991240963, 7682.884756728654, 4332.328489084197, 4534.478534968521, 4580.083983203373, 4590.88522786933, 4597.540245975376, 3086.583311252952, 3123.6876312369036, 3131.232847952266, 3136.5765621640785, 3137.450946084426, 484.8739327774733, 495.7917674576614, 496.1936852614501, 549.1323708540497, 568.1591244957486, 683.3815397065664, 702.9015937768717, 711.8896571031395, 732.783828770514, 733.9229380915034, 8652.610632521426, 8967.237784788365, 9060.864087038699, 9310.627574896927, 9448.433094014381, 563.3493315220926, 702.4205030849467, 710.531049507333, 712.6721708014268, 742.3912391838749, 3992.640662340345, 4067.4102255172834, 4155.792210389468, 4217.472815898012, 4244.1723863846255, 69086.0, 69124.65437672779, 69957.65021174963, 70683.0, 71027.0, 59974.40025599688, 60924.390756091874, 61456.692716536134, 61465.0, 62922.685386572775, 56000.71999640057, 56045.43954560484, 56250.43749562534, 56344.718276408355, 56797.7160114205, 7653.502522336031, 7656.6554505048525, 7666.19504951972, 7727.304542591194, 7807.102183248999, 38939.80530097331, 39450.40824387694, 40238.79880600578, 40533.59466405298, 41370.37944430776, 52968.73515632397, 55071.72464137654, 55097.0, 55745.44254557403, 56006.719966399905, 7643.350315223268, 7688.961555192188, 8004.51972881629, 8032.638531266224, 8221.917780822354, 11499.482523286304, 11730.589429369931, 11812.527498899948, 11901.285922844658, 11952.103592230444, 10924.617638382619, 11023.338599684044, 11098.944505277423, 11459.140564457532, 11470.885291146982, 11601.18791684574, 11610.187286889837, 11623.535058597561, 11882.227655202385, 11989.0, 10565.735856603646, 10962.835557466486, 11089.27919685218, 11098.500567474644, 11497.367644779617, 9994.250431217833, 10024.699259022242, 10147.238957078102, 10333.638322658671, 10402.0, 9706.70879873605, 9922.156616687662, 10229.641962531276, 10438.164946804085, 10455.16358691318], "unit": "ms", "name": "yui.html", "value": 6468.649905043363}], "name": "dromaeo_css", "value": 7130.699555543019}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 103.0, 109.0, 110.0, 110.0, 101.0, 109.0, 113.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 108.2}, {"lowerIsBetter": true, "replicates": [148.0, 129.0, 157.0, 166.0, 131.0, 148.0, 145.0, 125.0, 161.0, 129.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.9}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 162.0, 161.0, 159.0, 162.0, 159.0, 160.0, 160.0, 158.0], "unit": "ms", "name": "audio-dft", "value": 160.3}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 71.0, 137.0, 72.0, 72.0, 71.0, 72.0, 150.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 85.9}, {"lowerIsBetter": true, "replicates": [89.0, 89.0, 132.0, 89.0, 90.0, 89.0, 132.0, 90.0, 89.0, 132.0], "unit": "ms", "name": "audio-oscillator", "value": 102.1}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.6}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 146.0, 147.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 96.0, 95.0, 96.0, 97.0, 96.0, 96.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.7}, {"lowerIsBetter": true, "replicates": [52.0, 53.0, 55.0, 55.0, 54.0, 56.0, 66.0, 54.0, 52.0, 52.0], "unit": "ms", "name": "json-parse-financial", "value": 54.9}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 62.0, 62.0, 63.0, 64.0, 64.0, 64.0, 67.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.5}, {"lowerIsBetter": true, "replicates": [78.0, 78.0, 82.0, 79.0, 79.0, 79.0, 83.0, 79.0, 78.0, 80.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.5}, {"lowerIsBetter": true, "replicates": [119.0, 111.0, 108.0, 108.0, 116.0, 113.0, 108.0, 107.0, 109.0, 110.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 110.9}, {"lowerIsBetter": true, "replicates": [135.0, 134.0, 134.0, 135.0, 133.0, 147.0, 134.0, 137.0, 136.0, 134.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 135.9}, {"lowerIsBetter": true, "replicates": [65.0, 62.0, 61.0, 62.0, 62.0, 63.0, 61.0, 60.0, 62.0, 61.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.9}], "name": "kraken", "value": 1457.7000000000003}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.21201240411295], "unit": "score", "name": "Richards", "value": 216.29535399999995}, {"lowerIsBetter": false, "replicates": [170.64846416382252], "unit": "score", "name": "DeltaBlue", "value": 387.45148000000006}, {"lowerIsBetter": false, "replicates": [279.4076557697681], "unit": "score", "name": "Encrypt", "value": 952.6617989999997}, {"lowerIsBetter": false, "replicates": [4990.049751243781], "unit": "score", "name": "Decrypt", "value": 53.34235393818545}, {"lowerIsBetter": false, "replicates": [956.0229445506692], "unit": "score", "name": "RayTrace", "value": 774.0284939999998}, {"lowerIsBetter": false, "replicates": [557.4136008918617], "unit": "score", "name": "Earley", "value": 1195.6346220000003}, {"lowerIsBetter": false, "replicates": [10562.5], "unit": "score", "name": "Boyer", "value": 63.09708875739642}, {"lowerIsBetter": false, "replicates": [23904.761904761905], "unit": "score", "name": "RegExp", "value": 38.10893426294821}, {"lowerIsBetter": false, "replicates": [528.2722513089005], "unit": "score", "name": "Splay", "value": 154.25947472745298}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 23268.576107114248}]} 15:21:36 INFO - RETURN: dromaeo_css: 7130.70 15:21:36 INFO - RETURN: kraken: 1457.70 15:21:36 INFO - RETURN: v8_7: 23268.58 15:21:36 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23268.58"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1457.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7130.70"}}} 15:21:36 INFO - Return code: 0 15:21:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 15:21:36 INFO - # TBPL SUCCESS # 15:21:36 INFO - Running post-action listener: _resource_record_post_action 15:21:36 INFO - Running post-run listener: _resource_record_post_run 15:21:37 INFO - Total resource usage - Wall time: 1222s; CPU: 13.0%; Read bytes: 315392; Write bytes: 272027648; Read time: 48; Write time: 210124 15:21:37 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 13832192; Read time: 0; Write time: 6460 15:21:37 INFO - run-tests - Wall time: 1208s; CPU: 13.0%; Read bytes: 315392; Write bytes: 257191936; Read time: 48; Write time: 203632 15:21:37 INFO - Running post-run listener: _upload_blobber_files 15:21:37 INFO - Blob upload gear active. 15:21:37 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 15:21:37 INFO - Copying logs to upload dir... 15:21:37 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1266.619927 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-01-05 15:21:37.516285) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-05 15:21:37.519816) ========= 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=c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010034 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452026701/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-05 15:21:37.573352) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 15:21:37.573894) ========= 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=c6927cf182745ad25b1d38b9000001fe-1452034524.288243-406464010 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004544 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 15:21:37.623540) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-05 15:21:37.623877) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-05 15:21:37.624212) ========= ========= Total master_lag: 0.27 =========