builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-028 starttime: 1456439543.43 results: success (0) buildid: 20160225122425 builduid: bfd3f934d1cd4692888631c6a1611817 revision: bfd1ccbb2e46 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:23.429037) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:23.429564) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:23.429852) ========= 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=836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.008414 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:23.451894) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:23.452184) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:23.464871) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:23.465148) ========= 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=836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004685 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:23.514205) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:23.514509) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:23.514879) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:23.515147) ========= 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=836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-25 14:32:23-- 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% 40.1M=0s 2016-02-25 14:32:23 (40.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.101390 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:23.628785) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:23.629091) ========= 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=836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021180 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:23.678065) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:23.678483) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bfd1ccbb2e46 --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 bfd1ccbb2e46 --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=836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-25 14:32:24,035 Setting DEBUG logging. 2016-02-25 14:32:24,035 attempt 1/10 2016-02-25 14:32:24,035 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/bfd1ccbb2e46?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-25 14:32:24,311 unpacking tar archive at: mozilla-beta-bfd1ccbb2e46/testing/mozharness/ program finished with exit code 0 elapsedTime=0.587870 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:24.278924) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:24.279329) ========= echo bfd1ccbb2e46 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'bfd1ccbb2e46'] 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=836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False bfd1ccbb2e46 program finished with exit code 0 elapsedTime=0.004229 script_repo_revision: 'bfd1ccbb2e46' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:24.329070) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:24.329419) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 14:32:24.339009) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-02-25 14:32:24.339360) ========= /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=836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:32:24 INFO - MultiFileLogger online at 20160225 14:32:24 in /builds/slave/test-pgo 14:32:24 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 14:32:24 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 14:32:24 INFO - {'append_to_log': False, 14:32:24 INFO - 'base_work_dir': '/builds/slave/test-pgo', 14:32:24 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:32:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 14:32:24 INFO - 'branch': 'Mozilla-Beta', 14:32:24 INFO - 'buildbot_json_path': 'buildprops.json', 14:32:24 INFO - 'config_files': ('talos/linux_config.py',), 14:32:24 INFO - 'default_actions': ('clobber', 14:32:24 INFO - 'read-buildbot-config', 14:32:24 INFO - 'download-and-extract', 14:32:24 INFO - 'populate-webroot', 14:32:24 INFO - 'create-virtualenv', 14:32:24 INFO - 'install', 14:32:24 INFO - 'run-tests'), 14:32:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:32:24 INFO - 'download_minidump_stackwalk': True, 14:32:24 INFO - 'download_symbols': 'ondemand', 14:32:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:32:24 INFO - 'tooltool.py': '/tools/tooltool.py', 14:32:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:32:24 INFO - '/tools/misc-python/virtualenv.py')}, 14:32:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:32:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:32:24 INFO - 'installer_path': 'installer.exe', 14:32:24 INFO - 'log_level': 'info', 14:32:24 INFO - 'log_name': 'talos', 14:32:24 INFO - 'log_to_console': True, 14:32:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:32:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:32:24 INFO - 'opt_config_files': (), 14:32:24 INFO - 'pip_index': False, 14:32:24 INFO - 'sps_profile': False, 14:32:24 INFO - 'sps_profile_interval': 0, 14:32:24 INFO - 'suite': 'dromaeojs', 14:32:24 INFO - 'system_bits': '32', 14:32:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:32:24 INFO - 'title': 'talos-linux64-ix-028', 14:32:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:32:24 INFO - 'use_talos_json': True, 14:32:24 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 14:32:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:32:24 INFO - 'work_dir': 'build'} 14:32:24 INFO - ##### 14:32:24 INFO - ##### Running clobber step. 14:32:24 INFO - ##### 14:32:24 INFO - Running pre-action listener: _resource_record_pre_action 14:32:24 INFO - Running main action method: clobber 14:32:24 INFO - rmtree: /builds/slave/test-pgo/build 14:32:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 14:32:25 INFO - Running post-action listener: _resource_record_post_action 14:32:25 INFO - ##### 14:32:25 INFO - ##### Running read-buildbot-config step. 14:32:25 INFO - ##### 14:32:25 INFO - Running pre-action listener: _resource_record_pre_action 14:32:25 INFO - Running main action method: read_buildbot_config 14:32:25 INFO - Using buildbot properties: 14:32:25 INFO - { 14:32:25 INFO - "properties": { 14:32:25 INFO - "buildnumber": 102, 14:32:25 INFO - "product": "firefox", 14:32:25 INFO - "script_repo_revision": "production", 14:32:25 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 14:32:25 INFO - "repository": "", 14:32:25 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 14:32:25 INFO - "buildid": "20160225122425", 14:32:25 INFO - "slavename": "talos-linux64-ix-028", 14:32:25 INFO - "pgo_build": "True", 14:32:25 INFO - "basedir": "/builds/slave/test-pgo", 14:32:25 INFO - "project": "", 14:32:25 INFO - "platform": "ubuntu64_hw", 14:32:25 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:32:25 INFO - "slavebuilddir": "test", 14:32:25 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 14:32:25 INFO - "branch": "mozilla-beta", 14:32:25 INFO - "repo_path": "releases/mozilla-beta", 14:32:25 INFO - "stage_platform": "linux64-pgo", 14:32:25 INFO - "builduid": "bfd3f934d1cd4692888631c6a1611817", 14:32:25 INFO - "revision": "bfd1ccbb2e46" 14:32:25 INFO - }, 14:32:25 INFO - "sourcestamp": { 14:32:25 INFO - "repository": "", 14:32:25 INFO - "hasPatch": false, 14:32:25 INFO - "project": "", 14:32:25 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 14:32:25 INFO - "changes": [ 14:32:25 INFO - { 14:32:25 INFO - "category": null, 14:32:25 INFO - "files": [ 14:32:25 INFO - { 14:32:25 INFO - "url": null, 14:32:25 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/firefox-45.0.en-US.linux-x86_64.tar.bz2" 14:32:25 INFO - } 14:32:25 INFO - ], 14:32:25 INFO - "repository": "", 14:32:25 INFO - "rev": "bfd1ccbb2e46", 14:32:25 INFO - "who": "sendchange", 14:32:25 INFO - "when": 1456439506, 14:32:25 INFO - "number": 7655635, 14:32:25 INFO - "comments": "Bug 1085575: Account for another potential race when asserting metadata is consistent. r=bent a=sylvestre", 14:32:25 INFO - "project": "", 14:32:25 INFO - "at": "Thu 25 Feb 2016 14:31:46", 14:32:25 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 14:32:25 INFO - "revlink": "", 14:32:25 INFO - "properties": [ 14:32:25 INFO - [ 14:32:25 INFO - "buildid", 14:32:25 INFO - "20160225122425", 14:32:25 INFO - "Change" 14:32:25 INFO - ], 14:32:25 INFO - [ 14:32:25 INFO - "builduid", 14:32:25 INFO - "bfd3f934d1cd4692888631c6a1611817", 14:32:25 INFO - "Change" 14:32:25 INFO - ], 14:32:25 INFO - [ 14:32:25 INFO - "pgo_build", 14:32:25 INFO - "True", 14:32:25 INFO - "Change" 14:32:25 INFO - ] 14:32:25 INFO - ], 14:32:25 INFO - "revision": "bfd1ccbb2e46" 14:32:25 INFO - } 14:32:25 INFO - ], 14:32:25 INFO - "revision": "bfd1ccbb2e46" 14:32:25 INFO - } 14:32:25 INFO - } 14:32:25 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/firefox-45.0.en-US.linux-x86_64.tar.bz2. 14:32:25 INFO - Running post-action listener: _resource_record_post_action 14:32:25 INFO - ##### 14:32:25 INFO - ##### Running download-and-extract step. 14:32:25 INFO - ##### 14:32:25 INFO - Running pre-action listener: _resource_record_pre_action 14:32:25 INFO - Running main action method: download_and_extract 14:32:25 INFO - mkdir: /builds/slave/test-pgo/build/tests 14:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:32:25 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/test_packages.json 14:32:25 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 14:32:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 14:32:25 INFO - Downloaded 1270 bytes. 14:32:25 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 14:32:25 INFO - Using the following test package requirements: 14:32:25 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 14:32:25 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 14:32:25 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 14:32:25 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 14:32:25 INFO - u'jsshell-linux-x86_64.zip'], 14:32:25 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 14:32:25 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 14:32:25 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 14:32:25 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 14:32:25 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 14:32:25 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 14:32:25 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 14:32:25 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 14:32:25 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 14:32:25 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 14:32:25 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 14:32:25 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 14:32:25 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:32:25 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/firefox-45.0.en-US.linux-x86_64.common.tests.zip 14:32:25 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 14:32:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:32:25 INFO - Downloaded 21584300 bytes. 14:32:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 14:32:25 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 14:32:25 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 14:32:26 INFO - Return code: 0 14:32:26 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:32:26 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/firefox-45.0.en-US.linux-x86_64.common.tests.zip 14:32:26 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 14:32:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:32:27 INFO - Downloaded 21584300 bytes. 14:32:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 14:32:27 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 14:32:27 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 14:32:28 INFO - Return code: 0 14:32: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')]} 14:32:28 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 14:32:28 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 14:32:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:32:30 INFO - Downloaded 11132038 bytes. 14:32:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 14:32:30 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 14:32:30 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 14:32:30 INFO - Return code: 0 14:32:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:32:30 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/firefox-45.0.en-US.linux-x86_64.tar.bz2 14:32:30 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 14:32:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:32:31 INFO - Downloaded 57951914 bytes. 14:32:31 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/firefox-45.0.en-US.linux-x86_64.tar.bz2 14:32:31 INFO - mkdir: /builds/slave/test-pgo/properties 14:32:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 14:32:31 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 14:32:31 INFO - Contents: 14:32:31 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/firefox-45.0.en-US.linux-x86_64.tar.bz2 14:32:31 INFO - Running post-action listener: _resource_record_post_action 14:32:31 INFO - Running post-action listener: set_extra_try_arguments 14:32:31 INFO - ##### 14:32:31 INFO - ##### Running populate-webroot step. 14:32:31 INFO - ##### 14:32:31 INFO - Running pre-action listener: _resource_record_pre_action 14:32:31 INFO - Running main action method: populate_webroot 14:32:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:32:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:32:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:32:31 INFO - '--tppagecycles', 14:32:31 INFO - '7'], 14:32:31 INFO - 'tests': ['tsvgm']}}, 14:32:31 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 14:32:31 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 14:32:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:32:31 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:32:31 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 14:32:31 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:32:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:32:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:32:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:32:31 INFO - 'talos_options': ['--e10s'], 14:32:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:32:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:32:31 INFO - 'tests': ['damp', 'tps']}, 14:32:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:32:31 INFO - 'talos_options': ['--e10s'], 14:32:31 INFO - 'tests': ['damp', 'tps']}, 14:32:31 INFO - 'g3': {'tests': ['dromaeo_dom']}, 14:32:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:32:31 INFO - 'other': {'tests': ['a11yr', 14:32:31 INFO - 'ts_paint', 14:32:31 INFO - 'tpaint', 14:32:31 INFO - 'sessionrestore', 14:32:31 INFO - 'sessionrestore_no_auto_restore']}, 14:32:31 INFO - 'other-e10s': {'talos_options': ['--e10s'], 14:32:31 INFO - 'tests': ['a11yr', 14:32:31 INFO - 'ts_paint', 14:32:31 INFO - 'tpaint', 14:32:31 INFO - 'sessionrestore', 14:32:31 INFO - 'sessionrestore_no_auto_restore']}, 14:32:31 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 14:32:31 INFO - 'tests': ['a11yr', 14:32:31 INFO - 'ts_paint', 14:32:31 INFO - 'tpaint', 14:32:31 INFO - 'sessionrestore', 14:32:31 INFO - 'sessionrestore_no_auto_restore']}, 14:32:31 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 14:32:31 INFO - 'tests': ['a11yr', 14:32:31 INFO - 'ts_paint', 14:32:31 INFO - 'tpaint', 14:32:31 INFO - 'sessionrestore', 14:32:31 INFO - 'sessionrestore_no_auto_restore']}, 14:32:31 INFO - 'other_l64': {'tests': ['a11yr', 14:32:31 INFO - 'ts_paint', 14:32:31 INFO - 'tpaint', 14:32:31 INFO - 'sessionrestore', 14:32:31 INFO - 'sessionrestore_no_auto_restore']}, 14:32:31 INFO - 'other_nol64': {'tests': ['a11yr', 14:32:31 INFO - 'ts_paint', 14:32:31 INFO - 'tpaint', 14:32:31 INFO - 'sessionrestore', 14:32:31 INFO - 'sessionrestore_no_auto_restore']}, 14:32:31 INFO - 'svgr': {'tests': ['tsvgx', 14:32:31 INFO - 'tsvgr_opacity', 14:32:31 INFO - 'tart', 14:32:31 INFO - 'tscrollx', 14:32:31 INFO - 'cart']}, 14:32:31 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 14:32:31 INFO - 'tests': ['tsvgx', 14:32:31 INFO - 'tsvgr_opacity', 14:32:31 INFO - 'tart', 14:32:31 INFO - 'tscrollx', 14:32:31 INFO - 'cart']}, 14:32:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:32:31 INFO - 'tests': ['tp5o']}, 14:32:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:32:31 INFO - 'talos_options': ['--e10s'], 14:32:31 INFO - 'tests': ['tp5o']}, 14:32:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:32:31 INFO - 'talos_options': ['--xperf_path', 14:32:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:32:31 INFO - 'tests': ['tp5n']}, 14:32:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:32:31 INFO - 'talos_options': ['--e10s', 14:32:31 INFO - '--xperf_path', 14:32:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:32:31 INFO - 'tests': ['tp5n']}}, 14:32:31 INFO - 'talos.zip': {'path': '', 14:32:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:32:31 INFO - Running post-action listener: _resource_record_post_action 14:32:31 INFO - ##### 14:32:31 INFO - ##### Running create-virtualenv step. 14:32:31 INFO - ##### 14:32:31 INFO - Running pre-action listener: _resource_record_pre_action 14:32:31 INFO - Running main action method: create_virtualenv 14:32:31 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 14:32:31 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 14:32:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 14:32:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:32:31 INFO - Using real prefix '/usr' 14:32:31 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 14:32:32 INFO - Installing distribute.............................................................................................................................................................................................done. 14:32:34 INFO - Installing pip.................done. 14:32:34 INFO - Return code: 0 14:32:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 14:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:34 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda5c666f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27528a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ae350>, '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 0x28af250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28af6e0>, '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': '836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163', '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 14:32:34 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 14:32:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:32:34 INFO - Using env: {'DISPLAY': ':0', 14:32:34 INFO - 'HOME': '/home/cltbld', 14:32:34 INFO - 'LANG': 'en_US.UTF-8', 14:32:34 INFO - 'LANGUAGE': 'en_US:en', 14:32:34 INFO - 'LOGNAME': 'cltbld', 14:32:34 INFO - 'MAIL': '/var/mail/cltbld', 14:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:34 INFO - 'MOZ_NO_REMOTE': '1', 14:32:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:34 INFO - 'NO_EM_RESTART': '1', 14:32:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:32:34 INFO - 'PWD': '/builds/slave/test-pgo', 14:32:34 INFO - 'SHELL': '/bin/bash', 14:32:34 INFO - 'SHLVL': '1', 14:32:34 INFO - 'TERM': 'linux', 14:32:34 INFO - 'TMOUT': '86400', 14:32:34 INFO - 'USER': 'cltbld', 14:32:34 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163', 14:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:34 INFO - '_': '/tools/buildbot/bin/python'} 14:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:34 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 14:32:34 INFO - Cleaning up... 14:32:34 INFO - Return code: 0 14:32:34 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 14:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:34 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda5c666f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27528a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ae350>, '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 0x28af250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28af6e0>, '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': '836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163', '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 14:32:34 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 14:32:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 14:32:34 INFO - Using env: {'DISPLAY': ':0', 14:32:34 INFO - 'HOME': '/home/cltbld', 14:32:34 INFO - 'LANG': 'en_US.UTF-8', 14:32:34 INFO - 'LANGUAGE': 'en_US:en', 14:32:34 INFO - 'LOGNAME': 'cltbld', 14:32:34 INFO - 'MAIL': '/var/mail/cltbld', 14:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:34 INFO - 'MOZ_NO_REMOTE': '1', 14:32:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:34 INFO - 'NO_EM_RESTART': '1', 14:32:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:32:34 INFO - 'PWD': '/builds/slave/test-pgo', 14:32:34 INFO - 'SHELL': '/bin/bash', 14:32:34 INFO - 'SHLVL': '1', 14:32:34 INFO - 'TERM': 'linux', 14:32:34 INFO - 'TMOUT': '86400', 14:32:34 INFO - 'USER': 'cltbld', 14:32:34 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163', 14:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:34 INFO - '_': '/tools/buildbot/bin/python'} 14:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:34 INFO - Downloading/unpacking psutil>=0.7.1 14:32:34 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 14:32:34 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 14:32:34 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 14:32:34 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 14:32:34 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 14:32:34 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 14:32:36 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 14:32:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:32:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:32:36 INFO - Installing collected packages: psutil 14:32:36 INFO - Running setup.py install for psutil 14:32:36 INFO - building 'psutil._psutil_linux' extension 14:32:36 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 14:32:36 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 14:32:36 INFO - building 'psutil._psutil_posix' extension 14:32:36 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 14:32:36 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 14:32:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:32:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:32:36 INFO - Successfully installed psutil 14:32:36 INFO - Cleaning up... 14:32:37 INFO - Return code: 0 14:32:37 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 14:32: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')]} 14:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32: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')]} 14:32:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:37 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda5c666f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27528a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ae350>, '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 0x28af250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28af6e0>, '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': '836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163', '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 14:32:37 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 14:32:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 14:32:37 INFO - Using env: {'DISPLAY': ':0', 14:32:37 INFO - 'HOME': '/home/cltbld', 14:32:37 INFO - 'LANG': 'en_US.UTF-8', 14:32:37 INFO - 'LANGUAGE': 'en_US:en', 14:32:37 INFO - 'LOGNAME': 'cltbld', 14:32:37 INFO - 'MAIL': '/var/mail/cltbld', 14:32:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:37 INFO - 'MOZ_NO_REMOTE': '1', 14:32:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:37 INFO - 'NO_EM_RESTART': '1', 14:32:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:32:37 INFO - 'PWD': '/builds/slave/test-pgo', 14:32:37 INFO - 'SHELL': '/bin/bash', 14:32:37 INFO - 'SHLVL': '1', 14:32:37 INFO - 'TERM': 'linux', 14:32:37 INFO - 'TMOUT': '86400', 14:32:37 INFO - 'USER': 'cltbld', 14:32:37 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163', 14:32:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:37 INFO - '_': '/tools/buildbot/bin/python'} 14:32:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:37 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:32:37 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 14:32:37 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 14:32:37 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 14:32:37 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 14:32:37 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 14:32:37 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 14:32:39 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:32:39 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:32:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:32:39 INFO - Installing collected packages: mozsystemmonitor 14:32:39 INFO - Running setup.py install for mozsystemmonitor 14:32:39 INFO - Successfully installed mozsystemmonitor 14:32:39 INFO - Cleaning up... 14:32:39 INFO - Return code: 0 14:32:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 14:32:39 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')]} 14:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:39 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')]} 14:32:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:39 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda5c666f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27528a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ae350>, '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 0x28af250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28af6e0>, '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': '836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163', '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 14:32:39 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 14:32:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:32:39 INFO - Using env: {'DISPLAY': ':0', 14:32:39 INFO - 'HOME': '/home/cltbld', 14:32:39 INFO - 'LANG': 'en_US.UTF-8', 14:32:39 INFO - 'LANGUAGE': 'en_US:en', 14:32:39 INFO - 'LOGNAME': 'cltbld', 14:32:39 INFO - 'MAIL': '/var/mail/cltbld', 14:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:39 INFO - 'MOZ_NO_REMOTE': '1', 14:32:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:39 INFO - 'NO_EM_RESTART': '1', 14:32:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:32:39 INFO - 'PWD': '/builds/slave/test-pgo', 14:32:39 INFO - 'SHELL': '/bin/bash', 14:32:39 INFO - 'SHLVL': '1', 14:32:39 INFO - 'TERM': 'linux', 14:32:39 INFO - 'TMOUT': '86400', 14:32:39 INFO - 'USER': 'cltbld', 14:32:39 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163', 14:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:39 INFO - '_': '/tools/buildbot/bin/python'} 14:32:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:39 INFO - Downloading/unpacking blobuploader==1.2.4 14:32:39 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 14:32:39 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 14:32:39 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 14:32:39 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 14:32:39 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 14:32:39 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 14:32:41 INFO - Downloading blobuploader-1.2.4.tar.gz 14:32:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:32:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 14:32:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:41 INFO - Downloading docopt-0.6.1.tar.gz 14:32:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 14:32:42 INFO - Installing collected packages: blobuploader, requests, docopt 14:32:42 INFO - Running setup.py install for blobuploader 14:32:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:32:42 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 14:32:42 INFO - Running setup.py install for requests 14:32:42 INFO - Running setup.py install for docopt 14:32:42 INFO - Successfully installed blobuploader requests docopt 14:32:42 INFO - Cleaning up... 14:32:42 INFO - Return code: 0 14:32:42 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:32:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:42 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda5c666f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27528a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ae350>, '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 0x28af250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28af6e0>, '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': '836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163', '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 14:32:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 14:32:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:32:42 INFO - Using env: {'DISPLAY': ':0', 14:32:42 INFO - 'HOME': '/home/cltbld', 14:32:42 INFO - 'LANG': 'en_US.UTF-8', 14:32:42 INFO - 'LANGUAGE': 'en_US:en', 14:32:42 INFO - 'LOGNAME': 'cltbld', 14:32:42 INFO - 'MAIL': '/var/mail/cltbld', 14:32:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:42 INFO - 'MOZ_NO_REMOTE': '1', 14:32:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:42 INFO - 'NO_EM_RESTART': '1', 14:32:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:32:42 INFO - 'PWD': '/builds/slave/test-pgo', 14:32:42 INFO - 'SHELL': '/bin/bash', 14:32:42 INFO - 'SHLVL': '1', 14:32:42 INFO - 'TERM': 'linux', 14:32:42 INFO - 'TMOUT': '86400', 14:32:42 INFO - 'USER': 'cltbld', 14:32:42 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163', 14:32:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:42 INFO - '_': '/tools/buildbot/bin/python'} 14:32:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:32:42 INFO - Running setup.py (path:/tmp/pip-lqj9C9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:32:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:32:42 INFO - Running setup.py (path:/tmp/pip-5qID_F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:32:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:32:43 INFO - Running setup.py (path:/tmp/pip-bzMXqz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:32:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:32:43 INFO - Running setup.py (path:/tmp/pip-k8uUbH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:32:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:32:43 INFO - Running setup.py (path:/tmp/pip-tHA0pO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:32:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:32:43 INFO - Running setup.py (path:/tmp/pip-5BPFsk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:32:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:32:43 INFO - Running setup.py (path:/tmp/pip-IHATB8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:32:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:32:43 INFO - Running setup.py (path:/tmp/pip-V8a8Mb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:32:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:32:43 INFO - Running setup.py (path:/tmp/pip-sRUmVB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:32:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:32:43 INFO - Running setup.py (path:/tmp/pip-LI7y4z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:32:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:32:43 INFO - Running setup.py (path:/tmp/pip-XiTmie-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:32:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:32:43 INFO - Running setup.py (path:/tmp/pip-8Z3UtC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:32:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:32:43 INFO - Running setup.py (path:/tmp/pip-Mkj6We-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:32:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:32:43 INFO - Running setup.py (path:/tmp/pip-WjU08c-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:32:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:32:43 INFO - Running setup.py (path:/tmp/pip-qHyswD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:32:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:32:43 INFO - Running setup.py (path:/tmp/pip-0KbFzf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:32:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:32:44 INFO - Running setup.py (path:/tmp/pip-1iMZty-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:32:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:32:44 INFO - Running setup.py install for manifestparser 14:32:44 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 14:32:44 INFO - Running setup.py install for mozcrash 14:32:44 INFO - Running setup.py install for mozdebug 14:32:44 INFO - Running setup.py install for mozdevice 14:32:44 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 14:32:44 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 14:32:44 INFO - Running setup.py install for mozfile 14:32:44 INFO - Running setup.py install for mozhttpd 14:32:44 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 14:32:44 INFO - Running setup.py install for mozinfo 14:32:44 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 14:32:44 INFO - Running setup.py install for mozInstall 14:32:44 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 14:32:44 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 14:32:44 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 14:32:44 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 14:32:44 INFO - Running setup.py install for mozleak 14:32:44 INFO - Running setup.py install for mozlog 14:32:45 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 14:32:45 INFO - Running setup.py install for moznetwork 14:32:45 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 14:32:45 INFO - Running setup.py install for mozprocess 14:32:45 INFO - Running setup.py install for mozprofile 14:32:45 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 14:32:45 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 14:32:45 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 14:32:45 INFO - Running setup.py install for mozrunner 14:32:45 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 14:32:45 INFO - Running setup.py install for mozscreenshot 14:32:45 INFO - Running setup.py install for moztest 14:32:45 INFO - Running setup.py install for mozversion 14:32:45 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 14:32:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:32:45 INFO - Cleaning up... 14:32:45 INFO - Return code: 0 14:32:45 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:32:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda5c666f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27528a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ae350>, '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 0x28af250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28af6e0>, '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': '836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163', '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 14:32:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 14:32:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:32:45 INFO - Using env: {'DISPLAY': ':0', 14:32:45 INFO - 'HOME': '/home/cltbld', 14:32:45 INFO - 'LANG': 'en_US.UTF-8', 14:32:45 INFO - 'LANGUAGE': 'en_US:en', 14:32:45 INFO - 'LOGNAME': 'cltbld', 14:32:45 INFO - 'MAIL': '/var/mail/cltbld', 14:32:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:45 INFO - 'MOZ_NO_REMOTE': '1', 14:32:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:45 INFO - 'NO_EM_RESTART': '1', 14:32:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:32:45 INFO - 'PWD': '/builds/slave/test-pgo', 14:32:45 INFO - 'SHELL': '/bin/bash', 14:32:45 INFO - 'SHLVL': '1', 14:32:45 INFO - 'TERM': 'linux', 14:32:45 INFO - 'TMOUT': '86400', 14:32:45 INFO - 'USER': 'cltbld', 14:32:45 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163', 14:32:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:45 INFO - '_': '/tools/buildbot/bin/python'} 14:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:32:46 INFO - Running setup.py (path:/tmp/pip-rOmNER-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:32:46 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)) 14:32:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:32:46 INFO - Running setup.py (path:/tmp/pip-nIG1Xj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:32:46 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)) 14:32:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:32:46 INFO - Running setup.py (path:/tmp/pip-05I1ti-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:32:46 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)) 14:32:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:32:46 INFO - Running setup.py (path:/tmp/pip-ywRNsS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 14:32:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:32:46 INFO - Running setup.py (path:/tmp/pip-uEPapR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:32:46 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)) 14:32:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:32:46 INFO - Running setup.py (path:/tmp/pip-W3ZIlq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:32:46 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)) 14:32:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:32:46 INFO - Running setup.py (path:/tmp/pip-l1TOYl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 14:32:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:32:46 INFO - Running setup.py (path:/tmp/pip-Q53rrX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:32:46 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)) 14:32:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:32:46 INFO - Running setup.py (path:/tmp/pip-duq9Xe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:32:46 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)) 14:32:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:32:46 INFO - Running setup.py (path:/tmp/pip-G2wAvn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 14:32:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:32:46 INFO - Running setup.py (path:/tmp/pip-lXioxW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:32:46 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)) 14:32:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:32:46 INFO - Running setup.py (path:/tmp/pip-TinkMc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:32:46 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)) 14:32:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:32:46 INFO - Running setup.py (path:/tmp/pip-0appTQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:32:47 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)) 14:32:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:32:47 INFO - Running setup.py (path:/tmp/pip-Pjr5oc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:32:47 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)) 14:32:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:32:47 INFO - Running setup.py (path:/tmp/pip-09bDzg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:32:47 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)) 14:32:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:32:47 INFO - Running setup.py (path:/tmp/pip-lnn0Nf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:32:47 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)) 14:32:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:32:47 INFO - Running setup.py (path:/tmp/pip-qFK6y9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:32:47 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)) 14:32:47 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)) 14:32:47 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)) 14:32:47 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)) 14:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 14:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 14:32:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 14:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:49 INFO - Downloading blessings-1.6.tar.gz 14:32:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 14:32:49 INFO - Installing collected packages: blessings 14:32:49 INFO - Running setup.py install for blessings 14:32:49 INFO - Successfully installed blessings 14:32:49 INFO - Cleaning up... 14:32:49 INFO - Return code: 0 14:32:49 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 14:32:49 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:32:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:32:49 INFO - Reading from file tmpfile_stdout 14:32:49 INFO - Current package versions: 14:32:49 INFO - argparse == 1.2.1 14:32:49 INFO - blessings == 1.6 14:32:49 INFO - blobuploader == 1.2.4 14:32:49 INFO - docopt == 0.6.1 14:32:49 INFO - manifestparser == 1.1 14:32:49 INFO - mozInstall == 1.12 14:32:49 INFO - mozcrash == 0.16 14:32:49 INFO - mozdebug == 0.1 14:32:49 INFO - mozdevice == 0.47 14:32:49 INFO - mozfile == 1.2 14:32:49 INFO - mozhttpd == 0.7 14:32:49 INFO - mozinfo == 0.9 14:32:49 INFO - mozleak == 0.1 14:32:49 INFO - mozlog == 3.1 14:32:49 INFO - moznetwork == 0.27 14:32:49 INFO - mozprocess == 0.22 14:32:49 INFO - mozprofile == 0.27 14:32:49 INFO - mozrunner == 6.11 14:32:49 INFO - mozscreenshot == 0.1 14:32:49 INFO - mozsystemmonitor == 0.0 14:32:49 INFO - moztest == 0.7 14:32:49 INFO - mozversion == 1.4 14:32:49 INFO - psutil == 3.1.1 14:32:49 INFO - requests == 1.2.3 14:32:49 INFO - wsgiref == 0.1.2 14:32:49 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:32:49 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')]} 14:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:49 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')]} 14:32:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:49 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda5c666f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27528a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ae350>, '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 0x28af250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28af6e0>, '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': '836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163', '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 14:32:49 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 14:32:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:32:49 INFO - Using env: {'DISPLAY': ':0', 14:32:49 INFO - 'HOME': '/home/cltbld', 14:32:49 INFO - 'LANG': 'en_US.UTF-8', 14:32:49 INFO - 'LANGUAGE': 'en_US:en', 14:32:49 INFO - 'LOGNAME': 'cltbld', 14:32:49 INFO - 'MAIL': '/var/mail/cltbld', 14:32:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:49 INFO - 'MOZ_NO_REMOTE': '1', 14:32:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:49 INFO - 'NO_EM_RESTART': '1', 14:32:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:32:49 INFO - 'PWD': '/builds/slave/test-pgo', 14:32:49 INFO - 'SHELL': '/bin/bash', 14:32:49 INFO - 'SHLVL': '1', 14:32:49 INFO - 'TERM': 'linux', 14:32:49 INFO - 'TMOUT': '86400', 14:32:49 INFO - 'USER': 'cltbld', 14:32:49 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163', 14:32:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:49 INFO - '_': '/tools/buildbot/bin/python'} 14:32:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:49 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)) 14:32:49 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)) 14:32:49 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)) 14:32:49 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)) 14:32:49 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)) 14:32:49 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)) 14:32:49 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)) 14:32:49 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)) 14:32:49 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)) 14:32:49 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)) 14:32:49 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)) 14:32:49 INFO - Cleaning up... 14:32:49 INFO - Return code: 0 14:32:49 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 14:32:49 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')]} 14:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:49 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')]} 14:32:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:49 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda5c666f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27528a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ae350>, '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 0x28af250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28af6e0>, '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': '836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163', '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 14:32:49 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 14:32:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:32:49 INFO - Using env: {'DISPLAY': ':0', 14:32:49 INFO - 'HOME': '/home/cltbld', 14:32:49 INFO - 'LANG': 'en_US.UTF-8', 14:32:49 INFO - 'LANGUAGE': 'en_US:en', 14:32:49 INFO - 'LOGNAME': 'cltbld', 14:32:49 INFO - 'MAIL': '/var/mail/cltbld', 14:32:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:49 INFO - 'MOZ_NO_REMOTE': '1', 14:32:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:49 INFO - 'NO_EM_RESTART': '1', 14:32:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:32:49 INFO - 'PWD': '/builds/slave/test-pgo', 14:32:49 INFO - 'SHELL': '/bin/bash', 14:32:49 INFO - 'SHLVL': '1', 14:32:49 INFO - 'TERM': 'linux', 14:32:49 INFO - 'TMOUT': '86400', 14:32:49 INFO - 'USER': 'cltbld', 14:32:49 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163', 14:32:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:49 INFO - '_': '/tools/buildbot/bin/python'} 14:32:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:50 INFO - Downloading/unpacking jsonschema 14:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:32:51 INFO - Downloading/unpacking functools32 (from jsonschema) 14:32:51 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 14:32:51 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 14:32:51 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 14:32:51 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 14:32:51 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 14:32:51 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 14:32:52 INFO - Downloading functools32-3.2.3-2.tar.gz 14:32:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 14:32:52 INFO - warning: no files found matching '*.txt' 14:32:52 INFO - no previously-included directories found matching 'build' 14:32:52 INFO - no previously-included directories found matching 'dist' 14:32:52 INFO - no previously-included directories found matching '.git*' 14:32:52 INFO - Installing collected packages: jsonschema, functools32 14:32:52 INFO - Running setup.py install for functools32 14:32:52 INFO - warning: no files found matching '*.txt' 14:32:52 INFO - no previously-included directories found matching 'build' 14:32:52 INFO - no previously-included directories found matching 'dist' 14:32:52 INFO - no previously-included directories found matching '.git*' 14:32:52 INFO - Successfully installed jsonschema functools32 14:32:52 INFO - Cleaning up... 14:32:52 INFO - Return code: 0 14:32:52 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 14:32: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')]} 14:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32: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')]} 14:32:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda5c666f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27528a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ae350>, '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 0x28af250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28af6e0>, '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': '836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163', '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 14:32:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 14:32:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 14:32:52 INFO - Using env: {'DISPLAY': ':0', 14:32:52 INFO - 'HOME': '/home/cltbld', 14:32:52 INFO - 'LANG': 'en_US.UTF-8', 14:32:52 INFO - 'LANGUAGE': 'en_US:en', 14:32:52 INFO - 'LOGNAME': 'cltbld', 14:32:52 INFO - 'MAIL': '/var/mail/cltbld', 14:32:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:52 INFO - 'MOZ_NO_REMOTE': '1', 14:32:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:52 INFO - 'NO_EM_RESTART': '1', 14:32:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:32:52 INFO - 'PWD': '/builds/slave/test-pgo', 14:32:52 INFO - 'SHELL': '/bin/bash', 14:32:52 INFO - 'SHLVL': '1', 14:32:52 INFO - 'TERM': 'linux', 14:32:52 INFO - 'TMOUT': '86400', 14:32:52 INFO - 'USER': 'cltbld', 14:32:52 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163', 14:32:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:52 INFO - '_': '/tools/buildbot/bin/python'} 14:32:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:52 INFO - Downloading/unpacking flake8 14:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:54 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:32:54 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:32:54 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 14:32:54 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 14:32:54 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 14:32:54 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 14:32:54 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 14:32:54 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 14:32:54 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:32:54 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:32:54 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 14:32:54 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 14:32:54 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 14:32:54 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 14:32:54 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 14:32:54 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 14:32:54 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:32:54 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 14:32:54 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 14:32:54 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 14:32:54 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 14:32:54 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 14:32:54 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 14:32:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 14:32:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:32:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:32:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:32:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:32:55 INFO - no previously-included directories found matching 'docs/_build' 14:32:55 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:32:55 INFO - Running setup.py install for pep8 14:32:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:32:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:32:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:32:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:32:55 INFO - no previously-included directories found matching 'docs/_build' 14:32:55 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 14:32:55 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:32:55 INFO - Cleaning up... 14:32:55 INFO - Return code: 0 14:32:55 INFO - Running post-action listener: _resource_record_post_action 14:32:55 INFO - Running post-action listener: _start_resource_monitoring 14:32:55 INFO - Starting resource monitoring. 14:32:55 INFO - ##### 14:32:55 INFO - ##### Running install step. 14:32:55 INFO - ##### 14:32:55 INFO - Running pre-action listener: _resource_record_pre_action 14:32:55 INFO - Running main action method: install 14:32:55 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:32:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:32:55 INFO - Reading from file tmpfile_stdout 14:32:55 INFO - Detecting whether we're running mozinstall >=1.0... 14:32:55 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 14:32:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 14:32:55 INFO - Reading from file tmpfile_stdout 14:32:55 INFO - Output received: 14:32:55 INFO - Usage: mozinstall [options] installer 14:32:55 INFO - Options: 14:32:55 INFO - -h, --help show this help message and exit 14:32:55 INFO - -d DEST, --destination=DEST 14:32:55 INFO - Directory to install application into. [default: 14:32:55 INFO - "/builds/slave/test-pgo"] 14:32:55 INFO - --app=APP Application being installed. [default: firefox] 14:32:55 INFO - mkdir: /builds/slave/test-pgo/build/application 14:32:55 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 14:32:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 14:33:10 INFO - Reading from file tmpfile_stdout 14:33:10 INFO - Output received: 14:33:10 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 14:33:10 INFO - Running post-action listener: _resource_record_post_action 14:33:10 INFO - ##### 14:33:10 INFO - ##### Running run-tests step. 14:33:10 INFO - ##### 14:33:10 INFO - Running pre-action listener: _resource_record_pre_action 14:33:10 INFO - Running main action method: run_tests 14:33:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 14:33:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 14:33:10 INFO - Python 2.7.3 14:33:10 INFO - Return code: 0 14:33:10 INFO - grabbing minidump binary from tooltool 14:33:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:10 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 0x28ae350>, '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 0x28af250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28af6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 14:33:10 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 14:33:10 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 14:33:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:33:10 INFO - Return code: 0 14:33:10 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 14:33:10 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 14:33:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 14:33:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 14:33:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 14:33:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 14:33:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 14:33:11 INFO - Return code: 0 14:33:11 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/1456431865/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 14:33:11 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/1456431865/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-028 --webServer localhost 14:33:11 INFO - Using env: {'DISPLAY': ':0', 14:33:11 INFO - 'HOME': '/home/cltbld', 14:33:11 INFO - 'LANG': 'en_US.UTF-8', 14:33:11 INFO - 'LANGUAGE': 'en_US:en', 14:33:11 INFO - 'LOGNAME': 'cltbld', 14:33:11 INFO - 'MAIL': '/var/mail/cltbld', 14:33:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:33:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 14:33:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:11 INFO - 'MOZ_NO_REMOTE': '1', 14:33:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:33:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:33:11 INFO - 'NO_EM_RESTART': '1', 14:33:11 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:33:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:33:11 INFO - 'PWD': '/builds/slave/test-pgo', 14:33:11 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 14:33:11 INFO - 'SHELL': '/bin/bash', 14:33:11 INFO - 'SHLVL': '1', 14:33:11 INFO - 'TERM': 'linux', 14:33:11 INFO - 'TMOUT': '86400', 14:33:11 INFO - 'USER': 'cltbld', 14:33:11 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163', 14:33:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:11 INFO - '_': '/tools/buildbot/bin/python'} 14:33:11 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/1456431865/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--webServer', 'localhost'] with output_timeout 3600 14:33:11 INFO - mozversion INFO | application_buildid: 20160225122425 14:33:11 INFO - mozversion INFO | application_changeset: bfd1ccbb2e46d338249e1875b20701c58fd6f744 14:33:11 INFO - mozversion INFO | application_display_name: Nightly 14:33:11 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:33:11 INFO - mozversion INFO | application_name: Firefox 14:33:11 INFO - mozversion INFO | application_remotingname: firefox 14:33:11 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:33:11 INFO - mozversion INFO | application_vendor: Mozilla 14:33:11 INFO - mozversion INFO | application_version: 45.0 14:33:11 INFO - mozversion INFO | platform_buildid: 20160225122425 14:33:11 INFO - mozversion INFO | platform_changeset: bfd1ccbb2e46d338249e1875b20701c58fd6f744 14:33:11 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:33:11 INFO - mozversion INFO | platform_version: 45.0 14:33:11 INFO - 2016-02-25 14:33:11,537 DEBUG : using testdate: 1456439591 14:33:11 INFO - 2016-02-25 14:33:11,537 DEBUG : actual date: 1456439591 14:33:11 INFO - 2016-02-25 14:33:11,546 INFO : starting webserver on 'localhost:54774' 14:33:11 INFO - 2016-02-25 14:33:11,547 INFO : Starting test suite talos-linux64-ix-028 14:33:11 INFO - 2016-02-25 14:33:11,547 INFO : Starting test dromaeo_css 14:33:11 INFO - 2016-02-25 14:33:11,547 DEBUG : operating with platform_type : linux_ 14:33:11 INFO - 2016-02-25 14:33:11,548 INFO : Initialising browser for dromaeo_css test... 14:33:11 INFO - 2016-02-25 14:33:11,564 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp0otkwB/profile http://localhost:54774/getInfo.html 14:33:15 INFO - 2016-02-25 14:33:15,809 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:33:15 INFO - 2016-02-25 14:33:15,809 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:33:15 INFO - 2016-02-25 14:33:15,809 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:33:15 INFO - 2016-02-25 14:33:15,809 DEBUG : BROWSER_OUTPUT: __metrics 14:33:15 INFO - 2016-02-25 14:33:15,966 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] 14:33:16 INFO - 2016-02-25 14:33:16,778 INFO : Browser initialized. 14:33:16 INFO - 2016-02-25 14:33:16,779 INFO : Running cycle 1/1 for dromaeo_css test... 14:33:16 INFO - 2016-02-25 14:33:16,779 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp0otkwB/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:33:28 INFO - 2016-02-25 14:33:28,434 DEBUG : BROWSER_OUTPUT: RSS: Main: 134213632 14:33:28 INFO - 2016-02-25 14:33:28,434 DEBUG : BROWSER_OUTPUT: 14:34:19 INFO - 2016-02-25 14:34:19,266 DEBUG : BROWSER_OUTPUT: 1456439659266 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] 14:36:27 INFO - 2016-02-25 14:36:27,512 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54774/tests/dromaeo/cssquery-ext.html) 14:36:28 INFO - 2016-02-25 14:36:28,686 DEBUG : BROWSER_OUTPUT: RSS: Main: 251736064 14:36:28 INFO - 2016-02-25 14:36:28,686 DEBUG : BROWSER_OUTPUT: 14:39:30 INFO - 2016-02-25 14:39:30,790 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/dromaeo/cssquery-ext.html (next: http://localhost:54774/tests/dromaeo/cssquery-jquery.html) 14:39:31 INFO - 2016-02-25 14:39:31,356 DEBUG : BROWSER_OUTPUT: RSS: Main: 209121280 14:39:31 INFO - 2016-02-25 14:39:31,356 DEBUG : BROWSER_OUTPUT: 14:42:33 INFO - 2016-02-25 14:42:33,177 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54774/tests/dromaeo/cssquery-mootools.html) 14:42:33 INFO - 2016-02-25 14:42:33,743 DEBUG : BROWSER_OUTPUT: RSS: Main: 208744448 14:42:33 INFO - 2016-02-25 14:42:33,744 DEBUG : BROWSER_OUTPUT: 14:45:35 INFO - 2016-02-25 14:45:35,445 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54774/tests/dromaeo/cssquery-prototype.html) 14:45:36 INFO - 2016-02-25 14:45:36,012 DEBUG : BROWSER_OUTPUT: RSS: Main: 196378624 14:45:36 INFO - 2016-02-25 14:45:36,012 DEBUG : BROWSER_OUTPUT: 14:48:38 INFO - 2016-02-25 14:48:38,355 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54774/tests/dromaeo/cssquery-yui.html) 14:48:38 INFO - 2016-02-25 14:48:38,921 DEBUG : BROWSER_OUTPUT: RSS: Main: 197423104 14:48:38 INFO - 2016-02-25 14:48:38,921 DEBUG : BROWSER_OUTPUT: 14:51:41 INFO - 2016-02-25 14:51:41,121 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/dromaeo/cssquery-yui.html (next: http://localhost:54774/tests/dromaeo/cssquery-dojo.html) 14:51:41 INFO - 2016-02-25 14:51:41,371 DEBUG : BROWSER_OUTPUT: RSS: Main: 240668672 14:51:41 INFO - 2016-02-25 14:51:41,371 DEBUG : BROWSER_OUTPUT: 14:51:41 INFO - 2016-02-25 14:51:41,375 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:51:41 INFO - 2016-02-25 14:51:41,375 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:51:41 INFO - 2016-02-25 14:51:41,375 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:51:41 INFO - 2016-02-25 14:51:41,375 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:51:41 INFO - 2016-02-25 14:51:41,376 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3219.1308346746378;3306.404847127519;3319.1038419626702;3350.63143054264;3371.881984130556;5417.593680473966;5506.476884695952;5520.723963801804;5536.031194540959;5539.9999999999945;5499.587530935186;5539.612227144102;5545.279113715222;5547.667139971604;5561.249231353766;4972.328735620699;5003.699778013312;5040.294358789773;5108.8978220435565;5133.230015497668;4964.627652926027;5005.374328208974;5008.348914641091;5009.874753131183;5012.674176178537;4936.086823937583;4941.283513890484;5015.623828212899;5015.799368025275;5022.924656130161;4981.576565991877;5047.066292735855;5048.1418158913075;5058.570021548173;5064.189729643276;4988.02733466974;5044.268581055763;5059.747012649353;5082.695038297677;5108.616853735947;5207.687538747649;5251.238570407308;5316.707581083039;5360.892782144374;5366.302380690524;5908.113782932552;5935.49548288396;5944.167816505693;5947.167396564441;5952.464278214981;5973.581849270553;5983.371745966698;5984.4613984741145;5984.820455386302;5994.070919007517;5366.141417373241;5464.0711079116645;5467.097928841776;5577.972110139464;5595.328560572717;5930.406959304035;6044.788432404845;6053.182820319289;6054;6054.51563874889;5848.912266316009;5852.356240813507;5856.736446859902;5861.384554621789;5863.67749773758;5610.49505544503;5712.057510510802;5773.740181691834;5945;5949.762009519657;5895.616784909053;5977.641341519523;5977.701114944235;5983.521318294526;5994.940050599525;5933.050711886164;5969.283685957626;5980.73086711099;5992.610480318853;5995.760169593167;4386.846460373936;4429.047754732748;4491.146682130385;4557.111363284128;4574.99350142968;4067.023914260615;4071.7353372030725;4082.5509193988637;4089.652379547772;4108.8356465741035;5623.521796499598;5635.070213414822;5641.661500309994;5643.1535269709875;5662.207290979266;5745;5842.2405087338375;5855.912161317584;5867.647941123546;5973;5637.154426836008;5637.408072152447;5642.351129620064;5642.830715078554;5673.8014169504695;5167.844964650992;5172.7155006475;5176.5858731301405;5183.377994640668;5200.141976573898;5934.732937017845;5964.612300200473;6046.606970546901;6072.028475443908;6077.726502307407;5923.229980102559;5994.28068631767;5994.400559943971;6000.189974353407;6019.578629495893;5959.523238141025;6039.547033972557;6072.635641861502;6095.299040610386;6106.969465152646;5743.195952566728;5835.066389377679;5915.645061296336;5933.792317268875;5967.522598192221;5938.406159384027;5963.761449542143;5964.671943043174;5975.462208401264;5982.0727787193055;5624.268845050117;5781.971090144691;5789.247397838254;5811.476967072984;5812.7674893003805;5693.9430605693415;5772.076467765138;5787.363390027178;5791.623544469596;5820.912686309624;5696.54427645779;5705.087186050212;5706.058500347396;5722.456366645162;5728.713564321851;5573.247611572386;5676.347220069745;5786.884262314646;5861.794837180678;5868.23712917318;5777.133429985368;5808.07070868659;5809.099589563624;5812.128180772918;5816.3892791258 14:51:41 INFO - 2016-02-25 14:51:41,379 DEBUG : BROWSER_OUTPUT: |1;ext.html;570.8287513745876;608.321721280772;615.981520554383;620.9782657606985;651.2673242602073;31171.220719481997;31959;31993.680063199332;41441.378379324335;41598.58401415985;46457;46687.53312466874;46994.76502617491;47047.52952470474;47132;9230.769230769218;9418.576164072632;9420.199283060949;9428.057194280585;9434.339596228267;7830.765077047698;7993.840123197532;11173.329600223971;11453.14101442391;11483.483243254073;10976.670699879016;11018.834717479243;11084.390358530318;11091.944540277287;11101.888981110207;10945.890541094566;10983.450827458675;11035.282706624084;11040;11052.889471105347;10769.407682577455;10873.83689244662;10891.618793342273;10897.891021089767;10900.563977440894;43122.56877431248;43575;43577.12845743099;43586;43592;3694.704423646129;3696.5933747287777;3719.033051406627;3843.7885916274595;4022.658074063708;32610.67389326124;32694;32794.93756127509;32996.83501582501;33174.3365132696;4187.434696315989;4244.617984381421;4269.274223382033;4286.249906266391;4319.611234988866;286974.9999999979;287032;288793;288898;289173;54333;55621;61675.691621541606;62455;63056.68471657612;59289.703551481965;59333.40666593365;59390;59604.70197648984;59610.40389596135;15847.841521584867;15959.122248276457;16052.598685032734;16134;16157.919210403872;11734.589289374831;11852.525898964117;11888.88111118895;11899.405029748477;11902.107341949388;19608.313709020116;19781.20875165006;19821.900890495453;19832;19857.503562411057;7291.708331666674;7398.889016664754;7503.399728021745;7512.0420522454915;7516;1074.2050882347164;1106.6569363497342;1116.2298014370058;1141.9265890063314;1147.8852114788622;26326.548928489683;26742.19773406801;27902.16293511198;27914.72085279162;28697.139085827457;1039.0648416425279;1119.311623351633;1124.42092322454;1137.6075254037344;1151.3437340715711;13418;13658.248796316098;13740.862591374158;15030.849691503163;15992.440264590683;348072;349126;349827;353960;355854;54534.45465545294;54840.177397339074;54894;54900.17649735257;54945.45054549403;1093;1306.3468265867066;1468.8090548228663;1474.1376294867623;1534.77745726869;28364;28664.426711465236;28974.420511590073;29155.62665600177;29297;43157.56842431661;43337.566624333354;43460.56539434565;43588.78205608952;43594;239632;240241;241040;241193;242277;31914.52128218125;31992.520112198803;32001;32059.198520037186;32386.218757968538;42972.57027429686;43234.78382608067;43787.562124378346;44142.55857441513;44524.55475445334;37459.81270093632;37984.430233545965;38532.614673852906;38875.6112438872;39027.21945561016;38086.42870356891;38114.42828357632;38760.80619596884;38939.221215574966;39568.80215598903;34234.31531369408;34690.306193875476;34781.65218347784;34831;34972.47541286832;29857;31056.223594409417;31714.207144821565;32594.185145371557;33777.66222337844;25606;26255.212343629722;26960.595591065758;28642.57036144501;28700 14:51:41 INFO - 2016-02-25 14:51:41,379 DEBUG : BROWSER_OUTPUT: |2;jquery.html;387.6453045463401;388.1286511781052;389.53061560819214;390.8534299637636;392.35261818000316;14633.268336583182;16236.512904612851;17276.395326163587;18879.99999999998;18899.52751181221;15133.31900064497;15160.393584256644;15852.76220856688;18272.360467383645;18637.7204341935;6261.749530018805;6398.840028999289;6421.325760795119;6427.035944608323;6431.614103153803;4654.674172807905;4780.832670856521;4783.617310615143;4785.976070119645;6406.487481001509;6861.794146175623;6901.5859048457005;7094.538854974436;7106;7115.217326094152;7024.613646249454;7046.612436316001;7057.258987806258;7064.364207221349;7075.363217310467;5164.561012313958;5306.310179676656;5310.8141215057285;6774.8983765243065;6777.356151165632;13606.183628982193;13809.792853107212;16835.579110522212;20618.278360257318;20640.896795515928;2193.1885202475187;2194.396540951251;2197.3627647982225;2274.476870319819;2321.860688358704;9496.715098547054;9633.662821801274;9719.56261968213;13262.801057984138;13484.865151348458;1925.2202857842456;1933.8452923766065;1986.5728868293386;1999.6100760351737;2007.1168685778212;64708.35291647071;67338.66330668316;68504.65747671279;69695.30304696939;70096.6495167526;10900.291481053706;11155.27490713101;11245.887541124648;11251.212415130862;11329;14949.70100597982;15033.548993530212;15060.698786024219;15076.472323468626;15092.094474331576;6997.195322537973;7739.303462688273;7844.529328240324;7882.684692612232;7901.446898717035;7376.557406555624;7378.077740282465;7387.556746595222;7417.147028091731;7421.183669796318;4537.568930951555;4697.31888876111;4738.1708201064675;4805.6155507559315;4839.443464001614;1762.6703882845518;1794.3809385761892;1815.1378095404577;1847.6304739052243;1925.711143328484;445.29420867924057;460.5762698317556;460.8133705849136;481.850785875682;1006.7682738409641;11302.773944521228;11456.770864582662;11628.53485860573;11708.590199342982;14068.507602233873;488.0799692579513;488.2164126576864;989.7723523589615;994.472929347447;1018.2719355660738;6045.244344456943;6277.372262773777;10383.273170878118;10455.634052808267;10665.786684266426;647519;647594;664269;668701;671868;88478.55760721155;90665;90959.54520227357;90983.09016909878;92669;513.6507175120879;516.2565905096724;519.1097268185081;519.6570263625989;520.1002266079703;10620.946895265473;10720.678379648623;10877;11094.944525277322;11151.442427878736;39205.41191882181;39541.40687889626;39688.40467392933;40340.1931961365;40468.797656011535;50141.74929125331;51829.22256166236;53519;54011.45988540064;58509.4149058504;10269.486525673836;10472.528736206737;10506.789864202521;10614;10632.946835265775;17582.82417175812;17922.5519362017;17935.461936141935;17995.73006404879;18368.908155459136;15784.447544335893;15842.207889605243;15851.445199418426;15962.12208328553;16261.51215463538;15512;15800.446984355493;15948.362065517249;15949.086109666914;16086.839131609002;11779.646610601352;13072.477100915858;15469.767953480481;16142.67714645724;16460.506184814476;10108.39349639024;10176.338537995007;10293.845592316271;10337.638182663572;10370.637027703995;15350.462733804263;15522.922385388001;15614.609634759221;15641.217939103228;15841.366345346518 14:51:41 INFO - 2016-02-25 14:51:41,383 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1510.2675202526775;1558.2520390212699;1611.6937781821446;1706.6501367219714;1823;7157.928420715792;7449.292317229868;7455.0681164854395;7456.366208872246;7463.813404664879;6585.736570537185;6699.062131301622;7378.74174403896;7489.775306740793;7598.810029749259;2312.3409828198965;2326.5928462519078;2350.3888988862846;2351.235848349285;2351.905923763047;1968.4586738646844;2309;2323.0707716913203;2363.0902102690416;2365.2785900300487;1949.307995661541;2251.909923603054;2293.6674182243564;2295.621222498294;2298;2206.481476852938;2237.049254067015;2244.5286489837154;2254.4251215939994;2269.7503274639776;2222.4888275696517;2245.887705614729;2264.07173059045;2266.46738016566;2266.92065777697;7293.16128645202;7349.963250183715;7370.189279179286;7370.77887663371;7375.963120184419;4642.326862604903;4727.621790256806;4733.148033353995;4738.4313882334;4741.2176990796825;3045.4365942300747;3066.8159910405448;3128.2492201871614;3130.780845340827;3150.732187764043;1034.2398337222135;1051.9053285204293;1064.813657609923;1095.2825899036145;1130.1241537808182;9727.951360243223;9818.950905245429;9844.803103937953;9849.50752462374;9891.30760846741;3984.8007599619905;4012.2176175646055;4219.029623186684;4225.704200705921;4225.830966761357;8330.291925186428;8352.164783521599;8356.28971537414;8357.8328433431;8363.372747043995;4661.20759470884;4663.556962088596;4703.85888423341;4708.411448568929;4712.5758681718125;4305.225059489322;4341.110072435144;4355.477342718831;4361.6074553290355;4362.6946113772365;3289.227031647561;3354.07762865214;3356.278400143981;3388.15296175956;3392.7455440842036;1021.9489025548842;1030.3302853145515;1045.6235755128148;1065.6962765611763;1068.6740544133959;1154.1920655541155;1158.976820463586;1163.6218229075585;1167.3929556630505;1198.5924785572947;7291.08861392326;7417.332440080419;7425.85148297031;7476.775696729107;7488.663010164556;1088.8856670049531;1122.3770807201918;1168.5150662474978;1174.8707642159357;1178.4873579992732;5928.377520360386;5984.910226346608;5987.550933679991;5992.67040312787;5995.52035837132;8913.197812196933;9022.368434209673;9140.497272649935;9155.908440915638;9173.633054677872;8984.955075224712;9070.501122438329;9184.724458266262;9206.401583897024;9209.079092090871;7098.3256590623805;7221.8555628887125;7278.381337586258;7380.77857664271;7384.778456646309;7337.59643219628;7346.191918889025;7350.852982940418;7377.889331660137;7401.370883474965;8144.877826832484;8189.7133600323705;8360;8377.49735015901;8382.874256886029;8554.957225213833;8561.700340488052;8562.443441176303;8582.871256931026;8603.741887743377;6467.838304042437;6533.901991470227;6565.244996825426;6568.146141001639;6572.4084832365315;7372.115346158495;7408.666610002455;7411.4070874328845;7428.294312040348;7435.851282974419;6039.15451836752;6872.69072891711;6919.100516932766;7007.544509606859;7125.3230943060325;6749.595024298558;6854.314568543105;6897.379235868796;6897.931020689729;7041.049458323061;6763.898541521783;6952.409045231009;6957.547759395826;6958.79123626292;6961.79114626562;6517.087607734826;6537.836554086186;6541.738330466728;6554.737810487718;6559.048937903882;6524.3149469305035;6569.113169722217;6623.900641490285;6625.50308726857;6644.136262285871 14:51:41 INFO - 2016-02-25 14:51:41,384 DEBUG : BROWSER_OUTPUT: |4;prototype.html;361.78112242093533;366.37715882998907;368.8745826419017;372.08281585890785;373.5200267656061;6040.30536488304;6115.816525504237;6127.816165515031;6128.571000029994;6138.723757430916;5873.383294754053;5906.26171728534;6020.0969854521845;6033.155358249848;6050.546209034318;4763.309320148576;4778.139934811733;4793.6884102533395;4800.8319708810195;4803.975980120094;4779.569838714515;4784.1866882629865;4786.066716990188;4790.832320868771;4798.328234047236;4450.643948484129;4537.546245375453;4604.056168485469;4669.1362098011805;4738.2892566115015;4729.266963620645;4740.360051393087;4740.810367585293;4751.049790042005;4756.7859446325;4209.936850947238;4211.389348544474;4215.91568168638;4218.261804184286;4223.514295855985;5848.2689663792025;5851.941480585182;5855.326637436664;5860.765569377219;5862.443067908542;5409.783608655649;5573.749181286811;5574.275344205227;5578.163275508666;5585;6504.5121615879;6526.412622863965;6532.248791389004;6533.379328963741;6540.08438818561;4707.340972263886;4708.058388322315;4713.080949214905;4726;4730.172219861511;6661.16735408074;6811.216710078305;6814.795556133324;6815.079964204769;6827.556208846409;4825.227963525819;4833.782479788348;4834.081524510402;4835.395575553056;4840.878978025507;4897.877553061202;4901.558859702573;4905.460399356068;4909.926351104737;4913.656044076952;5551.056320425538;5556.638818476786;5557.472040156179;5567.053600887859;5571.275734154534;6412.775552855627;6549.738010479623;6553.803385898431;6578.144841170713;6583.210014798159;1708.0605666883164;1717.0041376001889;1727.06738361286;1734.765806616116;1738.1656804733798;1162.0761494611636;1168.2756690851727;1171.5957994491887;1190.8511436070492;1193.988060119405;6343.111964324999;6355.841103972345;6400.455961243347;6416.711247993851;6418.550701450947;6721.126253587003;6776.525643204928;6784.219814721271;6798.592084474848;6800.931990680129;6247.219097612799;6307.873842523216;6342.080398342215;6410.1987251593555;6458.225012998376;3648.7081033517256;3695.5380577427823;3696.5564132304294;3701.8704345348324;3704.759190652599;7017.947307903855;7029.156501219886;7029.683664235122;7034.191068027242;7036.542624729376;6914.481413893978;6942.583444993316;7033.261507541737;7045.506814522934;7050.929490705027;7025.543339682904;7029.051078104492;7035.894461583081;7039.401650859631;7045.436365090781;6745.0219718139615;6839;6855.348741869514;6864.691088900911;6864.725410983705;6728.125343705287;6734.663266836541;6736.157980252468;6747.527673062838;6748.325167483409;7032.437405007689;7037.190723066913;7037.753678621224;7042.683079261548;7046.436285097077;4883.975580122076;4887.315775791321;4889.559939605452;4893.09477746618;4894.094592500399;6714.865702686016;6859.7256109757045;6860.03959445688;6866.519343646097;6870;6665.666716664245;6666.999950007537;6671.199456065303;6674.532782705164;6678.799636010927;6473.385028422293;6634.270230274569;6641.269460359454;6645.700943457459;6645.800625981228;6489.5781774184525;6494.935050649433;6499.28507864144;6500.089987401862;6501.707423166064;6687.6321802301345;6688.531802773759;6689;6693.799186024427;6707.1616047994;6345.587536810092;6464.741410343534;6482.837929051623;6532.902006470002;6553.082568440517 14:51:41 INFO - 2016-02-25 14:51:41,386 DEBUG : BROWSER_OUTPUT: |5;yui.html;814.0638265994106;829.5727700234379;880.70936590925;946.89584145744;948.2177203806859;13106.344682765859;13347.733045339088;13366.866331336683;13379.130356526832;13391.06262561621;12539.498420063186;12544.37278136094;12562.30907300098;12595.496180152782;12694.873051269484;738.9408847292203;745.5936514599556;894.1550235027901;895.2211575928951;914.008300993423;909.1999040844048;910.1990248581272;916.4913473022503;917.6971599372191;918.9494577798218;959.299711210813;961.8845738511388;962.3744566032066;962.4273557233435;962.9711108666717;762.3063012658482;770.5723323555425;940.1256831146997;951.8667386565886;955.5365647660878;707.3245050976305;709.521073275537;709.9361057504849;712.0387476906187;712.1098626716604;12906.935465322707;13021.544245951442;13025.283609401478;13031.609051728463;13037.087403881733;12702.301373424458;13758.724825503434;13844.169349839041;13981.440742370292;13993.860061399357;10684.946575267151;10796.082333001705;10893.455327233607;10901.890981090168;10965.067969222626;3834.4056671215726;3883.650471457582;3884.9805750971345;3887.961120388788;3889.863854765098;49046.50953490455;49327.75336123332;50233.49766502361;50692.239616405415;50701.492985070414;10828.945855270831;11007.119430445546;11108.277961932448;11114.333140011626;11313.886861131448;19572.902135489516;19582.510437239183;19599.90200049019;19605.803941960683;19626.11682474292;7910.327622152182;7948.205179482005;8299.95850020746;8525.104863989316;8568.057513673491;4395.362672412482;4538.585457574203;4550.317452382169;4574.108048930493;4686.742229177428;3025.773067019982;3072.339447018902;3093.1648454917045;3095.845207739604;3101.65881753007;558.5866458820443;569.0496870226731;569.6297406685604;575.1545228514077;581.0992960910572;604.1994357476361;645.3481983197005;678.7861823525574;706.7914965085312;719.1370355573333;10155.136813370798;10234.692959211236;10397.84403233952;10716;10924.235303528676;548.8682716148096;568.1279236372148;578.0173704701939;620.4416025576927;735.9595222262827;4294.205571969133;4407.823687052482;4469.709468884512;4633.2818413146015;4678.508756580578;59913;62647;63391.68304158542;63730.36269637337;67202;56327.43672563304;57345;57765;57948.71025644929;58049;51657.74171129196;51906.74046629743;52091.21863172131;52311;52541.47458525442;9054.456732596065;9160.954195228982;9238.491882946502;9280.814383712153;9324.580393882427;36085.27829443344;36731.81634091812;37463;37482.812585936896;37784.433233502066;44739.32891006702;49875.75062124666;50910.23634645409;50987.235191471416;51469.742651286506;11667.6499705009;11705.648830535098;11733.706657333634;11753.236039657395;11973.461194246105;12900.483980640669;12902.741945160857;13477.32613369308;13658.590242292747;14141.010129290851;12339.197952133083;12627.684307892376;12654.683632909251;12987.415566299727;13334.13328133707;12063.819042714542;12179.939100304442;12309.076819238771;12538.435770390171;12990.740185196431;11837.171398002058;11982.460789264329;12006;12228.144029917821;12252.448639811051;9760;9910.801783964425;10032.69901902944;10326.535305911102;10598.576056957636;9552.856707149538;9714.611415543583;9766.632671425865;9813.116819485995;10235.23235757336 14:51:41 INFO - 2016-02-25 14:51:41,387 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:51:41 INFO - 2016-02-25 14:51:41,387 DEBUG : BROWSER_OUTPUT: __start_cc_report 14:51:41 INFO - 2016-02-25 14:51:41,387 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,933 14:51:41 INFO - 2016-02-25 14:51:41,387 DEBUG : BROWSER_OUTPUT: __end_cc_report 14:51:41 INFO - 2016-02-25 14:51:41,388 DEBUG : BROWSER_OUTPUT: __startTimestamp1456440701372__endTimestamp 14:51:41 INFO - 2016-02-25 14:51:41,388 DEBUG : BROWSER_OUTPUT: 14:51:41 INFO - 2016-02-25 14:51:41,388 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:51:41 INFO - 2016-02-25 14:51:41,388 DEBUG : BROWSER_OUTPUT: Number of tests: 6 14:51:41 INFO - 2016-02-25 14:51:41,388 DEBUG : BROWSER_OUTPUT: 14:51:41 INFO - 2016-02-25 14:51:41,389 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5498.10 Median:5706.06 stddev:610.35 (10.7%) stddev-sans-first:585.46 14:51:41 INFO - 2016-02-25 14:51:41,389 DEBUG : BROWSER_OUTPUT: Values: 3219.1 3306.4 3319.1 3350.6 3371.9 5417.6 5506.5 5520.7 5536.0 5540.0 5499.6 5539.6 5545.3 5547.7 5561.2 4972.3 5003.7 5040.3 5108.9 5133.2 4964.6 5005.4 5008.3 5009.9 5012.7 4936.1 4941.3 5015.6 5015.8 5022.9 4981.6 5047.1 5048.1 5058.6 5064.2 4988.0 5044.3 5059.7 5082.7 5108.6 5207.7 5251.2 5316.7 5360.9 5366.3 5908.1 5935.5 5944.2 5947.2 5952.5 5973.6 5983.4 5984.5 5984.8 5994.1 5366.1 5464.1 5467.1 5578.0 5595.3 5930.4 6044.8 6053.2 6054.0 6054.5 5848.9 5852.4 5856.7 5861.4 5863.7 5610.5 5712.1 5773.7 5945.0 5949.8 5895.6 5977.6 5977.7 5983.5 5994.9 5933.1 5969.3 5980.7 5992.6 5995.8 4386.8 4429.0 4491.1 4557.1 4575.0 4067.0 4071.7 4082.6 4089.7 4108.8 5623.5 5635.1 5641.7 5643.2 5662.2 5745.0 5842.2 5855.9 5867.6 5973.0 5637.2 5637.4 5642.4 5642.8 5673.8 5167.8 5172.7 5176.6 5183.4 5200.1 5934.7 5964.6 6046.6 6072.0 6077.7 5923.2 5994.3 5994.4 6000.2 6019.6 5959.5 6039.5 6072.6 6095.3 6107.0 5743.2 5835.1 5915.6 5933.8 5967.5 5938.4 5963.8 5964.7 5975.5 5982.1 5624.3 5782.0 5789.2 5811.5 5812.8 5693.9 5772.1 5787.4 5791.6 5820.9 5696.5 5705.1 5706.1 5722.5 5728.7 5573.2 5676.3 5786.9 5861.8 5868.2 5777.1 5808.1 5809.1 5812.1 5816.4 14:51:41 INFO - 2016-02-25 14:51:41,389 DEBUG : BROWSER_OUTPUT: 14:51:41 INFO - 2016-02-25 14:51:41,389 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:47040.60 Median:32652.34 stddev:77569.89 (237.6%) stddev-sans-first:77709.02 14:51:41 INFO - 2016-02-25 14:51:41,389 DEBUG : BROWSER_OUTPUT: Values: 570.8 608.3 616.0 621.0 651.3 31171.2 31959.0 31993.7 41441.4 41598.6 46457.0 46687.5 46994.8 47047.5 47132.0 9230.8 9418.6 9420.2 9428.1 9434.3 7830.8 7993.8 11173.3 11453.1 11483.5 10976.7 11018.8 11084.4 11091.9 11101.9 10945.9 10983.5 11035.3 11040.0 11052.9 10769.4 10873.8 10891.6 10897.9 10900.6 43122.6 43575.0 43577.1 43586.0 43592.0 3694.7 3696.6 3719.0 3843.8 4022.7 32610.7 32694.0 32794.9 32996.8 33174.3 4187.4 4244.6 4269.3 4286.2 4319.6 286975.0 287032.0 288793.0 288898.0 289173.0 54333.0 55621.0 61675.7 62455.0 63056.7 59289.7 59333.4 59390.0 59604.7 59610.4 15847.8 15959.1 16052.6 16134.0 16157.9 11734.6 11852.5 11888.9 11899.4 11902.1 19608.3 19781.2 19821.9 19832.0 19857.5 7291.7 7398.9 7503.4 7512.0 7516.0 1074.2 1106.7 1116.2 1141.9 1147.9 26326.5 26742.2 27902.2 27914.7 28697.1 1039.1 1119.3 1124.4 1137.6 1151.3 13418.0 13658.2 13740.9 15030.8 15992.4 348072.0 349126.0 349827.0 353960.0 355854.0 54534.5 54840.2 54894.0 54900.2 54945.5 1093.0 1306.3 1468.8 1474.1 1534.8 28364.0 28664.4 28974.4 29155.6 29297.0 43157.6 43337.6 43460.6 43588.8 43594.0 239632.0 240241.0 241040.0 241193.0 242277.0 31914.5 31992.5 32001.0 32059.2 32386.2 42972.6 43234.8 43787.6 44142.6 44524.6 37459.8 37984.4 38532.6 38875.6 39027.2 38086.4 38114.4 38760.8 38939.2 39568.8 34234.3 34690.3 34781.7 34831.0 34972.5 29857.0 31056.2 31714.2 32594.2 33777.7 25606.0 26255.2 26960.6 28642.6 28700.0 14:51:41 INFO - 2016-02-25 14:51:41,389 DEBUG : BROWSER_OUTPUT: 14:51:41 INFO - 2016-02-25 14:51:41,389 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:33122.10 Median:2298.17 stddev:107939.26 (4696.8%) stddev-sans-first:108214.07 14:51:41 INFO - 2016-02-25 14:51:41,390 DEBUG : BROWSER_OUTPUT: Values: 387.6 388.1 389.5 390.9 392.4 14633.3 16236.5 17276.4 18880.0 18899.5 15133.3 15160.4 15852.8 18272.4 18637.7 6261.7 6398.8 6421.3 6427.0 6431.6 4654.7 4780.8 4783.6 4786.0 6406.5 6861.8 6901.6 7094.5 7106.0 7115.2 7024.6 7046.6 7057.3 7064.4 7075.4 5164.6 5306.3 5310.8 6774.9 6777.4 13606.2 13809.8 16835.6 20618.3 20640.9 2193.2 2194.4 2197.4 2274.5 2321.9 9496.7 9633.7 9719.6 13262.8 13484.9 1925.2 1933.8 1986.6 1999.6 2007.1 64708.4 67338.7 68504.7 69695.3 70096.6 10900.3 11155.3 11245.9 11251.2 11329.0 14949.7 15033.5 15060.7 15076.5 15092.1 6997.2 7739.3 7844.5 7882.7 7901.4 7376.6 7378.1 7387.6 7417.1 7421.2 4537.6 4697.3 4738.2 4805.6 4839.4 1762.7 1794.4 1815.1 1847.6 1925.7 445.3 460.6 460.8 481.9 1006.8 11302.8 11456.8 11628.5 11708.6 14068.5 488.1 488.2 989.8 994.5 1018.3 6045.2 6277.4 10383.3 10455.6 10665.8 647519.0 647594.0 664269.0 668701.0 671868.0 88478.6 90665.0 90959.5 90983.1 92669.0 513.7 516.3 519.1 519.7 520.1 10620.9 10720.7 10877.0 11094.9 11151.4 39205.4 39541.4 39688.4 40340.2 40468.8 50141.7 51829.2 53519.0 54011.5 58509.4 10269.5 10472.5 10506.8 10614.0 10632.9 17582.8 17922.6 17935.5 17995.7 18368.9 15784.4 15842.2 15851.4 15962.1 16261.5 15512.0 15800.4 15948.4 15949.1 16086.8 11779.6 13072.5 15469.8 16142.7 16460.5 10108.4 10176.3 10293.8 10337.6 10370.6 15350.5 15522.9 15614.6 15641.2 15841.4 14:51:41 INFO - 2016-02-25 14:51:41,390 DEBUG : BROWSER_OUTPUT: 14:51:41 INFO - 2016-02-25 14:51:41,390 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5350.59 Median:6539.79 stddev:2704.71 (41.4%) stddev-sans-first:2696.89 14:51:41 INFO - 2016-02-25 14:51:41,391 DEBUG : BROWSER_OUTPUT: Values: 1510.3 1558.3 1611.7 1706.7 1823.0 7157.9 7449.3 7455.1 7456.4 7463.8 6585.7 6699.1 7378.7 7489.8 7598.8 2312.3 2326.6 2350.4 2351.2 2351.9 1968.5 2309.0 2323.1 2363.1 2365.3 1949.3 2251.9 2293.7 2295.6 2298.0 2206.5 2237.0 2244.5 2254.4 2269.8 2222.5 2245.9 2264.1 2266.5 2266.9 7293.2 7350.0 7370.2 7370.8 7376.0 4642.3 4727.6 4733.1 4738.4 4741.2 3045.4 3066.8 3128.2 3130.8 3150.7 1034.2 1051.9 1064.8 1095.3 1130.1 9728.0 9819.0 9844.8 9849.5 9891.3 3984.8 4012.2 4219.0 4225.7 4225.8 8330.3 8352.2 8356.3 8357.8 8363.4 4661.2 4663.6 4703.9 4708.4 4712.6 4305.2 4341.1 4355.5 4361.6 4362.7 3289.2 3354.1 3356.3 3388.2 3392.7 1021.9 1030.3 1045.6 1065.7 1068.7 1154.2 1159.0 1163.6 1167.4 1198.6 7291.1 7417.3 7425.9 7476.8 7488.7 1088.9 1122.4 1168.5 1174.9 1178.5 5928.4 5984.9 5987.6 5992.7 5995.5 8913.2 9022.4 9140.5 9155.9 9173.6 8985.0 9070.5 9184.7 9206.4 9209.1 7098.3 7221.9 7278.4 7380.8 7384.8 7337.6 7346.2 7350.9 7377.9 7401.4 8144.9 8189.7 8360.0 8377.5 8382.9 8555.0 8561.7 8562.4 8582.9 8603.7 6467.8 6533.9 6565.2 6568.1 6572.4 7372.1 7408.7 7411.4 7428.3 7435.9 6039.2 6872.7 6919.1 7007.5 7125.3 6749.6 6854.3 6897.4 6897.9 7041.0 6763.9 6952.4 6957.5 6958.8 6961.8 6517.1 6537.8 6541.7 6554.7 6559.0 6524.3 6569.1 6623.9 6625.5 6644.1 14:51:41 INFO - 2016-02-25 14:51:41,391 DEBUG : BROWSER_OUTPUT: 14:51:41 INFO - 2016-02-25 14:51:41,391 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5536.34 Median:6277.55 stddev:1641.06 (26.1%) stddev-sans-first:1599.04 14:51:41 INFO - 2016-02-25 14:51:41,391 DEBUG : BROWSER_OUTPUT: Values: 361.8 366.4 368.9 372.1 373.5 6040.3 6115.8 6127.8 6128.6 6138.7 5873.4 5906.3 6020.1 6033.2 6050.5 4763.3 4778.1 4793.7 4800.8 4804.0 4779.6 4784.2 4786.1 4790.8 4798.3 4450.6 4537.5 4604.1 4669.1 4738.3 4729.3 4740.4 4740.8 4751.0 4756.8 4209.9 4211.4 4215.9 4218.3 4223.5 5848.3 5851.9 5855.3 5860.8 5862.4 5409.8 5573.7 5574.3 5578.2 5585.0 6504.5 6526.4 6532.2 6533.4 6540.1 4707.3 4708.1 4713.1 4726.0 4730.2 6661.2 6811.2 6814.8 6815.1 6827.6 4825.2 4833.8 4834.1 4835.4 4840.9 4897.9 4901.6 4905.5 4909.9 4913.7 5551.1 5556.6 5557.5 5567.1 5571.3 6412.8 6549.7 6553.8 6578.1 6583.2 1708.1 1717.0 1727.1 1734.8 1738.2 1162.1 1168.3 1171.6 1190.9 1194.0 6343.1 6355.8 6400.5 6416.7 6418.6 6721.1 6776.5 6784.2 6798.6 6800.9 6247.2 6307.9 6342.1 6410.2 6458.2 3648.7 3695.5 3696.6 3701.9 3704.8 7017.9 7029.2 7029.7 7034.2 7036.5 6914.5 6942.6 7033.3 7045.5 7050.9 7025.5 7029.1 7035.9 7039.4 7045.4 6745.0 6839.0 6855.3 6864.7 6864.7 6728.1 6734.7 6736.2 6747.5 6748.3 7032.4 7037.2 7037.8 7042.7 7046.4 4884.0 4887.3 4889.6 4893.1 4894.1 6714.9 6859.7 6860.0 6866.5 6870.0 6665.7 6667.0 6671.2 6674.5 6678.8 6473.4 6634.3 6641.3 6645.7 6645.8 6489.6 6494.9 6499.3 6500.1 6501.7 6687.6 6688.5 6689.0 6693.8 6707.2 6345.6 6464.7 6482.8 6532.9 6553.1 14:51:41 INFO - 2016-02-25 14:51:41,392 DEBUG : BROWSER_OUTPUT: 14:51:41 INFO - 2016-02-25 14:51:41,392 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14920.35 Median:4562.21 stddev:17531.38 (384.3%) stddev-sans-first:17548.56 14:51:41 INFO - 2016-02-25 14:51:41,392 DEBUG : BROWSER_OUTPUT: Values: 814.1 829.6 880.7 946.9 948.2 13106.3 13347.7 13366.9 13379.1 13391.1 12539.5 12544.4 12562.3 12595.5 12694.9 738.9 745.6 894.2 895.2 914.0 909.2 910.2 916.5 917.7 918.9 959.3 961.9 962.4 962.4 963.0 762.3 770.6 940.1 951.9 955.5 707.3 709.5 709.9 712.0 712.1 12906.9 13021.5 13025.3 13031.6 13037.1 12702.3 13758.7 13844.2 13981.4 13993.9 10684.9 10796.1 10893.5 10901.9 10965.1 3834.4 3883.7 3885.0 3888.0 3889.9 49046.5 49327.8 50233.5 50692.2 50701.5 10828.9 11007.1 11108.3 11114.3 11313.9 19572.9 19582.5 19599.9 19605.8 19626.1 7910.3 7948.2 8300.0 8525.1 8568.1 4395.4 4538.6 4550.3 4574.1 4686.7 3025.8 3072.3 3093.2 3095.8 3101.7 558.6 569.0 569.6 575.2 581.1 604.2 645.3 678.8 706.8 719.1 10155.1 10234.7 10397.8 10716.0 10924.2 548.9 568.1 578.0 620.4 736.0 4294.2 4407.8 4469.7 4633.3 4678.5 59913.0 62647.0 63391.7 63730.4 67202.0 56327.4 57345.0 57765.0 57948.7 58049.0 51657.7 51906.7 52091.2 52311.0 52541.5 9054.5 9161.0 9238.5 9280.8 9324.6 36085.3 36731.8 37463.0 37482.8 37784.4 44739.3 49875.8 50910.2 50987.2 51469.7 11667.6 11705.6 11733.7 11753.2 11973.5 12900.5 12902.7 13477.3 13658.6 14141.0 12339.2 12627.7 12654.7 12987.4 13334.1 12063.8 12179.9 12309.1 12538.4 12990.7 11837.2 11982.5 12006.0 12228.1 12252.4 9760.0 9910.8 10032.7 10326.5 10598.6 9552.9 9714.6 9766.6 9813.1 10235.2 14:51:41 INFO - 2016-02-25 14:51:41,392 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:51:41 INFO - 2016-02-25 14:51:41,392 DEBUG : BROWSER_OUTPUT: 14:51:41 INFO - 2016-02-25 14:51:41,970 INFO : Browser exited with error code: 0 14:51:41 INFO - 2016-02-25 14:51:41,993 INFO : Completed test dromaeo_css (00:18:30) 14:51:41 INFO - 2016-02-25 14:51:41,993 INFO : Starting test kraken 14:51:41 INFO - 2016-02-25 14:51:41,994 DEBUG : operating with platform_type : linux_ 14:51:41 INFO - 2016-02-25 14:51:41,994 INFO : Initialising browser for kraken test... 14:51:42 INFO - 2016-02-25 14:51:42,000 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmprtSDV2/profile http://localhost:54774/getInfo.html 14:51:45 INFO - 2016-02-25 14:51:45,756 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:51:45 INFO - 2016-02-25 14:51:45,756 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:51:45 INFO - 2016-02-25 14:51:45,756 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:51:45 INFO - 2016-02-25 14:51:45,756 DEBUG : BROWSER_OUTPUT: __metrics 14:51:46 INFO - 2016-02-25 14:51:46,019 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] 14:51:47 INFO - 2016-02-25 14:51:47,002 INFO : Browser initialized. 14:51:47 INFO - 2016-02-25 14:51:47,003 INFO : Running cycle 1/1 for kraken test... 14:51:47 INFO - 2016-02-25 14:51:47,003 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmprtSDV2/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:51:58 INFO - 2016-02-25 14:51:58,678 DEBUG : BROWSER_OUTPUT: RSS: Main: 134365184 14:51:58 INFO - 2016-02-25 14:51:58,678 DEBUG : BROWSER_OUTPUT: 14:52:01 INFO - 2016-02-25 14:52:01,097 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54774/tests/kraken/driver.html?testName=audio-beat-detection) 14:52:01 INFO - 2016-02-25 14:52:01,683 DEBUG : BROWSER_OUTPUT: RSS: Main: 163958784 14:52:01 INFO - 2016-02-25 14:52:01,683 DEBUG : BROWSER_OUTPUT: 14:52:04 INFO - 2016-02-25 14:52:04,282 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54774/tests/kraken/driver.html?testName=audio-dft) 14:52:04 INFO - 2016-02-25 14:52:04,844 DEBUG : BROWSER_OUTPUT: RSS: Main: 162451456 14:52:04 INFO - 2016-02-25 14:52:04,844 DEBUG : BROWSER_OUTPUT: 14:52:07 INFO - 2016-02-25 14:52:07,623 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54774/tests/kraken/driver.html?testName=audio-fft) 14:52:08 INFO - 2016-02-25 14:52:08,189 DEBUG : BROWSER_OUTPUT: RSS: Main: 159019008 14:52:08 INFO - 2016-02-25 14:52:08,189 DEBUG : BROWSER_OUTPUT: 14:52:10 INFO - 2016-02-25 14:52:10,182 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54774/tests/kraken/driver.html?testName=audio-oscillator) 14:52:10 INFO - 2016-02-25 14:52:10,743 DEBUG : BROWSER_OUTPUT: RSS: Main: 160395264 14:52:10 INFO - 2016-02-25 14:52:10,744 DEBUG : BROWSER_OUTPUT: 14:52:12 INFO - 2016-02-25 14:52:12,923 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54774/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:52:13 INFO - 2016-02-25 14:52:13,484 DEBUG : BROWSER_OUTPUT: RSS: Main: 158310400 14:52:13 INFO - 2016-02-25 14:52:13,484 DEBUG : BROWSER_OUTPUT: 14:52:16 INFO - 2016-02-25 14:52:16,363 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54774/tests/kraken/driver.html?testName=imaging-darkroom) 14:52:16 INFO - 2016-02-25 14:52:16,925 DEBUG : BROWSER_OUTPUT: RSS: Main: 158412800 14:52:16 INFO - 2016-02-25 14:52:16,926 DEBUG : BROWSER_OUTPUT: 14:52:20 INFO - 2016-02-25 14:52:20,235 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54774/tests/kraken/driver.html?testName=imaging-desaturate) 14:52:20 INFO - 2016-02-25 14:52:20,804 DEBUG : BROWSER_OUTPUT: RSS: Main: 159780864 14:52:20 INFO - 2016-02-25 14:52:20,804 DEBUG : BROWSER_OUTPUT: 14:52:23 INFO - 2016-02-25 14:52:23,520 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54774/tests/kraken/driver.html?testName=json-parse-financial) 14:52:24 INFO - 2016-02-25 14:52:24,082 DEBUG : BROWSER_OUTPUT: RSS: Main: 158662656 14:52:24 INFO - 2016-02-25 14:52:24,082 DEBUG : BROWSER_OUTPUT: 14:52:25 INFO - 2016-02-25 14:52:25,619 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54774/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:52:26 INFO - 2016-02-25 14:52:26,181 DEBUG : BROWSER_OUTPUT: RSS: Main: 165732352 14:52:26 INFO - 2016-02-25 14:52:26,181 DEBUG : BROWSER_OUTPUT: 14:52:27 INFO - 2016-02-25 14:52:27,970 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54774/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:52:28 INFO - 2016-02-25 14:52:28,557 DEBUG : BROWSER_OUTPUT: RSS: Main: 163180544 14:52:28 INFO - 2016-02-25 14:52:28,558 DEBUG : BROWSER_OUTPUT: 14:52:30 INFO - 2016-02-25 14:52:30,568 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54774/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:52:31 INFO - 2016-02-25 14:52:31,135 DEBUG : BROWSER_OUTPUT: RSS: Main: 168255488 14:52:31 INFO - 2016-02-25 14:52:31,135 DEBUG : BROWSER_OUTPUT: 14:52:33 INFO - 2016-02-25 14:52:33,489 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54774/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:52:34 INFO - 2016-02-25 14:52:34,058 DEBUG : BROWSER_OUTPUT: RSS: Main: 174804992 14:52:34 INFO - 2016-02-25 14:52:34,058 DEBUG : BROWSER_OUTPUT: 14:52:36 INFO - 2016-02-25 14:52:36,573 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54774/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:52:37 INFO - 2016-02-25 14:52:37,136 DEBUG : BROWSER_OUTPUT: RSS: Main: 172638208 14:52:37 INFO - 2016-02-25 14:52:37,136 DEBUG : BROWSER_OUTPUT: 14:52:38 INFO - 2016-02-25 14:52:38,768 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54774/tests/kraken/driver.html?testName=ai-astar) 14:52:39 INFO - 2016-02-25 14:52:39,014 DEBUG : BROWSER_OUTPUT: RSS: Main: 230498304 14:52:39 INFO - 2016-02-25 14:52:39,014 DEBUG : BROWSER_OUTPUT: 14:52:39 INFO - 2016-02-25 14:52:39,017 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:52:39 INFO - 2016-02-25 14:52:39,018 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:52:39 INFO - 2016-02-25 14:52:39,018 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:52:39 INFO - 2016-02-25 14:52:39,018 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:52:39 INFO - 2016-02-25 14:52:39,018 DEBUG : BROWSER_OUTPUT: |0;ai-astar;107;109;103;107;108;110;99;107;109;109 14:52:39 INFO - 2016-02-25 14:52:39,018 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;121;143;164;122;146;151;125;173;121 14:52:39 INFO - 2016-02-25 14:52:39,018 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;161;160;161;161;163;159;161;161;161 14:52:39 INFO - 2016-02-25 14:52:39,019 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;70;155;71;72;71;71;172;71 14:52:39 INFO - 2016-02-25 14:52:39,019 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;92;146;91;93;92;136;92;93;132 14:52:39 INFO - 2016-02-25 14:52:39,019 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;110;109;109;109;109 14:52:39 INFO - 2016-02-25 14:52:39,019 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;146;145;145;146;145;146;145;145 14:52:39 INFO - 2016-02-25 14:52:39,019 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 14:52:39 INFO - 2016-02-25 14:52:39,019 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;54;60;55;55;56;57;57;54;54 14:52:39 INFO - 2016-02-25 14:52:39,019 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;65;64;64;65;65;65;65;65;65 14:52:39 INFO - 2016-02-25 14:52:39,019 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;78;78;77;78;78;79;85;81;77 14:52:39 INFO - 2016-02-25 14:52:39,020 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;115;126;117;111;114;116;117;111;125;113 14:52:39 INFO - 2016-02-25 14:52:39,020 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;154;141;143;141;144;131;142;141;134 14:52:39 INFO - 2016-02-25 14:52:39,020 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;64;64;64;64;65;65;64;66;66 14:52:39 INFO - 2016-02-25 14:52:39,020 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:52:39 INFO - 2016-02-25 14:52:39,020 DEBUG : BROWSER_OUTPUT: __start_cc_report 14:52:39 INFO - 2016-02-25 14:52:39,020 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,878 14:52:39 INFO - 2016-02-25 14:52:39,020 DEBUG : BROWSER_OUTPUT: __end_cc_report 14:52:39 INFO - 2016-02-25 14:52:39,021 DEBUG : BROWSER_OUTPUT: __startTimestamp1456440759015__endTimestamp 14:52:39 INFO - 2016-02-25 14:52:39,021 DEBUG : BROWSER_OUTPUT: 14:52:39 INFO - 2016-02-25 14:52:39,024 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:52:39 INFO - 2016-02-25 14:52:39,024 DEBUG : BROWSER_OUTPUT: Number of tests: 14 14:52:39 INFO - 2016-02-25 14:52:39,024 DEBUG : BROWSER_OUTPUT: 14:52:39 INFO - 2016-02-25 14:52:39,024 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.80 Median:109.00 stddev:3.36 (3.1%) stddev-sans-first:3.56 14:52:39 INFO - 2016-02-25 14:52:39,024 DEBUG : BROWSER_OUTPUT: Values: 107.0 109.0 103.0 107.0 108.0 110.0 99.0 107.0 109.0 109.0 14:52:39 INFO - 2016-02-25 14:52:39,025 DEBUG : BROWSER_OUTPUT: 14:52:39 INFO - 2016-02-25 14:52:39,025 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.50 Median:147.50 stddev:18.74 (12.7%) stddev-sans-first:19.68 14:52:39 INFO - 2016-02-25 14:52:39,025 DEBUG : BROWSER_OUTPUT: Values: 149.0 121.0 143.0 164.0 122.0 146.0 151.0 125.0 173.0 121.0 14:52:39 INFO - 2016-02-25 14:52:39,025 DEBUG : BROWSER_OUTPUT: 14:52:39 INFO - 2016-02-25 14:52:39,025 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 14:52:39 INFO - 2016-02-25 14:52:39,025 DEBUG : BROWSER_OUTPUT: Values: 161.0 161.0 160.0 161.0 161.0 163.0 159.0 161.0 161.0 161.0 14:52:39 INFO - 2016-02-25 14:52:39,026 DEBUG : BROWSER_OUTPUT: 14:52:39 INFO - 2016-02-25 14:52:39,026 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:89.50 Median:71.00 stddev:39.21 (55.2%) stddev-sans-first:41.01 14:52:39 INFO - 2016-02-25 14:52:39,026 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 70.0 155.0 71.0 72.0 71.0 71.0 172.0 71.0 14:52:39 INFO - 2016-02-25 14:52:39,026 DEBUG : BROWSER_OUTPUT: 14:52:39 INFO - 2016-02-25 14:52:39,026 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.10 Median:92.00 stddev:22.29 (24.2%) stddev-sans-first:23.21 14:52:39 INFO - 2016-02-25 14:52:39,026 DEBUG : BROWSER_OUTPUT: Values: 94.0 92.0 146.0 91.0 93.0 92.0 136.0 92.0 93.0 132.0 14:52:39 INFO - 2016-02-25 14:52:39,026 DEBUG : BROWSER_OUTPUT: 14:52:39 INFO - 2016-02-25 14:52:39,026 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 14:52:39 INFO - 2016-02-25 14:52:39,027 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 110.0 109.0 109.0 109.0 109.0 14:52:39 INFO - 2016-02-25 14:52:39,027 DEBUG : BROWSER_OUTPUT: 14:52:39 INFO - 2016-02-25 14:52:39,027 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 14:52:39 INFO - 2016-02-25 14:52:39,027 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 146.0 145.0 145.0 146.0 145.0 146.0 145.0 145.0 14:52:39 INFO - 2016-02-25 14:52:39,027 DEBUG : BROWSER_OUTPUT: 14:52:39 INFO - 2016-02-25 14:52:39,027 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 14:52:39 INFO - 2016-02-25 14:52:39,027 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 14:52:39 INFO - 2016-02-25 14:52:39,027 DEBUG : BROWSER_OUTPUT: 14:52:39 INFO - 2016-02-25 14:52:39,027 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.00 stddev:1.87 (3.3%) stddev-sans-first:1.99 14:52:39 INFO - 2016-02-25 14:52:39,028 DEBUG : BROWSER_OUTPUT: Values: 56.0 54.0 60.0 55.0 55.0 56.0 57.0 57.0 54.0 54.0 14:52:39 INFO - 2016-02-25 14:52:39,028 DEBUG : BROWSER_OUTPUT: 14:52:39 INFO - 2016-02-25 14:52:39,028 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:65.00 stddev:0.48 (0.7%) stddev-sans-first:0.44 14:52:39 INFO - 2016-02-25 14:52:39,028 DEBUG : BROWSER_OUTPUT: Values: 64.0 65.0 64.0 64.0 65.0 65.0 65.0 65.0 65.0 65.0 14:52:39 INFO - 2016-02-25 14:52:39,028 DEBUG : BROWSER_OUTPUT: 14:52:39 INFO - 2016-02-25 14:52:39,028 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:78.00 stddev:2.49 (3.2%) stddev-sans-first:2.55 14:52:39 INFO - 2016-02-25 14:52:39,028 DEBUG : BROWSER_OUTPUT: Values: 77.0 78.0 78.0 77.0 78.0 78.0 79.0 85.0 81.0 77.0 14:52:39 INFO - 2016-02-25 14:52:39,028 DEBUG : BROWSER_OUTPUT: 14:52:39 INFO - 2016-02-25 14:52:39,028 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:116.50 Median:116.50 stddev:5.21 (4.5%) stddev-sans-first:5.50 14:52:39 INFO - 2016-02-25 14:52:39,029 DEBUG : BROWSER_OUTPUT: Values: 115.0 126.0 117.0 111.0 114.0 116.0 117.0 111.0 125.0 113.0 14:52:39 INFO - 2016-02-25 14:52:39,029 DEBUG : BROWSER_OUTPUT: 14:52:39 INFO - 2016-02-25 14:52:39,029 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:141.10 Median:141.50 stddev:6.08 (4.3%) stddev-sans-first:6.44 14:52:39 INFO - 2016-02-25 14:52:39,029 DEBUG : BROWSER_OUTPUT: Values: 140.0 154.0 141.0 143.0 141.0 144.0 131.0 142.0 141.0 134.0 14:52:39 INFO - 2016-02-25 14:52:39,029 DEBUG : BROWSER_OUTPUT: 14:52:39 INFO - 2016-02-25 14:52:39,029 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:65.00 stddev:0.82 (1.3%) stddev-sans-first:0.87 14:52:39 INFO - 2016-02-25 14:52:39,029 DEBUG : BROWSER_OUTPUT: Values: 65.0 64.0 64.0 64.0 64.0 65.0 65.0 64.0 66.0 66.0 14:52:39 INFO - 2016-02-25 14:52:39,029 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:52:39 INFO - 2016-02-25 14:52:39,029 DEBUG : BROWSER_OUTPUT: 14:52:39 INFO - 2016-02-25 14:52:39,674 INFO : Browser exited with error code: 0 14:52:39 INFO - 2016-02-25 14:52:39,725 INFO : Completed test kraken (00:00:57) 14:52:39 INFO - 2016-02-25 14:52:39,725 INFO : Starting test v8_7 14:52:39 INFO - 2016-02-25 14:52:39,725 DEBUG : operating with platform_type : linux_ 14:52:39 INFO - 2016-02-25 14:52:39,726 INFO : Initialising browser for v8_7 test... 14:52:39 INFO - 2016-02-25 14:52:39,732 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGoILl7/profile http://localhost:54774/getInfo.html 14:52:43 INFO - 2016-02-25 14:52:43,818 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:52:43 INFO - 2016-02-25 14:52:43,818 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:52:43 INFO - 2016-02-25 14:52:43,818 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:52:43 INFO - 2016-02-25 14:52:43,818 DEBUG : BROWSER_OUTPUT: __metrics 14:52:44 INFO - 2016-02-25 14:52:44,000 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] 14:52:44 INFO - 2016-02-25 14:52:44,826 INFO : Browser initialized. 14:52:44 INFO - 2016-02-25 14:52:44,827 INFO : Running cycle 1/1 for v8_7 test... 14:52:44 INFO - 2016-02-25 14:52:44,827 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGoILl7/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:52:56 INFO - 2016-02-25 14:52:56,513 DEBUG : BROWSER_OUTPUT: RSS: Main: 133648384 14:52:56 INFO - 2016-02-25 14:52:56,514 DEBUG : BROWSER_OUTPUT: 14:53:17 INFO - 2016-02-25 14:53:17,866 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54774/tests/v8_7/run.html (next: http://localhost:54774/tests/v8_7/run.html) 14:53:18 INFO - 2016-02-25 14:53:18,116 DEBUG : BROWSER_OUTPUT: RSS: Main: 422084608 14:53:18 INFO - 2016-02-25 14:53:18,117 DEBUG : BROWSER_OUTPUT: 14:53:18 INFO - 2016-02-25 14:53:18,117 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:53:18 INFO - 2016-02-25 14:53:18,117 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:53:18 INFO - 2016-02-25 14:53:18,117 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:53:18 INFO - 2016-02-25 14:53:18,118 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:53:18 INFO - 2016-02-25 14:53:18,118 DEBUG : BROWSER_OUTPUT: |0;Richards;167.8697330871244 14:53:18 INFO - 2016-02-25 14:53:18,118 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;165.94756057085962 14:53:18 INFO - 2016-02-25 14:53:18,118 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.74115665356544 14:53:18 INFO - 2016-02-25 14:53:18,118 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5164.948453608247 14:53:18 INFO - 2016-02-25 14:53:18,118 DEBUG : BROWSER_OUTPUT: |4;RayTrace;945.179584120983 14:53:18 INFO - 2016-02-25 14:53:18,118 DEBUG : BROWSER_OUTPUT: |5;Earley;539.6654074473827 14:53:18 INFO - 2016-02-25 14:53:18,118 DEBUG : BROWSER_OUTPUT: |6;Boyer;10526.315789473685 14:53:18 INFO - 2016-02-25 14:53:18,119 DEBUG : BROWSER_OUTPUT: |7;RegExp;24047.619047619046 14:53:18 INFO - 2016-02-25 14:53:18,119 DEBUG : BROWSER_OUTPUT: |8;Splay;533.0490405117271 14:53:18 INFO - 2016-02-25 14:53:18,119 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 14:53:18 INFO - 2016-02-25 14:53:18,119 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:53:18 INFO - 2016-02-25 14:53:18,119 DEBUG : BROWSER_OUTPUT: __startTimestamp1456440798114__endTimestamp 14:53:18 INFO - 2016-02-25 14:53:18,119 DEBUG : BROWSER_OUTPUT: 14:53:18 INFO - 2016-02-25 14:53:18,120 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:53:18 INFO - 2016-02-25 14:53:18,120 DEBUG : BROWSER_OUTPUT: Number of tests: 10 14:53:18 INFO - 2016-02-25 14:53:18,120 DEBUG : BROWSER_OUTPUT: 14:53:18 INFO - 2016-02-25 14:53:18,120 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.87 Median:167.87 stddev:0.00 (0.0%) 14:53:18 INFO - 2016-02-25 14:53:18,121 DEBUG : BROWSER_OUTPUT: Values: 167.9 14:53:18 INFO - 2016-02-25 14:53:18,121 DEBUG : BROWSER_OUTPUT: 14:53:18 INFO - 2016-02-25 14:53:18,121 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:165.95 Median:165.95 stddev:0.00 (0.0%) 14:53:18 INFO - 2016-02-25 14:53:18,121 DEBUG : BROWSER_OUTPUT: Values: 165.9 14:53:18 INFO - 2016-02-25 14:53:18,122 DEBUG : BROWSER_OUTPUT: 14:53:18 INFO - 2016-02-25 14:53:18,122 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.74 Median:280.74 stddev:0.00 (0.0%) 14:53:18 INFO - 2016-02-25 14:53:18,122 DEBUG : BROWSER_OUTPUT: Values: 280.7 14:53:18 INFO - 2016-02-25 14:53:18,122 DEBUG : BROWSER_OUTPUT: 14:53:18 INFO - 2016-02-25 14:53:18,123 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5164.95 Median:5164.95 stddev:0.00 (0.0%) 14:53:18 INFO - 2016-02-25 14:53:18,123 DEBUG : BROWSER_OUTPUT: Values: 5164.9 14:53:18 INFO - 2016-02-25 14:53:18,123 DEBUG : BROWSER_OUTPUT: 14:53:18 INFO - 2016-02-25 14:53:18,124 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:945.18 Median:945.18 stddev:0.00 (0.0%) 14:53:18 INFO - 2016-02-25 14:53:18,124 DEBUG : BROWSER_OUTPUT: Values: 945.2 14:53:18 INFO - 2016-02-25 14:53:18,124 DEBUG : BROWSER_OUTPUT: 14:53:18 INFO - 2016-02-25 14:53:18,124 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:539.67 Median:539.67 stddev:0.00 (0.0%) 14:53:18 INFO - 2016-02-25 14:53:18,125 DEBUG : BROWSER_OUTPUT: Values: 539.7 14:53:18 INFO - 2016-02-25 14:53:18,125 DEBUG : BROWSER_OUTPUT: 14:53:18 INFO - 2016-02-25 14:53:18,125 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10526.32 Median:10526.32 stddev:0.00 (0.0%) 14:53:18 INFO - 2016-02-25 14:53:18,125 DEBUG : BROWSER_OUTPUT: Values: 10526.3 14:53:18 INFO - 2016-02-25 14:53:18,125 DEBUG : BROWSER_OUTPUT: 14:53:18 INFO - 2016-02-25 14:53:18,126 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24047.62 Median:24047.62 stddev:0.00 (0.0%) 14:53:18 INFO - 2016-02-25 14:53:18,126 DEBUG : BROWSER_OUTPUT: Values: 24047.6 14:53:18 INFO - 2016-02-25 14:53:18,126 DEBUG : BROWSER_OUTPUT: 14:53:18 INFO - 2016-02-25 14:53:18,126 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:533.05 Median:533.05 stddev:0.00 (0.0%) 14:53:18 INFO - 2016-02-25 14:53:18,127 DEBUG : BROWSER_OUTPUT: Values: 533.0 14:53:18 INFO - 2016-02-25 14:53:18,127 DEBUG : BROWSER_OUTPUT: 14:53:18 INFO - 2016-02-25 14:53:18,127 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 14:53:18 INFO - 2016-02-25 14:53:18,127 DEBUG : BROWSER_OUTPUT: Values: 4826.9 14:53:18 INFO - 2016-02-25 14:53:18,127 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:53:18 INFO - 2016-02-25 14:53:18,128 DEBUG : BROWSER_OUTPUT: 14:53:18 INFO - 2016-02-25 14:53:18,794 INFO : Browser exited with error code: 0 14:53:18 INFO - 2016-02-25 14:53:18,806 INFO : Completed test v8_7 (00:00:39) 14:53:19 INFO - 2016-02-25 14:53:19,187 INFO : Completed test suite (00:20:07) 14:53:19 INFO - 2016-02-25 14:53:19,187 DEBUG : Working with test: dromaeo_css 14:53:19 INFO - 2016-02-25 14:53:19,187 DEBUG : Generating results file: dromaeo_css 14:53:19 INFO - 2016-02-25 14:53:19,188 DEBUG : Working with test: kraken 14:53:19 INFO - 2016-02-25 14:53:19,188 DEBUG : Generating results file: kraken 14:53:19 INFO - 2016-02-25 14:53:19,189 INFO : javascript benchmark 14:53:19 INFO - 2016-02-25 14:53:19,189 DEBUG : Working with test: v8_7 14:53:19 INFO - 2016-02-25 14:53:19,189 DEBUG : Generating results file: v8_7 14:53:19 INFO - 2016-02-25 14:53:19,189 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:53:19 INFO - 2016-02-25 14:53:19,233 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 14:53:19 INFO - 2016-02-25 14:53:19,233 DEBUG : process_Request line: dromaeo_css 7219.48 graph.html#tests=[[72,53,35]] 14:53:19 INFO - 2016-02-25 14:53:19,233 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:53:19 INFO - 2016-02-25 14:53:19,266 DEBUG : process_Request line: kraken 1476.00 graph.html#tests=[[232,53,35]] 14:53:19 INFO - 2016-02-25 14:53:19,266 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:53:19 INFO - 2016-02-25 14:53:19,302 DEBUG : process_Request line: v8_7 23260.32 graph.html#tests=[[230,53,35]] 14:53:19 INFO - 2016-02-25 14:53:19,303 INFO : javascript benchmark 14:53:19 INFO - 2016-02-25 14:53:19,306 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3219.1308346746378, 3306.404847127519, 3319.1038419626702, 3350.63143054264, 3371.881984130556, 5417.593680473966, 5506.476884695952, 5520.723963801804, 5536.031194540959, 5539.9999999999945, 5499.587530935186, 5539.612227144102, 5545.279113715222, 5547.667139971604, 5561.249231353766, 4972.328735620699, 5003.699778013312, 5040.294358789773, 5108.8978220435565, 5133.230015497668, 4964.627652926027, 5005.374328208974, 5008.348914641091, 5009.874753131183, 5012.674176178537, 4936.086823937583, 4941.283513890484, 5015.623828212899, 5015.799368025275, 5022.924656130161, 4981.576565991877, 5047.066292735855, 5048.1418158913075, 5058.570021548173, 5064.189729643276, 4988.02733466974, 5044.268581055763, 5059.747012649353, 5082.695038297677, 5108.616853735947, 5207.687538747649, 5251.238570407308, 5316.707581083039, 5360.892782144374, 5366.302380690524, 5908.113782932552, 5935.49548288396, 5944.167816505693, 5947.167396564441, 5952.464278214981, 5973.581849270553, 5983.371745966698, 5984.4613984741145, 5984.820455386302, 5994.070919007517, 5366.141417373241, 5464.0711079116645, 5467.097928841776, 5577.972110139464, 5595.328560572717, 5930.406959304035, 6044.788432404845, 6053.182820319289, 6054.0, 6054.51563874889, 5848.912266316009, 5852.356240813507, 5856.736446859902, 5861.384554621789, 5863.67749773758, 5610.49505544503, 5712.057510510802, 5773.740181691834, 5945.0, 5949.762009519657, 5895.616784909053, 5977.641341519523, 5977.701114944235, 5983.521318294526, 5994.940050599525, 5933.050711886164, 5969.283685957626, 5980.73086711099, 5992.610480318853, 5995.760169593167, 4386.846460373936, 4429.047754732748, 4491.146682130385, 4557.111363284128, 4574.99350142968, 4067.023914260615, 4071.7353372030725, 4082.5509193988637, 4089.652379547772, 4108.8356465741035, 5623.521796499598, 5635.070213414822, 5641.661500309994, 5643.1535269709875, 5662.207290979266, 5745.0, 5842.2405087338375, 5855.912161317584, 5867.647941123546, 5973.0, 5637.154426836008, 5637.408072152447, 5642.351129620064, 5642.830715078554, 5673.8014169504695, 5167.844964650992, 5172.7155006475, 5176.5858731301405, 5183.377994640668, 5200.141976573898, 5934.732937017845, 5964.612300200473, 6046.606970546901, 6072.028475443908, 6077.726502307407, 5923.229980102559, 5994.28068631767, 5994.400559943971, 6000.189974353407, 6019.578629495893, 5959.523238141025, 6039.547033972557, 6072.635641861502, 6095.299040610386, 6106.969465152646, 5743.195952566728, 5835.066389377679, 5915.645061296336, 5933.792317268875, 5967.522598192221, 5938.406159384027, 5963.761449542143, 5964.671943043174, 5975.462208401264, 5982.0727787193055, 5624.268845050117, 5781.971090144691, 5789.247397838254, 5811.476967072984, 5812.7674893003805, 5693.9430605693415, 5772.076467765138, 5787.363390027178, 5791.623544469596, 5820.912686309624, 5696.54427645779, 5705.087186050212, 5706.058500347396, 5722.456366645162, 5728.713564321851, 5573.247611572386, 5676.347220069745, 5786.884262314646, 5861.794837180678, 5868.23712917318, 5777.133429985368, 5808.07070868659, 5809.099589563624, 5812.128180772918, 5816.3892791258], "unit": "ms", "name": "dojo.html", "value": 5458.459334185293}, {"lowerIsBetter": false, "replicates": [570.8287513745876, 608.321721280772, 615.981520554383, 620.9782657606985, 651.2673242602073, 31171.220719481997, 31959.0, 31993.680063199332, 41441.378379324335, 41598.58401415985, 46457.0, 46687.53312466874, 46994.76502617491, 47047.52952470474, 47132.0, 9230.769230769218, 9418.576164072632, 9420.199283060949, 9428.057194280585, 9434.339596228267, 7830.765077047698, 7993.840123197532, 11173.329600223971, 11453.14101442391, 11483.483243254073, 10976.670699879016, 11018.834717479243, 11084.390358530318, 11091.944540277287, 11101.888981110207, 10945.890541094566, 10983.450827458675, 11035.282706624084, 11040.0, 11052.889471105347, 10769.407682577455, 10873.83689244662, 10891.618793342273, 10897.891021089767, 10900.563977440894, 43122.56877431248, 43575.0, 43577.12845743099, 43586.0, 43592.0, 3694.704423646129, 3696.5933747287777, 3719.033051406627, 3843.7885916274595, 4022.658074063708, 32610.67389326124, 32694.0, 32794.93756127509, 32996.83501582501, 33174.3365132696, 4187.434696315989, 4244.617984381421, 4269.274223382033, 4286.249906266391, 4319.611234988866, 286974.9999999979, 287032.0, 288793.0, 288898.0, 289173.0, 54333.0, 55621.0, 61675.691621541606, 62455.0, 63056.68471657612, 59289.703551481965, 59333.40666593365, 59390.0, 59604.70197648984, 59610.40389596135, 15847.841521584867, 15959.122248276457, 16052.598685032734, 16134.0, 16157.919210403872, 11734.589289374831, 11852.525898964117, 11888.88111118895, 11899.405029748477, 11902.107341949388, 19608.313709020116, 19781.20875165006, 19821.900890495453, 19832.0, 19857.503562411057, 7291.708331666674, 7398.889016664754, 7503.399728021745, 7512.0420522454915, 7516.0, 1074.2050882347164, 1106.6569363497342, 1116.2298014370058, 1141.9265890063314, 1147.8852114788622, 26326.548928489683, 26742.19773406801, 27902.16293511198, 27914.72085279162, 28697.139085827457, 1039.0648416425279, 1119.311623351633, 1124.42092322454, 1137.6075254037344, 1151.3437340715711, 13418.0, 13658.248796316098, 13740.862591374158, 15030.849691503163, 15992.440264590683, 348072.0, 349126.0, 349827.0, 353960.0, 355854.0, 54534.45465545294, 54840.177397339074, 54894.0, 54900.17649735257, 54945.45054549403, 1093.0, 1306.3468265867066, 1468.8090548228663, 1474.1376294867623, 1534.77745726869, 28364.0, 28664.426711465236, 28974.420511590073, 29155.62665600177, 29297.0, 43157.56842431661, 43337.566624333354, 43460.56539434565, 43588.78205608952, 43594.0, 239632.0, 240241.0, 241040.0, 241193.0, 242277.0, 31914.52128218125, 31992.520112198803, 32001.0, 32059.198520037186, 32386.218757968538, 42972.57027429686, 43234.78382608067, 43787.562124378346, 44142.55857441513, 44524.55475445334, 37459.81270093632, 37984.430233545965, 38532.614673852906, 38875.6112438872, 39027.21945561016, 38086.42870356891, 38114.42828357632, 38760.80619596884, 38939.221215574966, 39568.80215598903, 34234.31531369408, 34690.306193875476, 34781.65218347784, 34831.0, 34972.47541286832, 29857.0, 31056.223594409417, 31714.207144821565, 32594.185145371557, 33777.66222337844, 25606.0, 26255.212343629722, 26960.595591065758, 28642.57036144501, 28700.0], "unit": "ms", "name": "ext.html", "value": 19344.86579558409}, {"lowerIsBetter": false, "replicates": [387.6453045463401, 388.1286511781052, 389.53061560819214, 390.8534299637636, 392.35261818000316, 14633.268336583182, 16236.512904612851, 17276.395326163587, 18879.99999999998, 18899.52751181221, 15133.31900064497, 15160.393584256644, 15852.76220856688, 18272.360467383645, 18637.7204341935, 6261.749530018805, 6398.840028999289, 6421.325760795119, 6427.035944608323, 6431.614103153803, 4654.674172807905, 4780.832670856521, 4783.617310615143, 4785.976070119645, 6406.487481001509, 6861.794146175623, 6901.5859048457005, 7094.538854974436, 7106.0, 7115.217326094152, 7024.613646249454, 7046.612436316001, 7057.258987806258, 7064.364207221349, 7075.363217310467, 5164.561012313958, 5306.310179676656, 5310.8141215057285, 6774.8983765243065, 6777.356151165632, 13606.183628982193, 13809.792853107212, 16835.579110522212, 20618.278360257318, 20640.896795515928, 2193.1885202475187, 2194.396540951251, 2197.3627647982225, 2274.476870319819, 2321.860688358704, 9496.715098547054, 9633.662821801274, 9719.56261968213, 13262.801057984138, 13484.865151348458, 1925.2202857842456, 1933.8452923766065, 1986.5728868293386, 1999.6100760351737, 2007.1168685778212, 64708.35291647071, 67338.66330668316, 68504.65747671279, 69695.30304696939, 70096.6495167526, 10900.291481053706, 11155.27490713101, 11245.887541124648, 11251.212415130862, 11329.0, 14949.70100597982, 15033.548993530212, 15060.698786024219, 15076.472323468626, 15092.094474331576, 6997.195322537973, 7739.303462688273, 7844.529328240324, 7882.684692612232, 7901.446898717035, 7376.557406555624, 7378.077740282465, 7387.556746595222, 7417.147028091731, 7421.183669796318, 4537.568930951555, 4697.31888876111, 4738.1708201064675, 4805.6155507559315, 4839.443464001614, 1762.6703882845518, 1794.3809385761892, 1815.1378095404577, 1847.6304739052243, 1925.711143328484, 445.29420867924057, 460.5762698317556, 460.8133705849136, 481.850785875682, 1006.7682738409641, 11302.773944521228, 11456.770864582662, 11628.53485860573, 11708.590199342982, 14068.507602233873, 488.0799692579513, 488.2164126576864, 989.7723523589615, 994.472929347447, 1018.2719355660738, 6045.244344456943, 6277.372262773777, 10383.273170878118, 10455.634052808267, 10665.786684266426, 647519.0, 647594.0, 664269.0, 668701.0, 671868.0, 88478.55760721155, 90665.0, 90959.54520227357, 90983.09016909878, 92669.0, 513.6507175120879, 516.2565905096724, 519.1097268185081, 519.6570263625989, 520.1002266079703, 10620.946895265473, 10720.678379648623, 10877.0, 11094.944525277322, 11151.442427878736, 39205.41191882181, 39541.40687889626, 39688.40467392933, 40340.1931961365, 40468.797656011535, 50141.74929125331, 51829.22256166236, 53519.0, 54011.45988540064, 58509.4149058504, 10269.486525673836, 10472.528736206737, 10506.789864202521, 10614.0, 10632.946835265775, 17582.82417175812, 17922.5519362017, 17935.461936141935, 17995.73006404879, 18368.908155459136, 15784.447544335893, 15842.207889605243, 15851.445199418426, 15962.12208328553, 16261.51215463538, 15512.0, 15800.446984355493, 15948.362065517249, 15949.086109666914, 16086.839131609002, 11779.646610601352, 13072.477100915858, 15469.767953480481, 16142.67714645724, 16460.506184814476, 10108.39349639024, 10176.338537995007, 10293.845592316271, 10337.638182663572, 10370.637027703995, 15350.462733804263, 15522.922385388001, 15614.609634759221, 15641.217939103228, 15841.366345346518], "unit": "ms", "name": "jquery.html", "value": 8958.93328203315}, {"lowerIsBetter": false, "replicates": [1510.2675202526775, 1558.2520390212699, 1611.6937781821446, 1706.6501367219714, 1823.0, 7157.928420715792, 7449.292317229868, 7455.0681164854395, 7456.366208872246, 7463.813404664879, 6585.736570537185, 6699.062131301622, 7378.74174403896, 7489.775306740793, 7598.810029749259, 2312.3409828198965, 2326.5928462519078, 2350.3888988862846, 2351.235848349285, 2351.905923763047, 1968.4586738646844, 2309.0, 2323.0707716913203, 2363.0902102690416, 2365.2785900300487, 1949.307995661541, 2251.909923603054, 2293.6674182243564, 2295.621222498294, 2298.0, 2206.481476852938, 2237.049254067015, 2244.5286489837154, 2254.4251215939994, 2269.7503274639776, 2222.4888275696517, 2245.887705614729, 2264.07173059045, 2266.46738016566, 2266.92065777697, 7293.16128645202, 7349.963250183715, 7370.189279179286, 7370.77887663371, 7375.963120184419, 4642.326862604903, 4727.621790256806, 4733.148033353995, 4738.4313882334, 4741.2176990796825, 3045.4365942300747, 3066.8159910405448, 3128.2492201871614, 3130.780845340827, 3150.732187764043, 1034.2398337222135, 1051.9053285204293, 1064.813657609923, 1095.2825899036145, 1130.1241537808182, 9727.951360243223, 9818.950905245429, 9844.803103937953, 9849.50752462374, 9891.30760846741, 3984.8007599619905, 4012.2176175646055, 4219.029623186684, 4225.704200705921, 4225.830966761357, 8330.291925186428, 8352.164783521599, 8356.28971537414, 8357.8328433431, 8363.372747043995, 4661.20759470884, 4663.556962088596, 4703.85888423341, 4708.411448568929, 4712.5758681718125, 4305.225059489322, 4341.110072435144, 4355.477342718831, 4361.6074553290355, 4362.6946113772365, 3289.227031647561, 3354.07762865214, 3356.278400143981, 3388.15296175956, 3392.7455440842036, 1021.9489025548842, 1030.3302853145515, 1045.6235755128148, 1065.6962765611763, 1068.6740544133959, 1154.1920655541155, 1158.976820463586, 1163.6218229075585, 1167.3929556630505, 1198.5924785572947, 7291.08861392326, 7417.332440080419, 7425.85148297031, 7476.775696729107, 7488.663010164556, 1088.8856670049531, 1122.3770807201918, 1168.5150662474978, 1174.8707642159357, 1178.4873579992732, 5928.377520360386, 5984.910226346608, 5987.550933679991, 5992.67040312787, 5995.52035837132, 8913.197812196933, 9022.368434209673, 9140.497272649935, 9155.908440915638, 9173.633054677872, 8984.955075224712, 9070.501122438329, 9184.724458266262, 9206.401583897024, 9209.079092090871, 7098.3256590623805, 7221.8555628887125, 7278.381337586258, 7380.77857664271, 7384.778456646309, 7337.59643219628, 7346.191918889025, 7350.852982940418, 7377.889331660137, 7401.370883474965, 8144.877826832484, 8189.7133600323705, 8360.0, 8377.49735015901, 8382.874256886029, 8554.957225213833, 8561.700340488052, 8562.443441176303, 8582.871256931026, 8603.741887743377, 6467.838304042437, 6533.901991470227, 6565.244996825426, 6568.146141001639, 6572.4084832365315, 7372.115346158495, 7408.666610002455, 7411.4070874328845, 7428.294312040348, 7435.851282974419, 6039.15451836752, 6872.69072891711, 6919.100516932766, 7007.544509606859, 7125.3230943060325, 6749.595024298558, 6854.314568543105, 6897.379235868796, 6897.931020689729, 7041.049458323061, 6763.898541521783, 6952.409045231009, 6957.547759395826, 6958.79123626292, 6961.79114626562, 6517.087607734826, 6537.836554086186, 6541.738330466728, 6554.737810487718, 6559.048937903882, 6524.3149469305035, 6569.113169722217, 6623.900641490285, 6625.50308726857, 6644.136262285871], "unit": "ms", "name": "mootools.html", "value": 4428.761914176839}, {"lowerIsBetter": false, "replicates": [361.78112242093533, 366.37715882998907, 368.8745826419017, 372.08281585890785, 373.5200267656061, 6040.30536488304, 6115.816525504237, 6127.816165515031, 6128.571000029994, 6138.723757430916, 5873.383294754053, 5906.26171728534, 6020.0969854521845, 6033.155358249848, 6050.546209034318, 4763.309320148576, 4778.139934811733, 4793.6884102533395, 4800.8319708810195, 4803.975980120094, 4779.569838714515, 4784.1866882629865, 4786.066716990188, 4790.832320868771, 4798.328234047236, 4450.643948484129, 4537.546245375453, 4604.056168485469, 4669.1362098011805, 4738.2892566115015, 4729.266963620645, 4740.360051393087, 4740.810367585293, 4751.049790042005, 4756.7859446325, 4209.936850947238, 4211.389348544474, 4215.91568168638, 4218.261804184286, 4223.514295855985, 5848.2689663792025, 5851.941480585182, 5855.326637436664, 5860.765569377219, 5862.443067908542, 5409.783608655649, 5573.749181286811, 5574.275344205227, 5578.163275508666, 5585.0, 6504.5121615879, 6526.412622863965, 6532.248791389004, 6533.379328963741, 6540.08438818561, 4707.340972263886, 4708.058388322315, 4713.080949214905, 4726.0, 4730.172219861511, 6661.16735408074, 6811.216710078305, 6814.795556133324, 6815.079964204769, 6827.556208846409, 4825.227963525819, 4833.782479788348, 4834.081524510402, 4835.395575553056, 4840.878978025507, 4897.877553061202, 4901.558859702573, 4905.460399356068, 4909.926351104737, 4913.656044076952, 5551.056320425538, 5556.638818476786, 5557.472040156179, 5567.053600887859, 5571.275734154534, 6412.775552855627, 6549.738010479623, 6553.803385898431, 6578.144841170713, 6583.210014798159, 1708.0605666883164, 1717.0041376001889, 1727.06738361286, 1734.765806616116, 1738.1656804733798, 1162.0761494611636, 1168.2756690851727, 1171.5957994491887, 1190.8511436070492, 1193.988060119405, 6343.111964324999, 6355.841103972345, 6400.455961243347, 6416.711247993851, 6418.550701450947, 6721.126253587003, 6776.525643204928, 6784.219814721271, 6798.592084474848, 6800.931990680129, 6247.219097612799, 6307.873842523216, 6342.080398342215, 6410.1987251593555, 6458.225012998376, 3648.7081033517256, 3695.5380577427823, 3696.5564132304294, 3701.8704345348324, 3704.759190652599, 7017.947307903855, 7029.156501219886, 7029.683664235122, 7034.191068027242, 7036.542624729376, 6914.481413893978, 6942.583444993316, 7033.261507541737, 7045.506814522934, 7050.929490705027, 7025.543339682904, 7029.051078104492, 7035.894461583081, 7039.401650859631, 7045.436365090781, 6745.0219718139615, 6839.0, 6855.348741869514, 6864.691088900911, 6864.725410983705, 6728.125343705287, 6734.663266836541, 6736.157980252468, 6747.527673062838, 6748.325167483409, 7032.437405007689, 7037.190723066913, 7037.753678621224, 7042.683079261548, 7046.436285097077, 4883.975580122076, 4887.315775791321, 4889.559939605452, 4893.09477746618, 4894.094592500399, 6714.865702686016, 6859.7256109757045, 6860.03959445688, 6866.519343646097, 6870.0, 6665.666716664245, 6666.999950007537, 6671.199456065303, 6674.532782705164, 6678.799636010927, 6473.385028422293, 6634.270230274569, 6641.269460359454, 6645.700943457459, 6645.800625981228, 6489.5781774184525, 6494.935050649433, 6499.28507864144, 6500.089987401862, 6501.707423166064, 6687.6321802301345, 6688.531802773759, 6689.0, 6693.799186024427, 6707.1616047994, 6345.587536810092, 6464.741410343534, 6482.837929051623, 6532.902006470002, 6553.082568440517], "unit": "ms", "name": "prototype.html", "value": 5012.763186800905}, {"lowerIsBetter": false, "replicates": [814.0638265994106, 829.5727700234379, 880.70936590925, 946.89584145744, 948.2177203806859, 13106.344682765859, 13347.733045339088, 13366.866331336683, 13379.130356526832, 13391.06262561621, 12539.498420063186, 12544.37278136094, 12562.30907300098, 12595.496180152782, 12694.873051269484, 738.9408847292203, 745.5936514599556, 894.1550235027901, 895.2211575928951, 914.008300993423, 909.1999040844048, 910.1990248581272, 916.4913473022503, 917.6971599372191, 918.9494577798218, 959.299711210813, 961.8845738511388, 962.3744566032066, 962.4273557233435, 962.9711108666717, 762.3063012658482, 770.5723323555425, 940.1256831146997, 951.8667386565886, 955.5365647660878, 707.3245050976305, 709.521073275537, 709.9361057504849, 712.0387476906187, 712.1098626716604, 12906.935465322707, 13021.544245951442, 13025.283609401478, 13031.609051728463, 13037.087403881733, 12702.301373424458, 13758.724825503434, 13844.169349839041, 13981.440742370292, 13993.860061399357, 10684.946575267151, 10796.082333001705, 10893.455327233607, 10901.890981090168, 10965.067969222626, 3834.4056671215726, 3883.650471457582, 3884.9805750971345, 3887.961120388788, 3889.863854765098, 49046.50953490455, 49327.75336123332, 50233.49766502361, 50692.239616405415, 50701.492985070414, 10828.945855270831, 11007.119430445546, 11108.277961932448, 11114.333140011626, 11313.886861131448, 19572.902135489516, 19582.510437239183, 19599.90200049019, 19605.803941960683, 19626.11682474292, 7910.327622152182, 7948.205179482005, 8299.95850020746, 8525.104863989316, 8568.057513673491, 4395.362672412482, 4538.585457574203, 4550.317452382169, 4574.108048930493, 4686.742229177428, 3025.773067019982, 3072.339447018902, 3093.1648454917045, 3095.845207739604, 3101.65881753007, 558.5866458820443, 569.0496870226731, 569.6297406685604, 575.1545228514077, 581.0992960910572, 604.1994357476361, 645.3481983197005, 678.7861823525574, 706.7914965085312, 719.1370355573333, 10155.136813370798, 10234.692959211236, 10397.84403233952, 10716.0, 10924.235303528676, 548.8682716148096, 568.1279236372148, 578.0173704701939, 620.4416025576927, 735.9595222262827, 4294.205571969133, 4407.823687052482, 4469.709468884512, 4633.2818413146015, 4678.508756580578, 59913.0, 62647.0, 63391.68304158542, 63730.36269637337, 67202.0, 56327.43672563304, 57345.0, 57765.0, 57948.71025644929, 58049.0, 51657.74171129196, 51906.74046629743, 52091.21863172131, 52311.0, 52541.47458525442, 9054.456732596065, 9160.954195228982, 9238.491882946502, 9280.814383712153, 9324.580393882427, 36085.27829443344, 36731.81634091812, 37463.0, 37482.812585936896, 37784.433233502066, 44739.32891006702, 49875.75062124666, 50910.23634645409, 50987.235191471416, 51469.742651286506, 11667.6499705009, 11705.648830535098, 11733.706657333634, 11753.236039657395, 11973.461194246105, 12900.483980640669, 12902.741945160857, 13477.32613369308, 13658.590242292747, 14141.010129290851, 12339.197952133083, 12627.684307892376, 12654.683632909251, 12987.415566299727, 13334.13328133707, 12063.819042714542, 12179.939100304442, 12309.076819238771, 12538.435770390171, 12990.740185196431, 11837.171398002058, 11982.460789264329, 12006.0, 12228.144029917821, 12252.448639811051, 9760.0, 9910.801783964425, 10032.69901902944, 10326.535305911102, 10598.576056957636, 9552.856707149538, 9714.611415543583, 9766.632671425865, 9813.116819485995, 10235.23235757336], "unit": "ms", "name": "yui.html", "value": 6741.327454444068}], "name": "dromaeo_css", "value": 7219.480578759689}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [107.0, 109.0, 103.0, 107.0, 108.0, 110.0, 99.0, 107.0, 109.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 106.8}, {"lowerIsBetter": true, "replicates": [149.0, 121.0, 143.0, 164.0, 122.0, 146.0, 151.0, 125.0, 173.0, 121.0], "unit": "ms", "name": "audio-beat-detection", "value": 141.5}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 160.0, 161.0, 161.0, 163.0, 159.0, 161.0, 161.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.9}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 70.0, 155.0, 71.0, 72.0, 71.0, 71.0, 172.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 89.5}, {"lowerIsBetter": true, "replicates": [94.0, 92.0, 146.0, 91.0, 93.0, 92.0, 136.0, 92.0, 93.0, 132.0], "unit": "ms", "name": "audio-oscillator", "value": 106.1}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 146.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.5}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.0}, {"lowerIsBetter": true, "replicates": [56.0, 54.0, 60.0, 55.0, 55.0, 56.0, 57.0, 57.0, 54.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 55.8}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 64.0, 64.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.7}, {"lowerIsBetter": true, "replicates": [77.0, 78.0, 78.0, 77.0, 78.0, 78.0, 79.0, 85.0, 81.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.8}, {"lowerIsBetter": true, "replicates": [115.0, 126.0, 117.0, 111.0, 114.0, 116.0, 117.0, 111.0, 125.0, 113.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 116.5}, {"lowerIsBetter": true, "replicates": [140.0, 154.0, 141.0, 143.0, 141.0, 144.0, 131.0, 142.0, 141.0, 134.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 141.1}, {"lowerIsBetter": true, "replicates": [65.0, 64.0, 64.0, 64.0, 64.0, 65.0, 65.0, 64.0, 66.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.7}], "name": "kraken", "value": 1476.0}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.8697330871244], "unit": "score", "name": "Richards", "value": 210.29401399999998}, {"lowerIsBetter": false, "replicates": [165.94756057085962], "unit": "score", "name": "DeltaBlue", "value": 398.4270680000002}, {"lowerIsBetter": false, "replicates": [280.74115665356544], "unit": "score", "name": "Encrypt", "value": 948.136722}, {"lowerIsBetter": false, "replicates": [5164.948453608247], "unit": "score", "name": "Decrypt", "value": 51.53604191616765}, {"lowerIsBetter": false, "replicates": [945.179584120983], "unit": "score", "name": "RayTrace", "value": 782.908362}, {"lowerIsBetter": false, "replicates": [539.6654074473827], "unit": "score", "name": "Earley", "value": 1234.9559390000002}, {"lowerIsBetter": false, "replicates": [10526.315789473685], "unit": "score", "name": "Boyer", "value": 63.313984999999995}, {"lowerIsBetter": false, "replicates": [24047.619047619046], "unit": "score", "name": "RegExp", "value": 37.88254455445545}, {"lowerIsBetter": false, "replicates": [533.0490405117271], "unit": "score", "name": "Splay", "value": 152.87711600000006}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 23260.31566464996}]} 14:53:19 INFO - RETURN: dromaeo_css: 7219.48 14:53:19 INFO - RETURN: kraken: 1476.00 14:53:19 INFO - RETURN: v8_7: 23260.32 14:53:19 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23260.32"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1476.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7219.48"}}} 14:53:19 INFO - Return code: 0 14:53:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 14:53:19 INFO - # TBPL SUCCESS # 14:53:19 INFO - Running post-action listener: _resource_record_post_action 14:53:19 INFO - Running post-run listener: _resource_record_post_run 14:53:20 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 327680; Write bytes: 510382080; Read time: 124; Write time: 702812 14:53:20 INFO - install - Wall time: 15s; CPU: 16.0%; Read bytes: 4096; Write bytes: 250085376; Read time: 44; Write time: 457112 14:53:20 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 323584; Write bytes: 260063232; Read time: 80; Write time: 245652 14:53:20 INFO - Running post-run listener: _upload_blobber_files 14:53:20 INFO - Blob upload gear active. 14:53:20 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 14:53:20 INFO - Copying logs to upload dir... 14:53:20 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1255.908792 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-02-25 14:53:20.259214) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-25 14:53:20.262760) ========= 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=836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009974 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456431865/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-25 14:53:20.314503) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 14:53:20.314917) ========= 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=836cfdc75598d54cb747368e000001a9-1456439430.632506-1637786163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004539 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 14:53:20.364274) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 14:53:20.364592) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 14:53:20.364889) ========= ========= Total master_lag: 0.25 =========