builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-044 starttime: 1490959813.58 results: success (0) revision: 03d602fd723ad6ff4588c04855884ffa1dee9410 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:13.579062) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:13.579516) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:13.579795) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:13.595261) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:13.595647) ========= 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/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-31 04:30:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.2M=0s 2017-03-31 04:30:13 (46.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092524 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:13.705305) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:13.705663) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', '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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023428 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:13.755027) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-31 04:30:13.758534) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 03d602fd723ad6ff4588c04855884ffa1dee9410 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 03d602fd723ad6ff4588c04855884ffa1dee9410 --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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-31 04:30:14,088 truncating revision to first 12 chars 2017-03-31 04:30:14,088 Setting DEBUG logging. 2017-03-31 04:30:14,088 attempt 1/10 2017-03-31 04:30:14,088 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/03d602fd723a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-31 04:30:15,069 unpacking tar archive at: mozilla-central-03d602fd723a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.289058 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-31 04:30:15.061086) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:15.061449) ========= script_repo_revision: 03d602fd723ad6ff4588c04855884ffa1dee9410 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:15.061845) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:15.062127) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:15.072874) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-03-31 04:30:15.073184) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:30:15 INFO - MultiFileLogger online at 20170331 04:30:15 in /builds/slave/test 04:30:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 04:30:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:30:15 INFO - {'append_to_log': False, 04:30:15 INFO - 'base_work_dir': '/builds/slave/test', 04:30:15 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 04:30:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:30:15 INFO - 'branch': 'Firefox-Non-PGO', 04:30:15 INFO - 'buildbot_json_path': 'buildprops.json', 04:30:15 INFO - 'config_files': ('talos/linux_config.py',), 04:30:15 INFO - 'default_actions': ('clobber', 04:30:15 INFO - 'read-buildbot-config', 04:30:15 INFO - 'download-and-extract', 04:30:15 INFO - 'populate-webroot', 04:30:15 INFO - 'create-virtualenv', 04:30:15 INFO - 'install', 04:30:15 INFO - 'run-tests'), 04:30:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:30:15 INFO - 'download_minidump_stackwalk': True, 04:30:15 INFO - 'download_symbols': 'ondemand', 04:30:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:30:15 INFO - 'tooltool.py': '/tools/tooltool.py', 04:30:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:30:15 INFO - '/tools/misc-python/virtualenv.py')}, 04:30:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:30:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:30:15 INFO - 'gecko_profile': False, 04:30:15 INFO - 'gecko_profile_interval': 0, 04:30:15 INFO - 'installer_path': 'installer.exe', 04:30:15 INFO - 'log_level': 'info', 04:30:15 INFO - 'log_name': 'talos', 04:30:15 INFO - 'log_to_console': True, 04:30:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:30:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:30:15 INFO - 'opt_config_files': (), 04:30:15 INFO - 'pip_index': False, 04:30:15 INFO - 'suite': 'dromaeojs-e10s', 04:30:15 INFO - 'system_bits': '32', 04:30:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:30:15 INFO - 'title': 'talos-linux64-ix-044', 04:30:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:30:15 INFO - 'use_talos_json': True, 04:30:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:30:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:30:15 INFO - 'work_dir': 'build'} 04:30:15 INFO - [mozharness: 2017-03-31 11:30:15.493121Z] Running clobber step. 04:30:15 INFO - Running pre-action listener: _resource_record_pre_action 04:30:15 INFO - Running main action method: clobber 04:30:15 INFO - rmtree: /builds/slave/test/build 04:30:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:30:16 INFO - Running post-action listener: _resource_record_post_action 04:30:16 INFO - [mozharness: 2017-03-31 11:30:16.495762Z] Finished clobber step (success) 04:30:16 INFO - [mozharness: 2017-03-31 11:30:16.495863Z] Running read-buildbot-config step. 04:30:16 INFO - Running pre-action listener: _resource_record_pre_action 04:30:16 INFO - Running main action method: read_buildbot_config 04:30:16 INFO - Using buildbot properties: 04:30:16 INFO - { 04:30:16 INFO - "project": "", 04:30:16 INFO - "product": "firefox", 04:30:16 INFO - "who": "cbook@mozilla.com", 04:30:16 INFO - "installer_path": "public/build/target.tar.bz2", 04:30:16 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:30:16 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 04:30:16 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 04:30:16 INFO - "stage_platform": "linux64", 04:30:16 INFO - "basedir": "/builds/slave/test", 04:30:16 INFO - "buildnumber": 781, 04:30:16 INFO - "platform": "ubuntu64_hw", 04:30:16 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:30:16 INFO - "slavebuilddir": "test", 04:30:16 INFO - "taskId": "UFxz5J0yRrKlEYJovTfBOQ", 04:30:16 INFO - "branch": "mozilla-central", 04:30:16 INFO - "script_repo_revision": "production", 04:30:16 INFO - "revision": "03d602fd723ad6ff4588c04855884ffa1dee9410", 04:30:16 INFO - "slavename": "talos-linux64-ix-044", 04:30:16 INFO - "repo_path": "mozilla-central" 04:30:16 INFO - } 04:30:16 INFO - Finding installer, test and symbols from parent task. 04:30:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UFxz5J0yRrKlEYJovTfBOQ'}, attempt #1 04:30:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UFxz5J0yRrKlEYJovTfBOQ'}, attempt #1 04:30:17 INFO - Task dependencies: FziPe46tTL-TfFHS0Z7sXw 04:30:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw'}, attempt #1 04:30:17 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.tar.bz2 04:30:17 INFO - Set test_url: https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/test_packages.json 04:30:17 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.crashreporter-symbols.zip 04:30:17 INFO - Running post-action listener: _resource_record_post_action 04:30:17 INFO - [mozharness: 2017-03-31 11:30:17.839908Z] Finished read-buildbot-config step (success) 04:30:17 INFO - [mozharness: 2017-03-31 11:30:17.840127Z] Running download-and-extract step. 04:30:17 INFO - Running pre-action listener: _resource_record_pre_action 04:30:17 INFO - Running main action method: download_and_extract 04:30:17 INFO - mkdir: /builds/slave/test/build/tests 04:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:17 INFO - trying https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.test_packages.json 04:30:17 INFO - Downloading https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:30:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:30:18 INFO - Downloaded 986 bytes. 04:30:18 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:30:18 INFO - Using the following test package requirements: 04:30:18 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:30:18 INFO - u'common': [u'target.common.tests.zip'], 04:30:18 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:30:18 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:30:18 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:30:18 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:30:18 INFO - u'mozbase': [u'target.common.tests.zip'], 04:30:18 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:30:18 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:30:18 INFO - u'web-platform': [u'target.common.tests.zip', 04:30:18 INFO - u'target.web-platform.tests.zip'], 04:30:18 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:30:18 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 04:30:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.common.tests.zip 04:30:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:30:18 INFO - Fetch https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.common.tests.zip into memory 04:30:19 INFO - Expected file size: 58227794 04:30:19 INFO - Obtained file size: 58227794 04:30:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 04:30:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.common.tests.zip 04:30:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:30:29 INFO - Fetch https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.common.tests.zip into memory 04:30:30 INFO - Expected file size: 58227794 04:30:30 INFO - Obtained file size: 58227794 04:30:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.talos.tests.zip 04:30:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:30:41 INFO - Fetch https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.talos.tests.zip into memory 04:30:41 INFO - Expected file size: 14404152 04:30:41 INFO - Obtained file size: 14404152 04:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:42 INFO - trying https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.tar.bz2 04:30:42 INFO - Downloading https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:30:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:30:43 INFO - Downloaded 62478421 bytes. 04:30:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.tar.bz2 04:30:43 INFO - mkdir: /builds/slave/test/properties 04:30:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:30:43 INFO - Writing to file /builds/slave/test/properties/build_url 04:30:43 INFO - Contents: 04:30:43 INFO - build_url:https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.tar.bz2 04:30:43 INFO - Running post-action listener: _resource_record_post_action 04:30:43 INFO - Running post-action listener: set_extra_try_arguments 04:30:43 INFO - [mozharness: 2017-03-31 11:30:43.349703Z] Finished download-and-extract step (success) 04:30:43 INFO - [mozharness: 2017-03-31 11:30:43.349800Z] Running populate-webroot step. 04:30:43 INFO - Running pre-action listener: _resource_record_pre_action 04:30:43 INFO - Running main action method: populate_webroot 04:30:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:30:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:30:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:30:43 INFO - '--tppagecycles', 04:30:43 INFO - '7'], 04:30:43 INFO - 'tests': ['tsvgm']}}, 04:30:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:30:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:30:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:30:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:30:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:30:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:30:43 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:30:43 INFO - 'talos_options': ['--disable-e10s'], 04:30:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:30:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:30:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:30:43 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:30:43 INFO - 'talos_options': ['--disable-e10s'], 04:30:43 INFO - 'tests': ['damp', 'tps']}, 04:30:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:30:43 INFO - 'tests': ['damp', 'tps']}, 04:30:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:30:43 INFO - 'tests': ['dromaeo_dom']}, 04:30:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:30:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:30:43 INFO - 'tests': ['basic_compositor_video']}, 04:30:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:30:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:30:43 INFO - 'tests': ['a11yr', 04:30:43 INFO - 'ts_paint', 04:30:43 INFO - 'tpaint', 04:30:43 INFO - 'sessionrestore', 04:30:43 INFO - 'sessionrestore_no_auto_restore', 04:30:43 INFO - 'tabpaint']}, 04:30:43 INFO - 'other-e10s': {'tests': ['a11yr', 04:30:43 INFO - 'ts_paint', 04:30:43 INFO - 'tpaint', 04:30:43 INFO - 'sessionrestore', 04:30:43 INFO - 'sessionrestore_no_auto_restore', 04:30:43 INFO - 'tabpaint']}, 04:30:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:30:43 INFO - 'ts_paint', 04:30:43 INFO - 'tpaint', 04:30:43 INFO - 'sessionrestore', 04:30:43 INFO - 'sessionrestore_no_auto_restore', 04:30:43 INFO - 'tabpaint']}, 04:30:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:30:43 INFO - 'ts_paint', 04:30:43 INFO - 'tpaint', 04:30:43 INFO - 'sessionrestore', 04:30:43 INFO - 'sessionrestore_no_auto_restore', 04:30:43 INFO - 'tabpaint']}, 04:30:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:30:43 INFO - 'tests': ['a11yr', 04:30:43 INFO - 'ts_paint', 04:30:43 INFO - 'tpaint', 04:30:43 INFO - 'sessionrestore', 04:30:43 INFO - 'sessionrestore_no_auto_restore', 04:30:43 INFO - 'tabpaint']}, 04:30:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:30:43 INFO - 'tests': ['a11yr', 04:30:43 INFO - 'ts_paint', 04:30:43 INFO - 'tpaint', 04:30:43 INFO - 'sessionrestore', 04:30:43 INFO - 'sessionrestore_no_auto_restore', 04:30:43 INFO - 'tabpaint']}, 04:30:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:30:43 INFO - 'tests': ['tsvgx', 04:30:43 INFO - 'tsvgr_opacity', 04:30:43 INFO - 'tart', 04:30:43 INFO - 'tscrollx', 04:30:43 INFO - 'cart', 04:30:43 INFO - 'tsvg_static']}, 04:30:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:30:43 INFO - 'tsvgr_opacity', 04:30:43 INFO - 'tart', 04:30:43 INFO - 'tscrollx', 04:30:43 INFO - 'cart', 04:30:43 INFO - 'tsvg_static']}, 04:30:43 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:30:43 INFO - 'talos_options': ['--disable-e10s'], 04:30:43 INFO - 'tests': ['tp5o']}, 04:30:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:30:43 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:30:43 INFO - 'talos_options': ['--disable-e10s', 04:30:43 INFO - '--xperf_path', 04:30:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:30:43 INFO - 'tests': ['tp5n']}, 04:30:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:30:43 INFO - 'talos_options': ['--xperf_path', 04:30:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:30:43 INFO - 'tests': ['tp5n']}}, 04:30:43 INFO - 'talos.zip': {'path': '', 04:30:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:30:43 INFO - Running post-action listener: _resource_record_post_action 04:30:43 INFO - [mozharness: 2017-03-31 11:30:43.362927Z] Finished populate-webroot step (success) 04:30:43 INFO - [mozharness: 2017-03-31 11:30:43.363016Z] Running create-virtualenv step. 04:30:43 INFO - Running pre-action listener: _resource_record_pre_action 04:30:43 INFO - Running main action method: create_virtualenv 04:30:43 INFO - Creating virtualenv /builds/slave/test/build/venv 04:30:43 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:30:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:30:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:30:43 INFO - Using real prefix '/usr' 04:30:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:30:43 INFO - Installing distribute.............................................................................................................................................................................................done. 04:30:45 INFO - Installing pip.................done. 04:30:45 INFO - Return code: 0 04:30:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:30:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:30:46 INFO - Reading from file tmpfile_stdout 04:30:46 INFO - Output received: 04:30:46 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:30:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/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 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 04:30:46 INFO - Running command: ['/builds/slave/test/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/build 04:30:46 INFO - Copy/paste: /builds/slave/test/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 04:30:46 INFO - Using env: {'DISPLAY': ':0', 04:30:46 INFO - 'HOME': '/home/cltbld', 04:30:46 INFO - 'LANG': 'en_US.UTF-8', 04:30:46 INFO - 'LANGUAGE': 'en_US:en', 04:30:46 INFO - 'LOGNAME': 'cltbld', 04:30:46 INFO - 'MAIL': '/var/mail/cltbld', 04:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:46 INFO - 'MOZ_NO_REMOTE': '1', 04:30:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:46 INFO - 'NO_EM_RESTART': '1', 04:30:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:46 INFO - 'PWD': '/builds/slave/test', 04:30:46 INFO - 'SHELL': '/bin/bash', 04:30:46 INFO - 'SHLVL': '1', 04:30:46 INFO - 'TERM': 'linux', 04:30:46 INFO - 'TMOUT': '86400', 04:30:46 INFO - 'USER': 'cltbld', 04:30:46 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', 04:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:46 INFO - '_': '/tools/buildbot/bin/python'} 04:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:46 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 04:30:46 INFO - Cleaning up... 04:30:46 INFO - Return code: 0 04:30:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 04:30:46 INFO - Running command: ['/builds/slave/test/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>=3.1.1'] in /builds/slave/test/build 04:30:46 INFO - Copy/paste: /builds/slave/test/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>=3.1.1 04:30:46 INFO - Using env: {'DISPLAY': ':0', 04:30:46 INFO - 'HOME': '/home/cltbld', 04:30:46 INFO - 'LANG': 'en_US.UTF-8', 04:30:46 INFO - 'LANGUAGE': 'en_US:en', 04:30:46 INFO - 'LOGNAME': 'cltbld', 04:30:46 INFO - 'MAIL': '/var/mail/cltbld', 04:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:46 INFO - 'MOZ_NO_REMOTE': '1', 04:30:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:46 INFO - 'NO_EM_RESTART': '1', 04:30:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:46 INFO - 'PWD': '/builds/slave/test', 04:30:46 INFO - 'SHELL': '/bin/bash', 04:30:46 INFO - 'SHLVL': '1', 04:30:46 INFO - 'TERM': 'linux', 04:30:46 INFO - 'TMOUT': '86400', 04:30:46 INFO - 'USER': 'cltbld', 04:30:46 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', 04:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:46 INFO - '_': '/tools/buildbot/bin/python'} 04:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:46 INFO - Downloading/unpacking psutil>=3.1.1 04:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:30:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:30:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:30:48 INFO - Installing collected packages: psutil 04:30:48 INFO - Running setup.py install for psutil 04:30:48 INFO - building 'psutil._psutil_linux' extension 04:30:48 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:30:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:30:49 INFO - building 'psutil._psutil_posix' extension 04:30:49 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 04:30:49 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 04:30:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:30:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:30:49 INFO - Successfully installed psutil 04:30:49 INFO - Cleaning up... 04:30:49 INFO - Return code: 0 04:30:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 04:30:49 INFO - Running command: ['/builds/slave/test/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.3'] in /builds/slave/test/build 04:30:49 INFO - Copy/paste: /builds/slave/test/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.3 04:30:49 INFO - Using env: {'DISPLAY': ':0', 04:30:49 INFO - 'HOME': '/home/cltbld', 04:30:49 INFO - 'LANG': 'en_US.UTF-8', 04:30:49 INFO - 'LANGUAGE': 'en_US:en', 04:30:49 INFO - 'LOGNAME': 'cltbld', 04:30:49 INFO - 'MAIL': '/var/mail/cltbld', 04:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:49 INFO - 'MOZ_NO_REMOTE': '1', 04:30:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:49 INFO - 'NO_EM_RESTART': '1', 04:30:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:49 INFO - 'PWD': '/builds/slave/test', 04:30:49 INFO - 'SHELL': '/bin/bash', 04:30:49 INFO - 'SHLVL': '1', 04:30:49 INFO - 'TERM': 'linux', 04:30:49 INFO - 'TMOUT': '86400', 04:30:49 INFO - 'USER': 'cltbld', 04:30:49 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', 04:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:49 INFO - '_': '/tools/buildbot/bin/python'} 04:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:30:49 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 04:30:49 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 04:30:49 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 04:30:49 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 04:30:49 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 04:30:49 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 04:30:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:30:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:30:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:30:51 INFO - Installing collected packages: mozsystemmonitor 04:30:51 INFO - Running setup.py install for mozsystemmonitor 04:30:51 INFO - Successfully installed mozsystemmonitor 04:30:51 INFO - Cleaning up... 04:30:51 INFO - Return code: 0 04:30:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:30:51 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:51 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 04:30:51 INFO - Running command: ['/builds/slave/test/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==2.5.1'] in /builds/slave/test/build 04:30:51 INFO - Copy/paste: /builds/slave/test/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==2.5.1 04:30:51 INFO - Using env: {'DISPLAY': ':0', 04:30:51 INFO - 'HOME': '/home/cltbld', 04:30:51 INFO - 'LANG': 'en_US.UTF-8', 04:30:51 INFO - 'LANGUAGE': 'en_US:en', 04:30:51 INFO - 'LOGNAME': 'cltbld', 04:30:51 INFO - 'MAIL': '/var/mail/cltbld', 04:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:51 INFO - 'MOZ_NO_REMOTE': '1', 04:30:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:51 INFO - 'NO_EM_RESTART': '1', 04:30:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:51 INFO - 'PWD': '/builds/slave/test', 04:30:51 INFO - 'SHELL': '/bin/bash', 04:30:51 INFO - 'SHLVL': '1', 04:30:51 INFO - 'TERM': 'linux', 04:30:51 INFO - 'TMOUT': '86400', 04:30:51 INFO - 'USER': 'cltbld', 04:30:51 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', 04:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:51 INFO - '_': '/tools/buildbot/bin/python'} 04:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:51 INFO - Downloading/unpacking jsonschema==2.5.1 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:30:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:54 INFO - Downloading functools32-3.2.3-2.tar.gz 04:30:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:30:54 INFO - warning: no files found matching '*.txt' 04:30:54 INFO - no previously-included directories found matching 'build' 04:30:54 INFO - no previously-included directories found matching 'dist' 04:30:54 INFO - no previously-included directories found matching '.git*' 04:30:54 INFO - Installing collected packages: jsonschema, functools32 04:30:54 INFO - Running setup.py install for functools32 04:30:54 INFO - warning: no files found matching '*.txt' 04:30:54 INFO - no previously-included directories found matching 'build' 04:30:54 INFO - no previously-included directories found matching 'dist' 04:30:54 INFO - no previously-included directories found matching '.git*' 04:30:54 INFO - Successfully installed jsonschema functools32 04:30:54 INFO - Cleaning up... 04:30:54 INFO - Return code: 0 04:30:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:30:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 04:30:54 INFO - Running command: ['/builds/slave/test/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', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:30:54 INFO - Copy/paste: /builds/slave/test/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 functools32==3.2.3-2 04:30:54 INFO - Using env: {'DISPLAY': ':0', 04:30:54 INFO - 'HOME': '/home/cltbld', 04:30:54 INFO - 'LANG': 'en_US.UTF-8', 04:30:54 INFO - 'LANGUAGE': 'en_US:en', 04:30:54 INFO - 'LOGNAME': 'cltbld', 04:30:54 INFO - 'MAIL': '/var/mail/cltbld', 04:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:54 INFO - 'MOZ_NO_REMOTE': '1', 04:30:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:54 INFO - 'NO_EM_RESTART': '1', 04:30:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:54 INFO - 'PWD': '/builds/slave/test', 04:30:54 INFO - 'SHELL': '/bin/bash', 04:30:54 INFO - 'SHLVL': '1', 04:30:54 INFO - 'TERM': 'linux', 04:30:54 INFO - 'TMOUT': '86400', 04:30:54 INFO - 'USER': 'cltbld', 04:30:54 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', 04:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:54 INFO - '_': '/tools/buildbot/bin/python'} 04:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:30:54 INFO - Cleaning up... 04:30:54 INFO - Return code: 0 04:30:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:30:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/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 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 04:30:54 INFO - Running command: ['/builds/slave/test/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/build 04:30:54 INFO - Copy/paste: /builds/slave/test/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 04:30:54 INFO - Using env: {'DISPLAY': ':0', 04:30:54 INFO - 'HOME': '/home/cltbld', 04:30:54 INFO - 'LANG': 'en_US.UTF-8', 04:30:54 INFO - 'LANGUAGE': 'en_US:en', 04:30:54 INFO - 'LOGNAME': 'cltbld', 04:30:54 INFO - 'MAIL': '/var/mail/cltbld', 04:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:54 INFO - 'MOZ_NO_REMOTE': '1', 04:30:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:54 INFO - 'NO_EM_RESTART': '1', 04:30:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:54 INFO - 'PWD': '/builds/slave/test', 04:30:54 INFO - 'SHELL': '/bin/bash', 04:30:54 INFO - 'SHLVL': '1', 04:30:54 INFO - 'TERM': 'linux', 04:30:54 INFO - 'TMOUT': '86400', 04:30:54 INFO - 'USER': 'cltbld', 04:30:54 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', 04:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:54 INFO - '_': '/tools/buildbot/bin/python'} 04:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:54 INFO - Downloading/unpacking blobuploader==1.2.4 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30:56 INFO - Downloading blobuploader-1.2.4.tar.gz 04:30:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:30:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:30:56 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 04:30:56 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 04:30:56 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 04:30:56 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 04:30:56 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 04:30:56 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 04:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:30:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:57 INFO - Downloading docopt-0.6.1.tar.gz 04:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:30:57 INFO - Installing collected packages: blobuploader, requests, docopt 04:30:57 INFO - Running setup.py install for blobuploader 04:30:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:30:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:30:57 INFO - Running setup.py install for requests 04:30:58 INFO - Running setup.py install for docopt 04:30:58 INFO - Successfully installed blobuploader requests docopt 04:30:58 INFO - Cleaning up... 04:30:58 INFO - Return code: 0 04:30:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 04:30:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 04:30:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/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 04:30:58 INFO - Using env: {'DISPLAY': ':0', 04:30:58 INFO - 'HOME': '/home/cltbld', 04:30:58 INFO - 'LANG': 'en_US.UTF-8', 04:30:58 INFO - 'LANGUAGE': 'en_US:en', 04:30:58 INFO - 'LOGNAME': 'cltbld', 04:30:58 INFO - 'MAIL': '/var/mail/cltbld', 04:30:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:58 INFO - 'MOZ_NO_REMOTE': '1', 04:30:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:58 INFO - 'NO_EM_RESTART': '1', 04:30:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:58 INFO - 'PWD': '/builds/slave/test', 04:30:58 INFO - 'SHELL': '/bin/bash', 04:30:58 INFO - 'SHLVL': '1', 04:30:58 INFO - 'TERM': 'linux', 04:30:58 INFO - 'TMOUT': '86400', 04:30:58 INFO - 'USER': 'cltbld', 04:30:58 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', 04:30:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:58 INFO - '_': '/tools/buildbot/bin/python'} 04:30:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:30:58 INFO - Running setup.py (path:/tmp/pip-ijM3M6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:30:58 INFO - Running setup.py (path:/tmp/pip-rjM4nn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:30:58 INFO - Running setup.py (path:/tmp/pip-vZbrXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:30:58 INFO - Running setup.py (path:/tmp/pip-f6EGUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:30:58 INFO - Running setup.py (path:/tmp/pip-18BSgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:30:58 INFO - Running setup.py (path:/tmp/pip-2wB9cL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:30:58 INFO - Running setup.py (path:/tmp/pip-AP47VR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:30:59 INFO - Running setup.py (path:/tmp/pip-fEgfVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:30:59 INFO - Running setup.py (path:/tmp/pip-J4UEfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:30:59 INFO - Running setup.py (path:/tmp/pip-m6iKVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:30:59 INFO - Running setup.py (path:/tmp/pip-1ubiUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:30:59 INFO - Running setup.py (path:/tmp/pip-_MmH14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:30:59 INFO - Running setup.py (path:/tmp/pip-Vv0qYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:30:59 INFO - Running setup.py (path:/tmp/pip-nGZpMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:59 INFO - Running setup.py (path:/tmp/pip-ZKhPt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:30:59 INFO - Running setup.py (path:/tmp/pip-Ii8jDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:30:59 INFO - Running setup.py (path:/tmp/pip-3Jqula-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:30:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:30:59 INFO - Running setup.py install for manifestparser 04:30:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:30:59 INFO - Running setup.py install for mozcrash 04:30:59 INFO - Running setup.py install for mozdebug 04:31:00 INFO - Running setup.py install for mozdevice 04:31:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Running setup.py install for mozfile 04:31:00 INFO - Running setup.py install for mozhttpd 04:31:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Running setup.py install for mozinfo 04:31:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Running setup.py install for mozInstall 04:31:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Running setup.py install for mozleak 04:31:00 INFO - Running setup.py install for mozlog 04:31:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Running setup.py install for moznetwork 04:31:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Running setup.py install for mozprocess 04:31:00 INFO - Running setup.py install for mozprofile 04:31:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:31:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:31:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:31:01 INFO - Running setup.py install for mozrunner 04:31:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:31:01 INFO - Running setup.py install for mozscreenshot 04:31:01 INFO - Running setup.py install for moztest 04:31:01 INFO - Running setup.py install for mozversion 04:31:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:31:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:31:01 INFO - Cleaning up... 04:31:01 INFO - Return code: 0 04:31:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 04:31:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 04:31:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/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 04:31:01 INFO - Using env: {'DISPLAY': ':0', 04:31:01 INFO - 'HOME': '/home/cltbld', 04:31:01 INFO - 'LANG': 'en_US.UTF-8', 04:31:01 INFO - 'LANGUAGE': 'en_US:en', 04:31:01 INFO - 'LOGNAME': 'cltbld', 04:31:01 INFO - 'MAIL': '/var/mail/cltbld', 04:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:01 INFO - 'MOZ_NO_REMOTE': '1', 04:31:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:31:01 INFO - 'NO_EM_RESTART': '1', 04:31:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:01 INFO - 'PWD': '/builds/slave/test', 04:31:01 INFO - 'SHELL': '/bin/bash', 04:31:01 INFO - 'SHLVL': '1', 04:31:01 INFO - 'TERM': 'linux', 04:31:01 INFO - 'TMOUT': '86400', 04:31:01 INFO - 'USER': 'cltbld', 04:31:01 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', 04:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:01 INFO - '_': '/tools/buildbot/bin/python'} 04:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:31:01 INFO - Running setup.py (path:/tmp/pip-xlDbW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:31:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:31:01 INFO - Running setup.py (path:/tmp/pip-TtqmCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:31:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:31:01 INFO - Running setup.py (path:/tmp/pip-4Hpu8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:31:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:31:01 INFO - Running setup.py (path:/tmp/pip-EW8Zlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:31:02 INFO - Running setup.py (path:/tmp/pip-K31Ftf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:31:02 INFO - Running setup.py (path:/tmp/pip-fhFo79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:31:02 INFO - Running setup.py (path:/tmp/pip-laEEkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:31:02 INFO - Running setup.py (path:/tmp/pip-HWwISh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:31:02 INFO - Running setup.py (path:/tmp/pip-dmcHIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:31:02 INFO - Running setup.py (path:/tmp/pip-Ub2_Ut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:31:02 INFO - Running setup.py (path:/tmp/pip-9cjYCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:31:02 INFO - Running setup.py (path:/tmp/pip-Oaj7yY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:31:02 INFO - Running setup.py (path:/tmp/pip-qtwgSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:31:02 INFO - Running setup.py (path:/tmp/pip-TTl7MU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:02 INFO - Running setup.py (path:/tmp/pip-mIamL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:31:02 INFO - Running setup.py (path:/tmp/pip-x3AFtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:31:02 INFO - Running setup.py (path:/tmp/pip-OUDZlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:31:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:31:02 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 04:31:02 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 04:31:02 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 04:31:02 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 04:31:02 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 04:31:02 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 04:31:04 INFO - Downloading blessings-1.6.tar.gz 04:31:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:31:05 INFO - Installing collected packages: blessings 04:31:05 INFO - Running setup.py install for blessings 04:31:05 INFO - Successfully installed blessings 04:31:05 INFO - Cleaning up... 04:31:05 INFO - Return code: 0 04:31:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:31:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:31:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:31:05 INFO - Reading from file tmpfile_stdout 04:31:05 INFO - Current package versions: 04:31:05 INFO - argparse == 1.2.1 04:31:05 INFO - blessings == 1.6 04:31:05 INFO - blobuploader == 1.2.4 04:31:05 INFO - docopt == 0.6.1 04:31:05 INFO - functools32 == 3.2.3-2 04:31:05 INFO - jsonschema == 2.5.1 04:31:05 INFO - manifestparser == 1.1 04:31:05 INFO - mozInstall == 1.12 04:31:05 INFO - mozcrash == 1.0 04:31:05 INFO - mozdebug == 0.1 04:31:05 INFO - mozdevice == 0.49 04:31:05 INFO - mozfile == 1.2 04:31:05 INFO - mozhttpd == 0.7 04:31:05 INFO - mozinfo == 0.9 04:31:05 INFO - mozleak == 0.1 04:31:05 INFO - mozlog == 3.4 04:31:05 INFO - moznetwork == 0.27 04:31:05 INFO - mozprocess == 0.25 04:31:05 INFO - mozprofile == 0.28 04:31:05 INFO - mozrunner == 6.13 04:31:05 INFO - mozscreenshot == 0.1 04:31:05 INFO - mozsystemmonitor == 0.3 04:31:05 INFO - moztest == 0.8 04:31:05 INFO - mozversion == 1.4 04:31:05 INFO - psutil == 3.1.1 04:31:05 INFO - requests == 1.2.3 04:31:05 INFO - wsgiref == 0.1.2 04:31:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:31:05 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:05 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 04:31:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/talos 04:31:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/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 04:31:05 INFO - Using env: {'DISPLAY': ':0', 04:31:05 INFO - 'HOME': '/home/cltbld', 04:31:05 INFO - 'LANG': 'en_US.UTF-8', 04:31:05 INFO - 'LANGUAGE': 'en_US:en', 04:31:05 INFO - 'LOGNAME': 'cltbld', 04:31:05 INFO - 'MAIL': '/var/mail/cltbld', 04:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:05 INFO - 'MOZ_NO_REMOTE': '1', 04:31:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:31:05 INFO - 'NO_EM_RESTART': '1', 04:31:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:05 INFO - 'PWD': '/builds/slave/test', 04:31:05 INFO - 'SHELL': '/bin/bash', 04:31:05 INFO - 'SHLVL': '1', 04:31:05 INFO - 'TERM': 'linux', 04:31:05 INFO - 'TMOUT': '86400', 04:31:05 INFO - 'USER': 'cltbld', 04:31:05 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', 04:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:05 INFO - '_': '/tools/buildbot/bin/python'} 04:31:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:31:05 INFO - Cleaning up... 04:31:05 INFO - Return code: 0 04:31:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:31:05 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:05 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/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 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 04:31:05 INFO - Running command: ['/builds/slave/test/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/build 04:31:05 INFO - Copy/paste: /builds/slave/test/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 04:31:05 INFO - Using env: {'DISPLAY': ':0', 04:31:05 INFO - 'HOME': '/home/cltbld', 04:31:05 INFO - 'LANG': 'en_US.UTF-8', 04:31:05 INFO - 'LANGUAGE': 'en_US:en', 04:31:05 INFO - 'LOGNAME': 'cltbld', 04:31:05 INFO - 'MAIL': '/var/mail/cltbld', 04:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:05 INFO - 'MOZ_NO_REMOTE': '1', 04:31:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:31:05 INFO - 'NO_EM_RESTART': '1', 04:31:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:05 INFO - 'PWD': '/builds/slave/test', 04:31:05 INFO - 'SHELL': '/bin/bash', 04:31:05 INFO - 'SHLVL': '1', 04:31:05 INFO - 'TERM': 'linux', 04:31:05 INFO - 'TMOUT': '86400', 04:31:05 INFO - 'USER': 'cltbld', 04:31:05 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', 04:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:05 INFO - '_': '/tools/buildbot/bin/python'} 04:31:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:31:05 INFO - Cleaning up... 04:31:05 INFO - Return code: 0 04:31:05 INFO - Running post-action listener: _resource_record_post_action 04:31:05 INFO - Running post-action listener: _start_resource_monitoring 04:31:05 INFO - Starting resource monitoring. 04:31:05 INFO - [mozharness: 2017-03-31 11:31:05.976648Z] Finished create-virtualenv step (success) 04:31:05 INFO - [mozharness: 2017-03-31 11:31:05.977114Z] Running install step. 04:31:05 INFO - Running pre-action listener: _resource_record_pre_action 04:31:05 INFO - Running main action method: install 04:31:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:31:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:31:06 INFO - Reading from file tmpfile_stdout 04:31:06 INFO - Detecting whether we're running mozinstall >=1.0... 04:31:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:31:06 INFO - Reading from file tmpfile_stdout 04:31:06 INFO - Output received: 04:31:06 INFO - Usage: mozinstall [options] installer 04:31:06 INFO - Options: 04:31:06 INFO - -h, --help show this help message and exit 04:31:06 INFO - -d DEST, --destination=DEST 04:31:06 INFO - Directory to install application into. [default: 04:31:06 INFO - "/builds/slave/test"] 04:31:06 INFO - --app=APP Application being installed. [default: firefox] 04:31:06 INFO - mkdir: /builds/slave/test/build/application 04:31:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:31:21 INFO - Reading from file tmpfile_stdout 04:31:21 INFO - Output received: 04:31:21 INFO - /builds/slave/test/build/application/firefox/firefox 04:31:21 INFO - Running post-action listener: _resource_record_post_action 04:31:21 INFO - [mozharness: 2017-03-31 11:31:21.928183Z] Finished install step (success) 04:31:21 INFO - [mozharness: 2017-03-31 11:31:21.928456Z] Running run-tests step. 04:31:21 INFO - Running pre-action listener: _resource_record_pre_action 04:31:21 INFO - Running main action method: run_tests 04:31:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:31:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:31:21 INFO - Python 2.7.3 04:31:21 INFO - Return code: 0 04:31:21 INFO - grabbing minidump binary from tooltool 04:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:21 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:31:21 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:31:21 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:31:21 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:31:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:31:22 INFO - Return code: 0 04:31:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:31:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:31:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:31:22 INFO - ENV: RUST_BACKTRACE is now 1 04:31:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:31:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:31:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:31:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-044 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:31:22 INFO - Using env: {'DISPLAY': ':0', 04:31:22 INFO - 'HOME': '/home/cltbld', 04:31:22 INFO - 'LANG': 'en_US.UTF-8', 04:31:22 INFO - 'LANGUAGE': 'en_US:en', 04:31:22 INFO - 'LOGNAME': 'cltbld', 04:31:22 INFO - 'MAIL': '/var/mail/cltbld', 04:31:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:31:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:31:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:22 INFO - 'MOZ_NO_REMOTE': '1', 04:31:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:31:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:31:22 INFO - 'NO_EM_RESTART': '1', 04:31:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:31:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:22 INFO - 'PWD': '/builds/slave/test', 04:31:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:31:22 INFO - 'RUST_BACKTRACE': '1', 04:31:22 INFO - 'SHELL': '/bin/bash', 04:31:22 INFO - 'SHLVL': '1', 04:31:22 INFO - 'TERM': 'linux', 04:31:22 INFO - 'TMOUT': '86400', 04:31:22 INFO - 'USER': 'cltbld', 04:31:22 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474', 04:31:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:22 INFO - '_': '/tools/buildbot/bin/python'} 04:31:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:31:22 INFO - mozversion application_buildid: 20170330114804 04:31:22 INFO - mozversion application_changeset: 03d602fd723ad6ff4588c04855884ffa1dee9410 04:31:22 INFO - mozversion application_display_name: Nightly 04:31:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:31:22 INFO - mozversion application_name: Firefox 04:31:22 INFO - mozversion application_remotingname: firefox 04:31:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:31:22 INFO - mozversion application_vendor: Mozilla 04:31:22 INFO - mozversion application_version: 55.0a1 04:31:22 INFO - mozversion platform_buildid: 20170330114804 04:31:22 INFO - mozversion platform_changeset: 03d602fd723ad6ff4588c04855884ffa1dee9410 04:31:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:31:22 INFO - mozversion platform_version: 55.0a1 04:31:22 INFO - using testdate: 1490959882 04:31:22 INFO - actual date: 1490959882 04:31:22 INFO - starting webserver on 'localhost:35265' 04:31:22 INFO - SUITE-START | Running 2 tests 04:31:22 INFO - TEST-START | dromaeo_css 04:31:22 INFO - operating with platform_type : linux_ 04:31:22 INFO - Initialising browser for dromaeo_css test... 04:31:22 INFO - TEST-INFO | started process 15890 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQwI1Mx/profile http://localhost:35265/getInfo.html) 04:31:26 INFO - PID 15890 | __metrics Screen width/height:1600/1200 04:31:26 INFO - PID 15890 | colorDepth:24 04:31:26 INFO - PID 15890 | Browser inner width/height: 1024/697 04:31:26 INFO - PID 15890 | __metrics 04:31:27 ERROR - PID 15890 | 1490959887284 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < permitUnload@remote-browser.xml:357:13 < CanCloseWindow@browser.js:6382:36 < canClose@browser.js:5233:12 < forceQuit@jar:file:///tmp/tmpQwI1Mx/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///tmp/tmpQwI1Mx/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///tmp/tmpQwI1Mx/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///tmp/tmpQwI1Mx/profile/extensions/talos-powers@TalosPowersService.js:55:9 04:31:27 ERROR - PID 15890 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 04:31:28 INFO - TEST-INFO | 15890: exit 0 04:31:28 INFO - Browser initialized. 04:31:28 INFO - Running cycle 1/1 for dromaeo_css test... 04:31:28 INFO - TEST-INFO | started process 16020 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQwI1Mx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:31:40 INFO - PID 16020 | RSS: Main: 156200960 04:31:40 INFO - PID 16020 | 04:34:45 INFO - PID 16020 | Cycle 1(1): loaded http://localhost:35265/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35265/tests/dromaeo/cssquery-ext.html) 04:34:46 INFO - PID 16020 | RSS: Main: 165609472 04:34:46 INFO - PID 16020 | 04:36:28 INFO - PID 16020 | ************************* 04:36:28 INFO - PID 16020 | A coding exception was thrown and uncaught in a Task. 04:36:28 INFO - PID 16020 | 04:36:28 ERROR - PID 16020 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:36:28 INFO - PID 16020 | Full stack: 04:36:28 INFO - PID 16020 | ************************* 04:36:28 INFO - PID 16020 | ************************* 04:36:28 INFO - PID 16020 | A coding exception was thrown and uncaught in a Task. 04:36:28 INFO - PID 16020 | 04:36:28 ERROR - PID 16020 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:36:28 INFO - PID 16020 | Full stack: 04:36:28 INFO - PID 16020 | ************************* 04:36:28 INFO - PID 16020 | ************************* 04:36:28 INFO - PID 16020 | A coding exception was thrown and uncaught in a Task. 04:36:28 INFO - PID 16020 | 04:36:28 ERROR - PID 16020 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:36:28 INFO - PID 16020 | Full stack: 04:36:28 INFO - PID 16020 | ************************* 04:36:28 INFO - PID 16020 | ************************* 04:36:28 INFO - PID 16020 | A coding exception was thrown and uncaught in a Task. 04:36:28 INFO - PID 16020 | 04:36:28 ERROR - PID 16020 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:36:28 INFO - PID 16020 | Full stack: 04:36:28 INFO - PID 16020 | ************************* 04:37:49 INFO - PID 16020 | Cycle 1(1): loaded http://localhost:35265/tests/dromaeo/cssquery-ext.html (next: http://localhost:35265/tests/dromaeo/cssquery-jquery.html) 04:37:49 INFO - PID 16020 | RSS: Main: 162099200 04:37:49 INFO - PID 16020 | 04:40:51 INFO - PID 16020 | Cycle 1(1): loaded http://localhost:35265/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35265/tests/dromaeo/cssquery-mootools.html) 04:40:52 INFO - PID 16020 | RSS: Main: 169230336 04:40:52 INFO - PID 16020 | 04:43:54 INFO - PID 16020 | Cycle 1(1): loaded http://localhost:35265/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35265/tests/dromaeo/cssquery-prototype.html) 04:43:54 INFO - PID 16020 | RSS: Main: 161808384 04:43:54 INFO - PID 16020 | 04:46:57 INFO - PID 16020 | Cycle 1(1): loaded http://localhost:35265/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35265/tests/dromaeo/cssquery-yui.html) 04:46:58 INFO - PID 16020 | RSS: Main: 168853504 04:46:58 INFO - PID 16020 | 04:50:01 INFO - PID 16020 | Cycle 1(1): loaded http://localhost:35265/tests/dromaeo/cssquery-yui.html (next: http://localhost:35265/tests/dromaeo/cssquery-dojo.html) 04:50:01 INFO - PID 16020 | RSS: Main: 162467840 04:50:01 INFO - PID 16020 | 04:50:01 INFO - PID 16020 | __start_tp_report 04:50:01 INFO - PID 16020 | _x_x_mozilla_page_load 04:50:01 INFO - PID 16020 | _x_x_mozilla_page_load_details 04:50:01 INFO - PID 16020 | |i|pagename|runs| 04:50:01 INFO - PID 16020 | |0;dojo.html;2709.4174752428225;2713.3759235375874;2733.876975536101;2750.2161883863105;2757.641506604142;5164.225366195073;5251.816186433471;5268.578513718903;5320.255164276995;5334.599905007125;5232.398274198468;5257;5286.841394758154;5330.600204984622;5372.301600791891;4768.570828625423;4812.542808433193;4815.66290359675;4818.927716084261;4858.052679727454;4858.344123543329;4879.8780030499165;4900.191468407708;4908.8281910133155;4962.255661650764;4767.570918617306;4808.903821923577;4817.156997525419;4838.201696720037;4850.393700787402;4761.35721677576;4761.857154282981;4797.160496913061;4817.975910120462;4834.129856625806;4657.976710116428;4758.547937945889;4775.379200703886;4793.880152996203;4800.951990480085;5047.772850221749;5167.870803229912;5204.063268611649;5227.947720522784;5288.365396152448;5665.8583535411535;5689.6017278790505;5730.971345143289;5738.254026976508;5740.741666625012;5662.207290979266;5673.063944449161;5689.260396148516;5697.658140511582;5699.658020518741;5103.336566246438;5278.8944221116135;5289.2859463972645;5293.391260005072;5346.251524786533;5697.572682048863;5703.315602127771;5728.484436400744;5746.741396637161;5781.971090144522;5511.090670039479;5516.503514683617;5535.474129957647;5567.331920169606;5602.355729091125;5659.462351076641;5710.71446427677;5712.771489140471;5713;5720.856978575569;5626.43735626434;5635;5647.576431767634;5659.547236221092;5666.546676265889;5500.3949565547755;5600.243967064475;5601.383847776741;5648.068068768607;5690.487856092972;4785.760711964457;4786.042791441726;4789.616830653539;4810.278458231223;4829.613630909519;4068.5524592294823;4089.407035979766;4093.488313960755;4108.6646839776595;4136.73111247768;4739.644526660445;4806.255030470283;4809.759512024385;4859.343988561499;4860.8784780380065;5672.8865422691315;5675.5459563234845;5684.403137670568;5699.458551437607;5717.942820571741;4820.566149046533;4820.6143508519235;4828.46886842447;4831.855044348676;4876.609871210295;4948.134076536592;5000.324956130876;5019.196928491424;5025.874353141201;5051.747412629427;5259.342582177228;5261.500157485033;5268.947310526845;5278.683279003272;5302.125149350314;5561.721913904369;5640.774369025193;5644.576656750843;5654.547636189012;5660.867826434647;5187.351581052369;5290.947090529045;5335.11970524859;5377.569794416359;5379.488948549882;5554.055810512142;5607.243022192115;5607.383187849415;5614.747336369792;5634.661920284796;5663.122216056518;5686.744096515584;5691.203231547669;5695.401982791747;5725.971370143289;5384.19237114436;5457.69982650958;5494.725263736717;5551.611387202857;5555.8888822224135;5494.36814766307;5499.972500137474;5520.944790552204;5522.116461366323;5565.137403702271;5370.704611246419;5437.8368648940595;5463.235147079492;5469.535089517435;5516.448355164452;5357.437469065692;5433.728313584384;5563.193336966036;5564.749586268708;5580;5360.195970604441;5389.784408623611;5407.432219616883;5419.593530485308;5452.427495113115 04:50:01 INFO - PID 16020 | |1;ext.html;620.8354785981713;693.1474286627448;709.8970649255858;1144.1933436926963;1155.122107198529;35416.46875296869;36840;36858.631413685885;36928.81535592325;36947.81526092369;33849.323013539775;34171.829140854265;34289;34303.82848085756;34314.65685343146;6887.207971083327;7061.717531298742;7320.450966177558;7537.547747135162;7547.735829245978;7600.885986710204;7666.65500052499;7715.652795624211;7731.652075656581;7752.186020467854;7681.270279323483;7741.380689544823;7746.612669366509;7752.806179845521;7757.379409647243;7715.382769378436;7719.536827790351;7737.148913619498;7759.7672069838;7760.961195194044;7324.450666200003;7353.1911489736085;7456.813579660497;7465.8506829863645;7469.365103966169;34609.82695086534;34688;34773.30453390943;34854.302913941836;34974;2682.383051898055;2727.721858204381;2737.890484380622;2742.6708794944734;2790.14900455361;24122;24218.273451796475;24295.514089718108;24320.148794792098;24980.37784521234;2580.2259322203263;2606.7002294736158;2623.580227163645;2630.1583493282155;2653.2703506535663;148762;150864;151042;151196;152126.23936880246;42784;42946.78526607347;43041;43042.78478607587;43116.35325470121;44440;44508.3323750144;44545.33182002272;44573;44632.55367446349;12892.80660790089;13180.670483237996;13243.139195952232;13290.069695121249;13320.267385293902;10462.68611941643;10539.209559283085;10562.630307939186;10567.471626418648;10581.36511809294;13078.738425231442;13176.538821141214;13180.868191317964;13199.93400033013;13392.330383480787;5526.889462210734;5544.805931792368;5563.276774019351;5616.354119276336;5619.269494965628;827.9503229806231;831.6548632317521;852.8507511185518;864.5158711121792;881.1408876345512;20515.897420512803;20523.281685140948;21275.574488510454;21302.2544210955;21664;935.420039575454;938.1697197979701;938.5072836760755;963.3978763272954;968.041638777605;10609.416482093558;11130.777384452265;11150.386728729836;11152.721181970515;11302.208845380745;224912;226878;228066;228600;229504;42486;42675.57324426778;42722.57277427248;43183.56816431858;43708.12583748307;1248.213625415982;1261.8675039120944;1269.7016201192712;1269.911106222574;1274.6685861675846;22830.88584557133;23047.769522305232;23791;24094.759052409954;24124;28827.8558607214;28847.836412268745;28851.422971540873;28867.711322887342;28926.276843079093;182246;182357;182981;183038;183308;26154.346141346618;26243.343916401478;26300.736992629827;26362.340941476617;29378.85310573519;36032.81983590065;36581.08547286339;36975.81512092422;37066.62933370632;37120;26230.737692622828;27735.30661733473;27793.44413111686;27860;28457;27633.723662763114;27943;28238.858805706663;28429.289267768472;28521.714782851905;30267.394652107276;30418.54372184463;30451.695483044885;31418.685813141576;31913.36173276568;27013.1896043119;27267.454650907268;27390.315242119108;27599.724002759714;27759.30601734892;20703;22243.666345004513;22658.093676253426;22712.65931011003;22728.772712272665 04:50:01 INFO - PID 16020 | |2;jquery.html;412.8101073506187;427.91035474357267;431.5598089948252;441.32697636104945;443.0518690003393;17291.65416691667;18164.182611782468;19103.23587056518;19301.0349482526;19381.32165374212;16554.83445165548;16830.07434591097;16936.83063169371;17113.31546738129;17168.82831171688;5591.888162236753;5644.971775141119;5681.318241810988;5704.800331988381;5710.685912274823;5696.287964004499;5703.344115426717;5788.855278618026;5916.26046744157;5937.85155371117;5900.026495628238;5900.763969441217;5905.350411454737;5908.172855800191;5933.88132237355;5561.972190139044;5761.769529218847;5784.421557844224;5808.912866307009;5863.003289440784;5157.638965272433;5200.037992971274;5201.349831271091;5202.635815492917;5205.947940520584;22369;22611.886940565193;23015.53968920629;23142.88428557863;23302.88348558263;1492.544773843977;1527.6639139389317;1553.487349174781;1566.0525382143867;1571.1123215383273;13884.861151388457;14000.22998735069;14008.22954737489;14524.854751452456;14622.926885365612;1745.3716662001668;1754.3070487157502;1775.7869055713268;1795.4613615915173;1816.2734906037558;85598.57200714019;87757.56121219417;89500.55249723709;90647;90744;10511.57953681844;10616.946915265375;10795.892041079645;10871.67384978452;10912.727181820517;14660.120392776467;14727;14800.777988330183;14816.925915370353;14847.925760371345;7203.459740519482;7440.962795185989;7446.367058800062;7619.390448764086;7619.504732192389;6782.8643427132165;6834.2482326944;7003.964980175067;7033.507654464139;7045.0489183960535;6258.718357673915;6582.24304205013;6608.2730899601;6620.768273110514;6652.70062847173;2119.9364019079453;2130.9254176103764;2131.8614290071405;2152.655575107975;2276.3967548599635;797.828466879612;820.1839170025862;825.4799476329873;829.1915382502012;851.2253848997507;16412.261448234858;16534.421295254608;17064.317427303016;17293.481195564153;17457.912710436365;826.7437094500724;834.3617132893341;839.3033781960958;847.9321654267644;863.3352318714681;8515.105913879077;8539.06070332263;8628.697995570124;8833.33749968755;8963.50700711454;441194;469052;469266;471068;475280;85397.57301213578;90033;92833.53583232041;94229;94739.52630236805;1058.5977328615077;1108.6009036746764;1127.5433449452985;1133.3086817041599;1134.6482590397004;17079.316827327264;17152.48542543726;17660.116994149983;18460.169292382143;18713.625727485647;44485.33272000987;44929.77535112303;45243;45902.770486147354;46105;49609.50390496193;51378.48621513737;51671.48328516817;51716;51918.48081519137;8087.433879628369;8210.671573137246;8351.373646976379;8370.623321950648;8692.217700406994;12170.322529043597;13003.414846331749;13056.34718264064;13328.133671311334;13594.524191653245;11332.716682083013;11465.350096943586;11549.538018479167;11711.414429278673;11913.106517011085;8995.55022248898;10275.332103413255;10422.687319380431;10450;10649;10283.485825708834;10382.221333400177;10397.636082737068;10441.164706823283;10732.302400343953;8378.664853405795;8453.731940208772;8481;8619.396642235235;8635.913640863762;10663.8933610666;10865.022148006718;10875.564977400816;11315.151363647594;11789.528418863149 04:50:01 INFO - PID 16020 | |3;mootools.html;1241.2056283978254;1329.5479536957434;1334.4061892457853;1340.6715354738092;1346.5623672306504;9287.860682089775;9787.412755234673;9788;9940.453275069869;10196.133328667072;9947.651832185875;10044.44755538447;10099.545520451578;10113.69658910234;10131.544080516376;2177.401214665964;2190.463336482556;2213.2032468311395;2218.622834118196;2223.110755697726;2139.5929579223375;2188.8139508141826;2193.8793366364844;2201.7357917049976;2204.40481070111;2110.472381904524;2203.2398822406244;2225.810806081485;2236.888155592222;2240.7759224077545;2179.40066481718;2189;2190.6713992901114;2192.013593882751;2199.736031676209;2028.5638587703568;2119.088791819517;2122.8301735861096;2156.1590979518;2160.2763074370105;9785.315027948047;9853.802923941481;9860.359076660066;9871.40771553702;9874.210063194927;5359.222912677679;5570.554355651579;5598.496135347838;5598.916016259759;5605.654642885678;3351.8324083795715;3376.088456116859;3399.133221028622;3452.3095380923664;3475.548178736748;999.4902599674218;1002.6791426743445;1018.4347687033693;1033.8966103389675;1038.4288641247283;14144.71710565783;14168.574942751733;14216.857831421761;14249.64375890611;14256.85743142576;4716.915109524829;4852.223644216909;4914.483979182206;4927.989762098761;4972.154733695281;10587.417692027013;10602.151827853908;10621.468926553642;10653;10694.037536621741;5417.241586177938;5423.538999185034;5427.9185812212845;5438.238646589481;5448.42791506894;4790.7365094920115;4965.428975667773;4996.600271978233;5012.248162775612;5049.520295571915;3644.653757892996;3778.981105094457;3842.442845787401;3849.3263678856088;3881.7088718346236;1143.982840257397;1151.0618845640777;1169.473736818418;1172.4255114993591;1175.1245322234818;647.8412788866758;672.838518755505;674.3795707948699;675.7128220506265;681.931806819324;5131.820386286462;5178.482151784867;5813.360530341659;6008.128821320884;6027.638341699424;813.4956327077132;817.1950628630794;828.7058094376409;849.3415609143342;853.5646820121658;3419.6409377015552;3512.631173726722;3568.839402226906;3616.4575313703153;3625.546806649169;11896.762064758655;12677.274386007053;12813.35933203336;12816.871831281755;12877.4205160768;12470.501179952882;12700.618981430573;12735;12760.744785104245;12786.552470663482;9243.491607961627;9262.90737092634;9358.57886395114;9454.527273636291;9525.476098814494;5656.208130861765;5804.73878675469;5826.650400975955;5829.941700583109;5907.734151963086;11291.604793832177;11541.36522491271;11618.302901825917;11772.646820595395;11832.46753896094;11340.149488788209;11658.18392712536;11671.416429178338;11826.822597661214;11851.940740296242;7379.741709040158;7585.051868516435;7616.428767842544;7619.885701714368;7620.618969051636;8925;8989.55052247398;9032.86450703227;9066.138716821892;9137.680181193626;7146.749863754743;7209.134903811576;7259.963700181465;7297.1973082961895;7316.451266155166;7125.144982602121;7293.4894557382595;7302.96348518254;7354.374878135419;7541.170471248268;6496.350364963655;6509.1863517060365;6615.4376877965915;6627;6627.37039981201;4520.3671485992645;4613.3771940787565;4620.237660785932;4681.133990211685;4752.976235118802;4997.950020499894;5028.245763135559;5074.086664400444;5164;5215.400228973716 04:50:01 INFO - PID 16020 | |4;prototype.html;367.1861006627261;398.7926278335266;398.81056498163366;404.87246517347035;407.0921844287237;5430.321209848769;5601.635893666914;5610.326760788711;5638.802641907534;5652.152177173426;5364.195370694398;5473.28847249857;5473.863153421166;5478.150886612581;5481.972590137044;4230.4500414946015;4232.851850185244;4235.555266696999;4239.279322515172;4247.299195632717;4101.876943691679;4244.363345498169;4253.702240843142;4254.765987870666;4268.935965960513;4208.494980602317;4221.746695198282;4226;4227.556106608808;4247.766372849492;4225.133847561243;4228.154369126171;4237;4239.3217085266515;4251.97874010631;3690.7231957603285;3722.236941427001;3738.476613274144;3744.475773391727;3749.4750734897134;5229.712365819878;5355.6251062425645;5357.330333708286;5379.488948549882;5405.594580406485;4899.8040078396825;4910;4911.705297682151;4918.360613120307;4921.114199444099;5489.780408783644;5804.187413762077;5808.215890854723;5821.708914554298;5844.503217226498;4024.1750441159493;4031.5565287818317;4058.1275025869286;4062.451569038172;4065.471488706479;5934.643921364732;6021.458068773832;6129.1419201311855;6152.5693201475915;6167.7532898684;4146.460960075171;4147;4177.973682763857;4178.937315940263;4186.769727665008;4220.936685949713;4232.576742325743;4232.809523571446;4247.277962746341;4247.957520424756;4765.571098601143;4849.80600775965;4866.829660961849;4881.4630390657;4884.023195360942;5771.422857714195;5797.971010144923;5802.187693722883;5804.564657650693;5806.216160818235;1445.6313640021726;1455.2650911289943;1457.6283047822737;1458.8030615866935;1460.1093333066822;932.0249137428872;984.9409035457895;985.4481490365418;993.6174572789383;999.2156157416393;5036.370453693288;5061.291419201315;5063.291139240509;5081.441041485434;5085.110105731556;6032.8793424132145;6036.245469316335;6073;6077.665728384893;6078.7264573093435;4823.131836269506;5012.949870501248;5039.974800125976;5049.343585333957;5049.84850454487;3169.952450713241;3204.182933352027;3206.3907857506997;3214.1161180675035;3223.3553289341758;5483.588730845203;5615.034737841805;5623.409541998113;5625.184348269478;5649.689267090267;6079.787207447718;6095.786647467317;6132.6933653317155;6135.233095863017;6154.107654390088;5555.611107222537;5582.860428489239;5612.410696876769;5620.97189514058;5621.241132447149;5778.94221057784;5947.524198064058;5963.194968679173;5995.760169593167;6054.152418661479;5851.063829787214;5896.7936122235515;5901.203337549376;5916.556258280732;5941.465268125717;5283.1546952487415;5374.220737993047;5407.78368865241;5496.917546236889;5563.885723425073;4113.691473139466;4121.216968775923;4131.35963925592;4132.648724858301;4150.651038412468;5748.971255143698;5986.2217911671205;5992.550558708027;6037.245344331958;6048.334683184934;5787.305523337226;5814.825555233349;5819.0689489681445;5831.79588714393;5863.17915491823;5777.988851950808;5801.448862358069;5820.621659592113;5825.300963884361;5840.240768700041;5593.664380137205;5696.914546281726;5700.5154561861045;5712.6001179917;5752.28096487939;5818.563607729353;5822.48615360001;5830.883382332245;5862;5863.325717542537;5532;5692.601517893708;5697.863301262027;5703.600747947769;5711.257536520392 04:50:01 INFO - PID 16020 | |5;yui.html;602.2892986276195;625.455853407535;629.5026928726306;633.4488994574717;656.363327572255;12750.979921606273;13025.674358141052;13253.403596838129;13253.602391928245;13286.601401957956;13923.512677056306;13937.233452160128;14041.368138433769;14064.22646754428;14090.225037622926;893.9329550283722;901.2654686430539;901.7114523352514;917.7797328641145;922.4419226368051;931.2177770672633;944.2540393089452;944.4097439100562;944.8204841080208;945.2438049560359;978.1441238915949;992.0278127435118;992.2657233647085;992.5583115513599;994;953.6662168241129;962.4465932089042;963.9614415423374;969.9127078562892;973.5375696544156;714.2857142857143;719.8848184290489;720.5424555407302;724.022569531134;726.3027493606145;13705.246211458365;13989.510367137102;14210.360533776005;14498.565043048726;14501.274936253145;13404.597862064056;13789.862101378958;13826.930865345608;13885;13979.021468497209;9151.633934642607;9416.152546270869;9508.286878484141;9672.322937394385;9906.504674766233;2432.671589335435;2436.658867758515;2475.9752402475924;2486.167134010109;2506.52376048552;57823.32650102476;58567.41432585705;59070;59587;60584.39415605832;9452.716418507456;9698.70903872885;10136.138428233535;10201.642942496977;10239.641612543524;19775.40673779789;20673.896630516752;20866.47833804167;20879.373618791462;21190.894045529672;6308.7476500940365;6410.8076757697345;6462.612243265419;6500.577462464925;6613.14029176204;3035.317053662908;3057.5260834570677;3075.4310452566347;3107.0678796361003;3204.6635103314284;3596.964030359663;3607.4769158471877;3617.565892092966;3657.2685462907525;3678.0988657779017;673.3502170405582;673.4242222899449;681.0873429604354;689.8137502874196;690.975815846443;436.5270741250842;450.9751963642031;461.3793652056352;477.68472807946586;477.9784909679073;7199;7539.849203015909;7694.4535772284335;7717.2282771722375;7721.343685786771;476.6806239819329;501.3557578511595;504.8056498248201;507.6624045010027;521.7313083761866;3288.983555082257;3324.235425852019;3385.4583266677705;3409.863605455754;3436.1581412553583;57880.71059644675;59868;60291.39708602858;62186.37813621896;62794;54059.59819761476;54249.1862622061;55388.723056385264;55597.72201139049;57042.7147864258;48141.03717925622;49063.26405103926;49340.25989610159;49723;50871.2369314468;6715.429188518835;6818.829529261808;6923.376896079377;6953.200381955938;7052.612106334201;24275.16711563362;24711.50576988486;25207.747922521274;25983.350416239744;26397.73602263953;42179.367309491;42215.57784422117;42256;42916.78541607272;43766.56233437625;10631.255812093079;10739.785204295715;10757.408342541235;10786.352818830896;11222.102231821598;11135.276207046516;11229.71925701864;12055.336956467478;12082.275063496218;12172.57395991136;10206.744831378977;10563.63027294041;10696.518656660313;10789;10956.616518421817;10254.641087562199;10477.842832357368;10603.151747860307;10798.56805727762;10977.725556861142;10549.841752373566;10811.025459021921;10838.620648277589;10871.728206794893;11228.719282018015;7411.370033547209;7532.8493430132185;7624.351930086005;7665.770026899202;7700.306972372513;8481;8653.783655408664;8692.304615630608;8737.737867863974;8757.649694012423 04:50:01 INFO - PID 16020 | __end_tp_report 04:50:01 INFO - PID 16020 | __start_cc_report 04:50:01 INFO - PID 16020 | _x_x_mozilla_cycle_collect,382 04:50:01 INFO - PID 16020 | __end_cc_report 04:50:01 INFO - PID 16020 | __startTimestamp1490961001637__endTimestamp 04:50:01 INFO - PID 16020 | 04:50:01 INFO - PID 16020 | ------- Summary: start ------- 04:50:01 INFO - PID 16020 | Number of tests: 6 04:50:01 INFO - PID 16020 | 04:50:01 INFO - PID 16020 | [#0] dojo.html Cycles:165 Average:5199.94 Median:5357.44 stddev:588.33 (11.0%) stddev-sans-first:556.75 04:50:01 INFO - PID 16020 | Values: 2709.4 2713.4 2733.9 2750.2 2757.6 5164.2 5251.8 5268.6 5320.3 5334.6 5232.4 5257.0 5286.8 5330.6 5372.3 4768.6 4812.5 4815.7 4818.9 4858.1 4858.3 4879.9 4900.2 4908.8 4962.3 4767.6 4808.9 4817.2 4838.2 4850.4 4761.4 4761.9 4797.2 4818.0 4834.1 4658.0 4758.5 4775.4 4793.9 4801.0 5047.8 5167.9 5204.1 5227.9 5288.4 5665.9 5689.6 5731.0 5738.3 5740.7 5662.2 5673.1 5689.3 5697.7 5699.7 5103.3 5278.9 5289.3 5293.4 5346.3 5697.6 5703.3 5728.5 5746.7 5782.0 5511.1 5516.5 5535.5 5567.3 5602.4 5659.5 5710.7 5712.8 5713.0 5720.9 5626.4 5635.0 5647.6 5659.5 5666.5 5500.4 5600.2 5601.4 5648.1 5690.5 4785.8 4786.0 4789.6 4810.3 4829.6 4068.6 4089.4 4093.5 4108.7 4136.7 4739.6 4806.3 4809.8 4859.3 4860.9 5672.9 5675.5 5684.4 5699.5 5717.9 4820.6 4820.6 4828.5 4831.9 4876.6 4948.1 5000.3 5019.2 5025.9 5051.7 5259.3 5261.5 5268.9 5278.7 5302.1 5561.7 5640.8 5644.6 5654.5 5660.9 5187.4 5290.9 5335.1 5377.6 5379.5 5554.1 5607.2 5607.4 5614.7 5634.7 5663.1 5686.7 5691.2 5695.4 5726.0 5384.2 5457.7 5494.7 5551.6 5555.9 5494.4 5500.0 5520.9 5522.1 5565.1 5370.7 5437.8 5463.2 5469.5 5516.4 5357.4 5433.7 5563.2 5564.7 5580.0 5360.2 5389.8 5407.4 5419.6 5452.4 04:50:01 INFO - PID 16020 | 04:50:01 INFO - PID 16020 | [#1] ext.html Cycles:180 Average:33215.06 Median:28674.79 stddev:49242.49 (171.7%) stddev-sans-first:49319.81 04:50:01 INFO - PID 16020 | Values: 620.8 693.1 709.9 1144.2 1155.1 35416.5 36840.0 36858.6 36928.8 36947.8 33849.3 34171.8 34289.0 34303.8 34314.7 6887.2 7061.7 7320.5 7537.5 7547.7 7600.9 7666.7 7715.7 7731.7 7752.2 7681.3 7741.4 7746.6 7752.8 7757.4 7715.4 7719.5 7737.1 7759.8 7761.0 7324.5 7353.2 7456.8 7465.9 7469.4 34609.8 34688.0 34773.3 34854.3 34974.0 2682.4 2727.7 2737.9 2742.7 2790.1 24122.0 24218.3 24295.5 24320.1 24980.4 2580.2 2606.7 2623.6 2630.2 2653.3 148762.0 150864.0 151042.0 151196.0 152126.2 42784.0 42946.8 43041.0 43042.8 43116.4 44440.0 44508.3 44545.3 44573.0 44632.6 12892.8 13180.7 13243.1 13290.1 13320.3 10462.7 10539.2 10562.6 10567.5 10581.4 13078.7 13176.5 13180.9 13199.9 13392.3 5526.9 5544.8 5563.3 5616.4 5619.3 828.0 831.7 852.9 864.5 881.1 20515.9 20523.3 21275.6 21302.3 21664.0 935.4 938.2 938.5 963.4 968.0 10609.4 11130.8 11150.4 11152.7 11302.2 224912.0 226878.0 228066.0 228600.0 229504.0 42486.0 42675.6 42722.6 43183.6 43708.1 1248.2 1261.9 1269.7 1269.9 1274.7 22830.9 23047.8 23791.0 24094.8 24124.0 28827.9 28847.8 28851.4 28867.7 28926.3 182246.0 182357.0 182981.0 183038.0 183308.0 26154.3 26243.3 26300.7 26362.3 29378.9 36032.8 36581.1 36975.8 37066.6 37120.0 26230.7 27735.3 27793.4 27860.0 28457.0 27633.7 27943.0 28238.9 28429.3 28521.7 30267.4 30418.5 30451.7 31418.7 31913.4 27013.2 27267.5 27390.3 27599.7 27759.3 20703.0 22243.7 22658.1 22712.7 22728.8 04:50:01 INFO - PID 16020 | 04:50:01 INFO - PID 16020 | [#2] jquery.html Cycles:180 Average:28190.73 Median:44707.55 stddev:77018.21 (172.3%) stddev-sans-first:77206.03 04:50:01 INFO - PID 16020 | Values: 412.8 427.9 431.6 441.3 443.1 17291.7 18164.2 19103.2 19301.0 19381.3 16554.8 16830.1 16936.8 17113.3 17168.8 5591.9 5645.0 5681.3 5704.8 5710.7 5696.3 5703.3 5788.9 5916.3 5937.9 5900.0 5900.8 5905.4 5908.2 5933.9 5562.0 5761.8 5784.4 5808.9 5863.0 5157.6 5200.0 5201.3 5202.6 5205.9 22369.0 22611.9 23015.5 23142.9 23302.9 1492.5 1527.7 1553.5 1566.1 1571.1 13884.9 14000.2 14008.2 14524.9 14622.9 1745.4 1754.3 1775.8 1795.5 1816.3 85598.6 87757.6 89500.6 90647.0 90744.0 10511.6 10616.9 10795.9 10871.7 10912.7 14660.1 14727.0 14800.8 14816.9 14847.9 7203.5 7441.0 7446.4 7619.4 7619.5 6782.9 6834.2 7004.0 7033.5 7045.0 6258.7 6582.2 6608.3 6620.8 6652.7 2119.9 2130.9 2131.9 2152.7 2276.4 797.8 820.2 825.5 829.2 851.2 16412.3 16534.4 17064.3 17293.5 17457.9 826.7 834.4 839.3 847.9 863.3 8515.1 8539.1 8628.7 8833.3 8963.5 441194.0 469052.0 469266.0 471068.0 475280.0 85397.6 90033.0 92833.5 94229.0 94739.5 1058.6 1108.6 1127.5 1133.3 1134.6 17079.3 17152.5 17660.1 18460.2 18713.6 44485.3 44929.8 45243.0 45902.8 46105.0 49609.5 51378.5 51671.5 51716.0 51918.5 8087.4 8210.7 8351.4 8370.6 8692.2 12170.3 13003.4 13056.3 13328.1 13594.5 11332.7 11465.4 11549.5 11711.4 11913.1 8995.6 10275.3 10422.7 10450.0 10649.0 10283.5 10382.2 10397.6 10441.2 10732.3 8378.7 8453.7 8481.0 8619.4 8635.9 10663.9 10865.0 10875.6 11315.2 11789.5 04:50:01 INFO - PID 16020 | 04:50:01 INFO - PID 16020 | [#3] mootools.html Cycles:180 Average:6080.61 Median:4616.81 stddev:3866.55 (83.7%) stddev-sans-first:3860.30 04:50:01 INFO - PID 16020 | Values: 1241.2 1329.5 1334.4 1340.7 1346.6 9287.9 9787.4 9788.0 9940.5 10196.1 9947.7 10044.4 10099.5 10113.7 10131.5 2177.4 2190.5 2213.2 2218.6 2223.1 2139.6 2188.8 2193.9 2201.7 2204.4 2110.5 2203.2 2225.8 2236.9 2240.8 2179.4 2189.0 2190.7 2192.0 2199.7 2028.6 2119.1 2122.8 2156.2 2160.3 9785.3 9853.8 9860.4 9871.4 9874.2 5359.2 5570.6 5598.5 5598.9 5605.7 3351.8 3376.1 3399.1 3452.3 3475.5 999.5 1002.7 1018.4 1033.9 1038.4 14144.7 14168.6 14216.9 14249.6 14256.9 4716.9 4852.2 4914.5 4928.0 4972.2 10587.4 10602.2 10621.5 10653.0 10694.0 5417.2 5423.5 5427.9 5438.2 5448.4 4790.7 4965.4 4996.6 5012.2 5049.5 3644.7 3779.0 3842.4 3849.3 3881.7 1144.0 1151.1 1169.5 1172.4 1175.1 647.8 672.8 674.4 675.7 681.9 5131.8 5178.5 5813.4 6008.1 6027.6 813.5 817.2 828.7 849.3 853.6 3419.6 3512.6 3568.8 3616.5 3625.5 11896.8 12677.3 12813.4 12816.9 12877.4 12470.5 12700.6 12735.0 12760.7 12786.6 9243.5 9262.9 9358.6 9454.5 9525.5 5656.2 5804.7 5826.7 5829.9 5907.7 11291.6 11541.4 11618.3 11772.6 11832.5 11340.1 11658.2 11671.4 11826.8 11851.9 7379.7 7585.1 7616.4 7619.9 7620.6 8925.0 8989.6 9032.9 9066.1 9137.7 7146.7 7209.1 7260.0 7297.2 7316.5 7125.1 7293.5 7303.0 7354.4 7541.2 6496.4 6509.2 6615.4 6627.0 6627.4 4520.4 4613.4 4620.2 4681.1 4753.0 4998.0 5028.2 5074.1 5164.0 5215.4 04:50:01 INFO - PID 16020 | 04:50:01 INFO - PID 16020 | [#4] prototype.html Cycles:180 Average:4793.28 Median:5473.58 stddev:1404.27 (25.7%) stddev-sans-first:1368.35 04:50:01 INFO - PID 16020 | Values: 367.2 398.8 398.8 404.9 407.1 5430.3 5601.6 5610.3 5638.8 5652.2 5364.2 5473.3 5473.9 5478.2 5482.0 4230.5 4232.9 4235.6 4239.3 4247.3 4101.9 4244.4 4253.7 4254.8 4268.9 4208.5 4221.7 4226.0 4227.6 4247.8 4225.1 4228.2 4237.0 4239.3 4252.0 3690.7 3722.2 3738.5 3744.5 3749.5 5229.7 5355.6 5357.3 5379.5 5405.6 4899.8 4910.0 4911.7 4918.4 4921.1 5489.8 5804.2 5808.2 5821.7 5844.5 4024.2 4031.6 4058.1 4062.5 4065.5 5934.6 6021.5 6129.1 6152.6 6167.8 4146.5 4147.0 4178.0 4178.9 4186.8 4220.9 4232.6 4232.8 4247.3 4248.0 4765.6 4849.8 4866.8 4881.5 4884.0 5771.4 5798.0 5802.2 5804.6 5806.2 1445.6 1455.3 1457.6 1458.8 1460.1 932.0 984.9 985.4 993.6 999.2 5036.4 5061.3 5063.3 5081.4 5085.1 6032.9 6036.2 6073.0 6077.7 6078.7 4823.1 5012.9 5040.0 5049.3 5049.8 3170.0 3204.2 3206.4 3214.1 3223.4 5483.6 5615.0 5623.4 5625.2 5649.7 6079.8 6095.8 6132.7 6135.2 6154.1 5555.6 5582.9 5612.4 5621.0 5621.2 5778.9 5947.5 5963.2 5995.8 6054.2 5851.1 5896.8 5901.2 5916.6 5941.5 5283.2 5374.2 5407.8 5496.9 5563.9 4113.7 4121.2 4131.4 4132.6 4150.7 5749.0 5986.2 5992.6 6037.2 6048.3 5787.3 5814.8 5819.1 5831.8 5863.2 5778.0 5801.4 5820.6 5825.3 5840.2 5593.7 5696.9 5700.5 5712.6 5752.3 5818.6 5822.5 5830.9 5862.0 5863.3 5532.0 5692.6 5697.9 5703.6 5711.3 04:50:01 INFO - PID 16020 | 04:50:01 INFO - PID 16020 | [#5] yui.html Cycles:180 Average:13857.60 Median:49201.76 stddev:17144.55 (34.8%) stddev-sans-first:17163.75 04:50:01 INFO - PID 16020 | Values: 602.3 625.5 629.5 633.4 656.4 12751.0 13025.7 13253.4 13253.6 13286.6 13923.5 13937.2 14041.4 14064.2 14090.2 893.9 901.3 901.7 917.8 922.4 931.2 944.3 944.4 944.8 945.2 978.1 992.0 992.3 992.6 994.0 953.7 962.4 964.0 969.9 973.5 714.3 719.9 720.5 724.0 726.3 13705.2 13989.5 14210.4 14498.6 14501.3 13404.6 13789.9 13826.9 13885.0 13979.0 9151.6 9416.2 9508.3 9672.3 9906.5 2432.7 2436.7 2476.0 2486.2 2506.5 57823.3 58567.4 59070.0 59587.0 60584.4 9452.7 9698.7 10136.1 10201.6 10239.6 19775.4 20673.9 20866.5 20879.4 21190.9 6308.7 6410.8 6462.6 6500.6 6613.1 3035.3 3057.5 3075.4 3107.1 3204.7 3597.0 3607.5 3617.6 3657.3 3678.1 673.4 673.4 681.1 689.8 691.0 436.5 451.0 461.4 477.7 478.0 7199.0 7539.8 7694.5 7717.2 7721.3 476.7 501.4 504.8 507.7 521.7 3289.0 3324.2 3385.5 3409.9 3436.2 57880.7 59868.0 60291.4 62186.4 62794.0 54059.6 54249.2 55388.7 55597.7 57042.7 48141.0 49063.3 49340.3 49723.0 50871.2 6715.4 6818.8 6923.4 6953.2 7052.6 24275.2 24711.5 25207.7 25983.4 26397.7 42179.4 42215.6 42256.0 42916.8 43766.6 10631.3 10739.8 10757.4 10786.4 11222.1 11135.3 11229.7 12055.3 12082.3 12172.6 10206.7 10563.6 10696.5 10789.0 10956.6 10254.6 10477.8 10603.2 10798.6 10977.7 10549.8 10811.0 10838.6 10871.7 11228.7 7411.4 7532.8 7624.4 7665.8 7700.3 8481.0 8653.8 8692.3 8737.7 8757.6 04:50:01 INFO - PID 16020 | -------- Summary: end -------- 04:50:01 INFO - PID 16020 | 04:50:02 INFO - TEST-INFO | 16020: exit 0 04:50:02 INFO - TEST-OK | dromaeo_css | took 1120311ms 04:50:02 INFO - TEST-START | kraken 04:50:02 INFO - operating with platform_type : linux_ 04:50:02 INFO - Initialising browser for kraken test... 04:50:02 INFO - TEST-INFO | started process 16398 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAfQe6m/profile http://localhost:35265/getInfo.html) 04:50:06 INFO - PID 16398 | __metrics Screen width/height:1600/1200 04:50:06 INFO - PID 16398 | colorDepth:24 04:50:06 INFO - PID 16398 | Browser inner width/height: 1024/697 04:50:06 INFO - PID 16398 | __metrics 04:50:07 ERROR - PID 16398 | 1490961007470 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < permitUnload@remote-browser.xml:357:13 < CanCloseWindow@browser.js:6382:36 < canClose@browser.js:5233:12 < forceQuit@jar:file:///tmp/tmpAfQe6m/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///tmp/tmpAfQe6m/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///tmp/tmpAfQe6m/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///tmp/tmpAfQe6m/profile/extensions/talos-powers@TalosPowersService.js:55:9 04:50:07 ERROR - PID 16398 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 04:50:08 INFO - TEST-INFO | 16398: exit 0 04:50:08 INFO - Browser initialized. 04:50:08 INFO - Running cycle 1/1 for kraken test... 04:50:08 INFO - TEST-INFO | started process 16528 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAfQe6m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:50:20 INFO - PID 16528 | RSS: Main: 155316224 04:50:20 INFO - PID 16528 | 04:50:23 INFO - PID 16528 | Cycle 1(1): loaded http://localhost:35265/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35265/tests/kraken/driver.html?testName=audio-beat-detection) 04:50:24 INFO - PID 16528 | RSS: Main: 161779712 04:50:24 INFO - PID 16528 | 04:50:27 INFO - PID 16528 | Cycle 1(1): loaded http://localhost:35265/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35265/tests/kraken/driver.html?testName=audio-dft) 04:50:27 INFO - PID 16528 | RSS: Main: 161132544 04:50:27 INFO - PID 16528 | 04:50:30 INFO - PID 16528 | Cycle 1(1): loaded http://localhost:35265/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35265/tests/kraken/driver.html?testName=audio-fft) 04:50:31 INFO - PID 16528 | RSS: Main: 157609984 04:50:31 INFO - PID 16528 | 04:50:33 INFO - PID 16528 | Cycle 1(1): loaded http://localhost:35265/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35265/tests/kraken/driver.html?testName=audio-oscillator) 04:50:34 INFO - PID 16528 | RSS: Main: 158904320 04:50:34 INFO - PID 16528 | 04:50:36 INFO - PID 16528 | Cycle 1(1): loaded http://localhost:35265/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35265/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:50:37 INFO - PID 16528 | RSS: Main: 158822400 04:50:37 INFO - PID 16528 | 04:50:40 INFO - PID 16528 | Cycle 1(1): loaded http://localhost:35265/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35265/tests/kraken/driver.html?testName=imaging-darkroom) 04:50:40 INFO - PID 16528 | RSS: Main: 163471360 04:50:40 INFO - PID 16528 | 04:50:44 INFO - PID 16528 | Cycle 1(1): loaded http://localhost:35265/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35265/tests/kraken/driver.html?testName=imaging-desaturate) 04:50:45 INFO - PID 16528 | RSS: Main: 161325056 04:50:45 INFO - PID 16528 | 04:50:48 INFO - PID 16528 | Cycle 1(1): loaded http://localhost:35265/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35265/tests/kraken/driver.html?testName=json-parse-financial) 04:50:48 INFO - PID 16528 | RSS: Main: 162222080 04:50:48 INFO - PID 16528 | 04:50:50 INFO - PID 16528 | Cycle 1(1): loaded http://localhost:35265/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35265/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:50:51 INFO - PID 16528 | RSS: Main: 162721792 04:50:51 INFO - PID 16528 | 04:50:53 INFO - PID 16528 | Cycle 1(1): loaded http://localhost:35265/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35265/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:50:53 INFO - PID 16528 | RSS: Main: 162824192 04:50:53 INFO - PID 16528 | 04:50:55 INFO - PID 16528 | Cycle 1(1): loaded http://localhost:35265/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35265/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:50:56 INFO - PID 16528 | RSS: Main: 163168256 04:50:56 INFO - PID 16528 | 04:50:59 INFO - PID 16528 | Cycle 1(1): loaded http://localhost:35265/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:51:00 INFO - PID 16528 | RSS: Main: 163663872 04:51:00 INFO - PID 16528 | 04:51:03 INFO - PID 16528 | Cycle 1(1): loaded http://localhost:35265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:51:03 INFO - PID 16528 | RSS: Main: 166100992 04:51:03 INFO - PID 16528 | 04:51:05 INFO - PID 16528 | Cycle 1(1): loaded http://localhost:35265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35265/tests/kraken/driver.html?testName=ai-astar) 04:51:06 INFO - PID 16528 | RSS: Main: 168005632 04:51:06 INFO - PID 16528 | 04:51:06 INFO - PID 16528 | __start_tp_report 04:51:06 INFO - PID 16528 | _x_x_mozilla_page_load 04:51:06 INFO - PID 16528 | _x_x_mozilla_page_load_details 04:51:06 INFO - PID 16528 | |i|pagename|runs| 04:51:06 INFO - PID 16528 | |0;ai-astar;108;121;113;113;111;113;112;112;113;111 04:51:06 INFO - PID 16528 | |1;audio-beat-detection;154;131;128;172;126;132;126;128;149;127 04:51:06 INFO - PID 16528 | |2;audio-dft;168;173;167;170;165;167;176;166;165;164 04:51:06 INFO - PID 16528 | |3;audio-fft;75;78;78;106;77;75;79;75;106;75 04:51:06 INFO - PID 16528 | |4;audio-oscillator;85;84;105;85;89;84;103;85;85;114 04:51:06 INFO - PID 16528 | |5;imaging-gaussian-blur;116;115;115;115;116;114;116;114;114;114 04:51:06 INFO - PID 16528 | |6;imaging-darkroom;153;152;152;151;155;151;152;153;151;151 04:51:06 INFO - PID 16528 | |7;imaging-desaturate;95;96;95;96;95;96;95;95;95;96 04:51:06 INFO - PID 16528 | |8;json-parse-financial;70;69;70;68;71;72;71;69;70;71 04:51:06 INFO - PID 16528 | |9;json-stringify-tinderbox;58;57;58;57;57;57;56;56;55;56 04:51:06 INFO - PID 16528 | |10;stanford-crypto-aes;92;84;83;84;84;82;85;85;84;83 04:51:06 INFO - PID 16528 | |11;stanford-crypto-ccm;147;143;140;136;140;151;134;144;192;135 04:51:06 INFO - PID 16528 | |12;stanford-crypto-pbkdf2;193;200;199;190;193;195;187;190;208;201 04:51:06 INFO - PID 16528 | |13;stanford-crypto-sha256-iterative;74;73;71;78;71;76;71;71;71;70 04:51:06 INFO - PID 16528 | __end_tp_report 04:51:06 INFO - PID 16528 | __start_cc_report 04:51:06 INFO - PID 16528 | _x_x_mozilla_cycle_collect,877 04:51:06 INFO - PID 16528 | __end_cc_report 04:51:06 INFO - PID 16528 | __startTimestamp1490961066042__endTimestamp 04:51:06 INFO - PID 16528 | 04:51:06 INFO - PID 16528 | ------- Summary: start ------- 04:51:06 INFO - PID 16528 | Number of tests: 14 04:51:06 INFO - PID 16528 | 04:51:06 INFO - PID 16528 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.00 stddev:3.30 (2.9%) stddev-sans-first:3.03 04:51:06 INFO - PID 16528 | Values: 108.0 121.0 113.0 113.0 111.0 113.0 112.0 112.0 113.0 111.0 04:51:06 INFO - PID 16528 | 04:51:06 INFO - PID 16528 | [#1] audio-beat-detection Cycles:10 Average:137.30 Median:131.50 stddev:15.71 (11.9%) stddev-sans-first:15.46 04:51:06 INFO - PID 16528 | Values: 154.0 131.0 128.0 172.0 126.0 132.0 126.0 128.0 149.0 127.0 04:51:06 INFO - PID 16528 | 04:51:06 INFO - PID 16528 | [#2] audio-dft Cycles:10 Average:168.10 Median:167.50 stddev:3.84 (2.3%) stddev-sans-first:4.08 04:51:06 INFO - PID 16528 | Values: 168.0 173.0 167.0 170.0 165.0 167.0 176.0 166.0 165.0 164.0 04:51:06 INFO - PID 16528 | 04:51:06 INFO - PID 16528 | [#3] audio-fft Cycles:10 Average:82.40 Median:76.00 stddev:12.53 (16.5%) stddev-sans-first:13.00 04:51:06 INFO - PID 16528 | Values: 75.0 78.0 78.0 106.0 77.0 75.0 79.0 75.0 106.0 75.0 04:51:06 INFO - PID 16528 | 04:51:06 INFO - PID 16528 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:85.00 stddev:11.09 (13.0%) stddev-sans-first:11.48 04:51:06 INFO - PID 16528 | Values: 85.0 84.0 105.0 85.0 89.0 84.0 103.0 85.0 85.0 114.0 04:51:06 INFO - PID 16528 | 04:51:06 INFO - PID 16528 | [#5] imaging-gaussian-blur Cycles:10 Average:114.90 Median:115.00 stddev:0.88 (0.8%) stddev-sans-first:0.83 04:51:06 INFO - PID 16528 | Values: 116.0 115.0 115.0 115.0 116.0 114.0 116.0 114.0 114.0 114.0 04:51:06 INFO - PID 16528 | 04:51:06 INFO - PID 16528 | [#6] imaging-darkroom Cycles:10 Average:152.10 Median:152.00 stddev:1.29 (0.8%) stddev-sans-first:1.32 04:51:06 INFO - PID 16528 | Values: 153.0 152.0 152.0 151.0 155.0 151.0 152.0 153.0 151.0 151.0 04:51:06 INFO - PID 16528 | 04:51:06 INFO - PID 16528 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 04:51:06 INFO - PID 16528 | Values: 95.0 96.0 95.0 96.0 95.0 96.0 95.0 95.0 95.0 96.0 04:51:06 INFO - PID 16528 | 04:51:06 INFO - PID 16528 | [#8] json-parse-financial Cycles:10 Average:70.10 Median:70.50 stddev:1.20 (1.7%) stddev-sans-first:1.27 04:51:06 INFO - PID 16528 | Values: 70.0 69.0 70.0 68.0 71.0 72.0 71.0 69.0 70.0 71.0 04:51:06 INFO - PID 16528 | 04:51:06 INFO - PID 16528 | [#9] json-stringify-tinderbox Cycles:10 Average:56.70 Median:57.00 stddev:0.95 (1.7%) stddev-sans-first:0.88 04:51:06 INFO - PID 16528 | Values: 58.0 57.0 58.0 57.0 57.0 57.0 56.0 56.0 55.0 56.0 04:51:06 INFO - PID 16528 | 04:51:06 INFO - PID 16528 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:84.00 stddev:2.76 (3.3%) stddev-sans-first:0.97 04:51:06 INFO - PID 16528 | Values: 92.0 84.0 83.0 84.0 84.0 82.0 85.0 85.0 84.0 83.0 04:51:06 INFO - PID 16528 | 04:51:06 INFO - PID 16528 | [#11] stanford-crypto-ccm Cycles:10 Average:146.20 Median:143.50 stddev:16.97 (11.8%) stddev-sans-first:18.00 04:51:06 INFO - PID 16528 | Values: 147.0 143.0 140.0 136.0 140.0 151.0 134.0 144.0 192.0 135.0 04:51:06 INFO - PID 16528 | 04:51:06 INFO - PID 16528 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.60 Median:197.00 stddev:6.36 (3.2%) stddev-sans-first:6.68 04:51:06 INFO - PID 16528 | Values: 193.0 200.0 199.0 190.0 193.0 195.0 187.0 190.0 208.0 201.0 04:51:06 INFO - PID 16528 | 04:51:06 INFO - PID 16528 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.60 Median:72.00 stddev:2.63 (3.7%) stddev-sans-first:2.74 04:51:06 INFO - PID 16528 | Values: 74.0 73.0 71.0 78.0 71.0 76.0 71.0 71.0 71.0 70.0 04:51:06 INFO - PID 16528 | -------- Summary: end -------- 04:51:06 INFO - PID 16528 | 04:51:07 INFO - TEST-INFO | 16528: exit 0 04:51:07 INFO - TEST-OK | kraken | took 64971ms 04:51:07 INFO - SUITE-END | took 1185s 04:51:07 INFO - Completed test suite (00:19:45) 04:51:07 INFO - javascript benchmark 04:51:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6620.194716209154, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2709.4174752428225, 2713.3759235375874, 2733.876975536101, 2750.2161883863105, 2757.641506604142, 5164.225366195073, 5251.816186433471, 5268.578513718903, 5320.255164276995, 5334.599905007125, 5232.398274198468, 5257.0, 5286.841394758154, 5330.600204984622, 5372.301600791891, 4768.570828625423, 4812.542808433193, 4815.66290359675, 4818.927716084261, 4858.052679727454, 4858.344123543329, 4879.8780030499165, 4900.191468407708, 4908.8281910133155, 4962.255661650764, 4767.570918617306, 4808.903821923577, 4817.156997525419, 4838.201696720037, 4850.393700787402, 4761.35721677576, 4761.857154282981, 4797.160496913061, 4817.975910120462, 4834.129856625806, 4657.976710116428, 4758.547937945889, 4775.379200703886, 4793.880152996203, 4800.951990480085, 5047.772850221749, 5167.870803229912, 5204.063268611649, 5227.947720522784, 5288.365396152448, 5665.8583535411535, 5689.6017278790505, 5730.971345143289, 5738.254026976508, 5740.741666625012, 5662.207290979266, 5673.063944449161, 5689.260396148516, 5697.658140511582, 5699.658020518741, 5103.336566246438, 5278.8944221116135, 5289.2859463972645, 5293.391260005072, 5346.251524786533, 5697.572682048863, 5703.315602127771, 5728.484436400744, 5746.741396637161, 5781.971090144522, 5511.090670039479, 5516.503514683617, 5535.474129957647, 5567.331920169606, 5602.355729091125, 5659.462351076641, 5710.71446427677, 5712.771489140471, 5713.0, 5720.856978575569, 5626.43735626434, 5635.0, 5647.576431767634, 5659.547236221092, 5666.546676265889, 5500.3949565547755, 5600.243967064475, 5601.383847776741, 5648.068068768607, 5690.487856092972, 4785.760711964457, 4786.042791441726, 4789.616830653539, 4810.278458231223, 4829.613630909519, 4068.5524592294823, 4089.407035979766, 4093.488313960755, 4108.6646839776595, 4136.73111247768, 4739.644526660445, 4806.255030470283, 4809.759512024385, 4859.343988561499, 4860.8784780380065, 5672.8865422691315, 5675.5459563234845, 5684.403137670568, 5699.458551437607, 5717.942820571741, 4820.566149046533, 4820.6143508519235, 4828.46886842447, 4831.855044348676, 4876.609871210295, 4948.134076536592, 5000.324956130876, 5019.196928491424, 5025.874353141201, 5051.747412629427, 5259.342582177228, 5261.500157485033, 5268.947310526845, 5278.683279003272, 5302.125149350314, 5561.721913904369, 5640.774369025193, 5644.576656750843, 5654.547636189012, 5660.867826434647, 5187.351581052369, 5290.947090529045, 5335.11970524859, 5377.569794416359, 5379.488948549882, 5554.055810512142, 5607.243022192115, 5607.383187849415, 5614.747336369792, 5634.661920284796, 5663.122216056518, 5686.744096515584, 5691.203231547669, 5695.401982791747, 5725.971370143289, 5384.19237114436, 5457.69982650958, 5494.725263736717, 5551.611387202857, 5555.8888822224135, 5494.36814766307, 5499.972500137474, 5520.944790552204, 5522.116461366323, 5565.137403702271, 5370.704611246419, 5437.8368648940595, 5463.235147079492, 5469.535089517435, 5516.448355164452, 5357.437469065692, 5433.728313584384, 5563.193336966036, 5564.749586268708, 5580.0, 5360.195970604441, 5389.784408623611, 5407.432219616883, 5419.593530485308, 5452.427495113115], "value": 5158.00053537365, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [620.8354785981713, 693.1474286627448, 709.8970649255858, 1144.1933436926963, 1155.122107198529, 35416.46875296869, 36840.0, 36858.631413685885, 36928.81535592325, 36947.81526092369, 33849.323013539775, 34171.829140854265, 34289.0, 34303.82848085756, 34314.65685343146, 6887.207971083327, 7061.717531298742, 7320.450966177558, 7537.547747135162, 7547.735829245978, 7600.885986710204, 7666.65500052499, 7715.652795624211, 7731.652075656581, 7752.186020467854, 7681.270279323483, 7741.380689544823, 7746.612669366509, 7752.806179845521, 7757.379409647243, 7715.382769378436, 7719.536827790351, 7737.148913619498, 7759.7672069838, 7760.961195194044, 7324.450666200003, 7353.1911489736085, 7456.813579660497, 7465.8506829863645, 7469.365103966169, 34609.82695086534, 34688.0, 34773.30453390943, 34854.302913941836, 34974.0, 2682.383051898055, 2727.721858204381, 2737.890484380622, 2742.6708794944734, 2790.14900455361, 24122.0, 24218.273451796475, 24295.514089718108, 24320.148794792098, 24980.37784521234, 2580.2259322203263, 2606.7002294736158, 2623.580227163645, 2630.1583493282155, 2653.2703506535663, 148762.0, 150864.0, 151042.0, 151196.0, 152126.23936880246, 42784.0, 42946.78526607347, 43041.0, 43042.78478607587, 43116.35325470121, 44440.0, 44508.3323750144, 44545.33182002272, 44573.0, 44632.55367446349, 12892.80660790089, 13180.670483237996, 13243.139195952232, 13290.069695121249, 13320.267385293902, 10462.68611941643, 10539.209559283085, 10562.630307939186, 10567.471626418648, 10581.36511809294, 13078.738425231442, 13176.538821141214, 13180.868191317964, 13199.93400033013, 13392.330383480787, 5526.889462210734, 5544.805931792368, 5563.276774019351, 5616.354119276336, 5619.269494965628, 827.9503229806231, 831.6548632317521, 852.8507511185518, 864.5158711121792, 881.1408876345512, 20515.897420512803, 20523.281685140948, 21275.574488510454, 21302.2544210955, 21664.0, 935.420039575454, 938.1697197979701, 938.5072836760755, 963.3978763272954, 968.041638777605, 10609.416482093558, 11130.777384452265, 11150.386728729836, 11152.721181970515, 11302.208845380745, 224912.0, 226878.0, 228066.0, 228600.0, 229504.0, 42486.0, 42675.57324426778, 42722.57277427248, 43183.56816431858, 43708.12583748307, 1248.213625415982, 1261.8675039120944, 1269.7016201192712, 1269.911106222574, 1274.6685861675846, 22830.88584557133, 23047.769522305232, 23791.0, 24094.759052409954, 24124.0, 28827.8558607214, 28847.836412268745, 28851.422971540873, 28867.711322887342, 28926.276843079093, 182246.0, 182357.0, 182981.0, 183038.0, 183308.0, 26154.346141346618, 26243.343916401478, 26300.736992629827, 26362.340941476617, 29378.85310573519, 36032.81983590065, 36581.08547286339, 36975.81512092422, 37066.62933370632, 37120.0, 26230.737692622828, 27735.30661733473, 27793.44413111686, 27860.0, 28457.0, 27633.723662763114, 27943.0, 28238.858805706663, 28429.289267768472, 28521.714782851905, 30267.394652107276, 30418.54372184463, 30451.695483044885, 31418.685813141576, 31913.36173276568, 27013.1896043119, 27267.454650907268, 27390.315242119108, 27599.724002759714, 27759.30601734892, 20703.0, 22243.666345004513, 22658.093676253426, 22712.65931011003, 22728.772712272665], "value": 14968.883209340964, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.8101073506187, 427.91035474357267, 431.5598089948252, 441.32697636104945, 443.0518690003393, 17291.65416691667, 18164.182611782468, 19103.23587056518, 19301.0349482526, 19381.32165374212, 16554.83445165548, 16830.07434591097, 16936.83063169371, 17113.31546738129, 17168.82831171688, 5591.888162236753, 5644.971775141119, 5681.318241810988, 5704.800331988381, 5710.685912274823, 5696.287964004499, 5703.344115426717, 5788.855278618026, 5916.26046744157, 5937.85155371117, 5900.026495628238, 5900.763969441217, 5905.350411454737, 5908.172855800191, 5933.88132237355, 5561.972190139044, 5761.769529218847, 5784.421557844224, 5808.912866307009, 5863.003289440784, 5157.638965272433, 5200.037992971274, 5201.349831271091, 5202.635815492917, 5205.947940520584, 22369.0, 22611.886940565193, 23015.53968920629, 23142.88428557863, 23302.88348558263, 1492.544773843977, 1527.6639139389317, 1553.487349174781, 1566.0525382143867, 1571.1123215383273, 13884.861151388457, 14000.22998735069, 14008.22954737489, 14524.854751452456, 14622.926885365612, 1745.3716662001668, 1754.3070487157502, 1775.7869055713268, 1795.4613615915173, 1816.2734906037558, 85598.57200714019, 87757.56121219417, 89500.55249723709, 90647.0, 90744.0, 10511.57953681844, 10616.946915265375, 10795.892041079645, 10871.67384978452, 10912.727181820517, 14660.120392776467, 14727.0, 14800.777988330183, 14816.925915370353, 14847.925760371345, 7203.459740519482, 7440.962795185989, 7446.367058800062, 7619.390448764086, 7619.504732192389, 6782.8643427132165, 6834.2482326944, 7003.964980175067, 7033.507654464139, 7045.0489183960535, 6258.718357673915, 6582.24304205013, 6608.2730899601, 6620.768273110514, 6652.70062847173, 2119.9364019079453, 2130.9254176103764, 2131.8614290071405, 2152.655575107975, 2276.3967548599635, 797.828466879612, 820.1839170025862, 825.4799476329873, 829.1915382502012, 851.2253848997507, 16412.261448234858, 16534.421295254608, 17064.317427303016, 17293.481195564153, 17457.912710436365, 826.7437094500724, 834.3617132893341, 839.3033781960958, 847.9321654267644, 863.3352318714681, 8515.105913879077, 8539.06070332263, 8628.697995570124, 8833.33749968755, 8963.50700711454, 441194.0, 469052.0, 469266.0, 471068.0, 475280.0, 85397.57301213578, 90033.0, 92833.53583232041, 94229.0, 94739.52630236805, 1058.5977328615077, 1108.6009036746764, 1127.5433449452985, 1133.3086817041599, 1134.6482590397004, 17079.316827327264, 17152.48542543726, 17660.116994149983, 18460.169292382143, 18713.625727485647, 44485.33272000987, 44929.77535112303, 45243.0, 45902.770486147354, 46105.0, 49609.50390496193, 51378.48621513737, 51671.48328516817, 51716.0, 51918.48081519137, 8087.433879628369, 8210.671573137246, 8351.373646976379, 8370.623321950648, 8692.217700406994, 12170.322529043597, 13003.414846331749, 13056.34718264064, 13328.133671311334, 13594.524191653245, 11332.716682083013, 11465.350096943586, 11549.538018479167, 11711.414429278673, 11913.106517011085, 8995.55022248898, 10275.332103413255, 10422.687319380431, 10450.0, 10649.0, 10283.485825708834, 10382.221333400177, 10397.636082737068, 10441.164706823283, 10732.302400343953, 8378.664853405795, 8453.731940208772, 8481.0, 8619.396642235235, 8635.913640863762, 10663.8933610666, 10865.022148006718, 10875.564977400816, 11315.151363647594, 11789.528418863149], "value": 8952.334438565103, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1241.2056283978254, 1329.5479536957434, 1334.4061892457853, 1340.6715354738092, 1346.5623672306504, 9287.860682089775, 9787.412755234673, 9788.0, 9940.453275069869, 10196.133328667072, 9947.651832185875, 10044.44755538447, 10099.545520451578, 10113.69658910234, 10131.544080516376, 2177.401214665964, 2190.463336482556, 2213.2032468311395, 2218.622834118196, 2223.110755697726, 2139.5929579223375, 2188.8139508141826, 2193.8793366364844, 2201.7357917049976, 2204.40481070111, 2110.472381904524, 2203.2398822406244, 2225.810806081485, 2236.888155592222, 2240.7759224077545, 2179.40066481718, 2189.0, 2190.6713992901114, 2192.013593882751, 2199.736031676209, 2028.5638587703568, 2119.088791819517, 2122.8301735861096, 2156.1590979518, 2160.2763074370105, 9785.315027948047, 9853.802923941481, 9860.359076660066, 9871.40771553702, 9874.210063194927, 5359.222912677679, 5570.554355651579, 5598.496135347838, 5598.916016259759, 5605.654642885678, 3351.8324083795715, 3376.088456116859, 3399.133221028622, 3452.3095380923664, 3475.548178736748, 999.4902599674218, 1002.6791426743445, 1018.4347687033693, 1033.8966103389675, 1038.4288641247283, 14144.71710565783, 14168.574942751733, 14216.857831421761, 14249.64375890611, 14256.85743142576, 4716.915109524829, 4852.223644216909, 4914.483979182206, 4927.989762098761, 4972.154733695281, 10587.417692027013, 10602.151827853908, 10621.468926553642, 10653.0, 10694.037536621741, 5417.241586177938, 5423.538999185034, 5427.9185812212845, 5438.238646589481, 5448.42791506894, 4790.7365094920115, 4965.428975667773, 4996.600271978233, 5012.248162775612, 5049.520295571915, 3644.653757892996, 3778.981105094457, 3842.442845787401, 3849.3263678856088, 3881.7088718346236, 1143.982840257397, 1151.0618845640777, 1169.473736818418, 1172.4255114993591, 1175.1245322234818, 647.8412788866758, 672.838518755505, 674.3795707948699, 675.7128220506265, 681.931806819324, 5131.820386286462, 5178.482151784867, 5813.360530341659, 6008.128821320884, 6027.638341699424, 813.4956327077132, 817.1950628630794, 828.7058094376409, 849.3415609143342, 853.5646820121658, 3419.6409377015552, 3512.631173726722, 3568.839402226906, 3616.4575313703153, 3625.546806649169, 11896.762064758655, 12677.274386007053, 12813.35933203336, 12816.871831281755, 12877.4205160768, 12470.501179952882, 12700.618981430573, 12735.0, 12760.744785104245, 12786.552470663482, 9243.491607961627, 9262.90737092634, 9358.57886395114, 9454.527273636291, 9525.476098814494, 5656.208130861765, 5804.73878675469, 5826.650400975955, 5829.941700583109, 5907.734151963086, 11291.604793832177, 11541.36522491271, 11618.302901825917, 11772.646820595395, 11832.46753896094, 11340.149488788209, 11658.18392712536, 11671.416429178338, 11826.822597661214, 11851.940740296242, 7379.741709040158, 7585.051868516435, 7616.428767842544, 7619.885701714368, 7620.618969051636, 8925.0, 8989.55052247398, 9032.86450703227, 9066.138716821892, 9137.680181193626, 7146.749863754743, 7209.134903811576, 7259.963700181465, 7297.1973082961895, 7316.451266155166, 7125.144982602121, 7293.4894557382595, 7302.96348518254, 7354.374878135419, 7541.170471248268, 6496.350364963655, 6509.1863517060365, 6615.4376877965915, 6627.0, 6627.37039981201, 4520.3671485992645, 4613.3771940787565, 4620.237660785932, 4681.133990211685, 4752.976235118802, 4997.950020499894, 5028.245763135559, 5074.086664400444, 5164.0, 5215.400228973716], "value": 4611.822129102013, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.1861006627261, 398.7926278335266, 398.81056498163366, 404.87246517347035, 407.0921844287237, 5430.321209848769, 5601.635893666914, 5610.326760788711, 5638.802641907534, 5652.152177173426, 5364.195370694398, 5473.28847249857, 5473.863153421166, 5478.150886612581, 5481.972590137044, 4230.4500414946015, 4232.851850185244, 4235.555266696999, 4239.279322515172, 4247.299195632717, 4101.876943691679, 4244.363345498169, 4253.702240843142, 4254.765987870666, 4268.935965960513, 4208.494980602317, 4221.746695198282, 4226.0, 4227.556106608808, 4247.766372849492, 4225.133847561243, 4228.154369126171, 4237.0, 4239.3217085266515, 4251.97874010631, 3690.7231957603285, 3722.236941427001, 3738.476613274144, 3744.475773391727, 3749.4750734897134, 5229.712365819878, 5355.6251062425645, 5357.330333708286, 5379.488948549882, 5405.594580406485, 4899.8040078396825, 4910.0, 4911.705297682151, 4918.360613120307, 4921.114199444099, 5489.780408783644, 5804.187413762077, 5808.215890854723, 5821.708914554298, 5844.503217226498, 4024.1750441159493, 4031.5565287818317, 4058.1275025869286, 4062.451569038172, 4065.471488706479, 5934.643921364732, 6021.458068773832, 6129.1419201311855, 6152.5693201475915, 6167.7532898684, 4146.460960075171, 4147.0, 4177.973682763857, 4178.937315940263, 4186.769727665008, 4220.936685949713, 4232.576742325743, 4232.809523571446, 4247.277962746341, 4247.957520424756, 4765.571098601143, 4849.80600775965, 4866.829660961849, 4881.4630390657, 4884.023195360942, 5771.422857714195, 5797.971010144923, 5802.187693722883, 5804.564657650693, 5806.216160818235, 1445.6313640021726, 1455.2650911289943, 1457.6283047822737, 1458.8030615866935, 1460.1093333066822, 932.0249137428872, 984.9409035457895, 985.4481490365418, 993.6174572789383, 999.2156157416393, 5036.370453693288, 5061.291419201315, 5063.291139240509, 5081.441041485434, 5085.110105731556, 6032.8793424132145, 6036.245469316335, 6073.0, 6077.665728384893, 6078.7264573093435, 4823.131836269506, 5012.949870501248, 5039.974800125976, 5049.343585333957, 5049.84850454487, 3169.952450713241, 3204.182933352027, 3206.3907857506997, 3214.1161180675035, 3223.3553289341758, 5483.588730845203, 5615.034737841805, 5623.409541998113, 5625.184348269478, 5649.689267090267, 6079.787207447718, 6095.786647467317, 6132.6933653317155, 6135.233095863017, 6154.107654390088, 5555.611107222537, 5582.860428489239, 5612.410696876769, 5620.97189514058, 5621.241132447149, 5778.94221057784, 5947.524198064058, 5963.194968679173, 5995.760169593167, 6054.152418661479, 5851.063829787214, 5896.7936122235515, 5901.203337549376, 5916.556258280732, 5941.465268125717, 5283.1546952487415, 5374.220737993047, 5407.78368865241, 5496.917546236889, 5563.885723425073, 4113.691473139466, 4121.216968775923, 4131.35963925592, 4132.648724858301, 4150.651038412468, 5748.971255143698, 5986.2217911671205, 5992.550558708027, 6037.245344331958, 6048.334683184934, 5787.305523337226, 5814.825555233349, 5819.0689489681445, 5831.79588714393, 5863.17915491823, 5777.988851950808, 5801.448862358069, 5820.621659592113, 5825.300963884361, 5840.240768700041, 5593.664380137205, 5696.914546281726, 5700.5154561861045, 5712.6001179917, 5752.28096487939, 5818.563607729353, 5822.48615360001, 5830.883382332245, 5862.0, 5863.325717542537, 5532.0, 5692.601517893708, 5697.863301262027, 5703.600747947769, 5711.257536520392], "value": 4362.162544955109, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [602.2892986276195, 625.455853407535, 629.5026928726306, 633.4488994574717, 656.363327572255, 12750.979921606273, 13025.674358141052, 13253.403596838129, 13253.602391928245, 13286.601401957956, 13923.512677056306, 13937.233452160128, 14041.368138433769, 14064.22646754428, 14090.225037622926, 893.9329550283722, 901.2654686430539, 901.7114523352514, 917.7797328641145, 922.4419226368051, 931.2177770672633, 944.2540393089452, 944.4097439100562, 944.8204841080208, 945.2438049560359, 978.1441238915949, 992.0278127435118, 992.2657233647085, 992.5583115513599, 994.0, 953.6662168241129, 962.4465932089042, 963.9614415423374, 969.9127078562892, 973.5375696544156, 714.2857142857143, 719.8848184290489, 720.5424555407302, 724.022569531134, 726.3027493606145, 13705.246211458365, 13989.510367137102, 14210.360533776005, 14498.565043048726, 14501.274936253145, 13404.597862064056, 13789.862101378958, 13826.930865345608, 13885.0, 13979.021468497209, 9151.633934642607, 9416.152546270869, 9508.286878484141, 9672.322937394385, 9906.504674766233, 2432.671589335435, 2436.658867758515, 2475.9752402475924, 2486.167134010109, 2506.52376048552, 57823.32650102476, 58567.41432585705, 59070.0, 59587.0, 60584.39415605832, 9452.716418507456, 9698.70903872885, 10136.138428233535, 10201.642942496977, 10239.641612543524, 19775.40673779789, 20673.896630516752, 20866.47833804167, 20879.373618791462, 21190.894045529672, 6308.7476500940365, 6410.8076757697345, 6462.612243265419, 6500.577462464925, 6613.14029176204, 3035.317053662908, 3057.5260834570677, 3075.4310452566347, 3107.0678796361003, 3204.6635103314284, 3596.964030359663, 3607.4769158471877, 3617.565892092966, 3657.2685462907525, 3678.0988657779017, 673.3502170405582, 673.4242222899449, 681.0873429604354, 689.8137502874196, 690.975815846443, 436.5270741250842, 450.9751963642031, 461.3793652056352, 477.68472807946586, 477.9784909679073, 7199.0, 7539.849203015909, 7694.4535772284335, 7717.2282771722375, 7721.343685786771, 476.6806239819329, 501.3557578511595, 504.8056498248201, 507.6624045010027, 521.7313083761866, 3288.983555082257, 3324.235425852019, 3385.4583266677705, 3409.863605455754, 3436.1581412553583, 57880.71059644675, 59868.0, 60291.39708602858, 62186.37813621896, 62794.0, 54059.59819761476, 54249.1862622061, 55388.723056385264, 55597.72201139049, 57042.7147864258, 48141.03717925622, 49063.26405103926, 49340.25989610159, 49723.0, 50871.2369314468, 6715.429188518835, 6818.829529261808, 6923.376896079377, 6953.200381955938, 7052.612106334201, 24275.16711563362, 24711.50576988486, 25207.747922521274, 25983.350416239744, 26397.73602263953, 42179.367309491, 42215.57784422117, 42256.0, 42916.78541607272, 43766.56233437625, 10631.255812093079, 10739.785204295715, 10757.408342541235, 10786.352818830896, 11222.102231821598, 11135.276207046516, 11229.71925701864, 12055.336956467478, 12082.275063496218, 12172.57395991136, 10206.744831378977, 10563.63027294041, 10696.518656660313, 10789.0, 10956.616518421817, 10254.641087562199, 10477.842832357368, 10603.151747860307, 10798.56805727762, 10977.725556861142, 10549.841752373566, 10811.025459021921, 10838.620648277589, 10871.728206794893, 11228.719282018015, 7411.370033547209, 7532.8493430132185, 7624.351930086005, 7665.770026899202, 7700.306972372513, 8481.0, 8653.783655408664, 8692.304615630608, 8737.737867863974, 8757.649694012423], "value": 6053.541573361675, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1580.5999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 121.0, 113.0, 113.0, 111.0, 113.0, 112.0, 112.0, 113.0, 111.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 131.0, 128.0, 172.0, 126.0, 132.0, 126.0, 128.0, 149.0, 127.0], "value": 137.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 173.0, 167.0, 170.0, 165.0, 167.0, 176.0, 166.0, 165.0, 164.0], "value": 168.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 78.0, 106.0, 77.0, 75.0, 79.0, 75.0, 106.0, 75.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 105.0, 85.0, 89.0, 84.0, 103.0, 85.0, 85.0, 114.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 115.0, 115.0, 116.0, 114.0, 116.0, 114.0, 114.0, 114.0], "value": 114.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 152.0, 152.0, 151.0, 155.0, 151.0, 152.0, 153.0, 151.0, 151.0], "value": 152.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 70.0, 68.0, 71.0, 72.0, 71.0, 69.0, 70.0, 71.0], "value": 70.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 58.0, 57.0, 57.0, 57.0, 56.0, 56.0, 55.0, 56.0], "value": 56.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 84.0, 83.0, 84.0, 84.0, 82.0, 85.0, 85.0, 84.0, 83.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 143.0, 140.0, 136.0, 140.0, 151.0, 134.0, 144.0, 192.0, 135.0], "value": 146.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 200.0, 199.0, 190.0, 193.0, 195.0, 187.0, 190.0, 208.0, 201.0], "value": 195.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 71.0, 78.0, 71.0, 76.0, 71.0, 71.0, 71.0, 70.0], "value": 72.6, "unit": "score"}]}]} 04:51:07 INFO - Return code: 0 04:51:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:51:07 ERROR - # TBPL SUCCESS # 04:51:07 INFO - Running post-action listener: _resource_record_post_action 04:51:07 INFO - [mozharness: 2017-03-31 11:51:07.877499Z] Finished run-tests step (success) 04:51:07 INFO - Running post-run listener: _resource_record_post_run 04:51:07 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 8192; Write bytes: 365793280; Read time: 20; Write time: 275776 04:51:07 INFO - TinderboxPrint: CPU usage
13.3% 04:51:07 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 20 04:51:07 INFO - TinderboxPrint: I/O write bytes / time
365,793,280 / 275,776 04:51:07 INFO - TinderboxPrint: CPU idle
8,306.9 (86.7%) 04:51:07 INFO - TinderboxPrint: CPU user
1,220.8 (12.7%) 04:51:07 INFO - TinderboxPrint: Swap in / out
0 / 0 04:51:07 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 106283008; Read time: 0; Write time: 105560 04:51:07 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 8192; Write bytes: 258973696; Read time: 20; Write time: 170216 04:51:08 INFO - Running post-run listener: _upload_blobber_files 04:51:08 INFO - Blob upload gear active. 04:51:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:51:08 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:51:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:51:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:51:08 INFO - (blobuploader) - INFO - Open directory for files ... 04:51:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:51:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:51:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:51:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:51:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:51:09 INFO - (blobuploader) - INFO - Done attempting. 04:51:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:51:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:51:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:51:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:51:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:51:13 INFO - (blobuploader) - INFO - Done attempting. 04:51:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:51:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:51:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:51:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:51:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:51:14 INFO - (blobuploader) - INFO - Done attempting. 04:51:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:51:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:51:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:51:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:51:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:51:15 INFO - (blobuploader) - INFO - Done attempting. 04:51:15 INFO - (blobuploader) - INFO - Iteration through files over. 04:51:15 INFO - Return code: 0 04:51:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:51:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:51:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/84cdf039e6a715d99715e660b3d932514398ff69e722d7a5710965a6520b766e81b88392d123202748582255aac782e1cee5cff170ec831e7d25b63db1247377", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/928aeca6fac99c2f885766e7b60a0ebad5cc2c2a7d92487f268f822bc790356948f4607448df70f3a8c0e6381d860cda872dc386705819ef0a2be4f02da6ee1e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8055c462a524b04a657f2eea6f8363b880bcd85baa1c5e9f362f15627bc519bbc79c0e16abc623f19289ad46c3d33aa78b1115cab4dd3b495a3174070c632c02", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/575ca8d04195f0d8a9f15c8ec062933c4b6f3a5f72c25be9db4742341378f6b31d3c89ecef66e06e3bd98485cade3c1518eacb5a3fa3d07c48757575abd2c11c"} 04:51:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:51:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:51:15 INFO - Contents: 04:51:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/84cdf039e6a715d99715e660b3d932514398ff69e722d7a5710965a6520b766e81b88392d123202748582255aac782e1cee5cff170ec831e7d25b63db1247377", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/928aeca6fac99c2f885766e7b60a0ebad5cc2c2a7d92487f268f822bc790356948f4607448df70f3a8c0e6381d860cda872dc386705819ef0a2be4f02da6ee1e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8055c462a524b04a657f2eea6f8363b880bcd85baa1c5e9f362f15627bc519bbc79c0e16abc623f19289ad46c3d33aa78b1115cab4dd3b495a3174070c632c02", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/575ca8d04195f0d8a9f15c8ec062933c4b6f3a5f72c25be9db4742341378f6b31d3c89ecef66e06e3bd98485cade3c1518eacb5a3fa3d07c48757575abd2c11c"} 04:51:15 INFO - Running post-run listener: copy_logs_to_upload_dir 04:51:15 INFO - Copying logs to upload dir... 04:51:15 INFO - mkdir: /builds/slave/test/build/upload/logs 04:51:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.719709 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-03-31 04:51:14.796350) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-31 04:51:14.799608) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/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/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/84cdf039e6a715d99715e660b3d932514398ff69e722d7a5710965a6520b766e81b88392d123202748582255aac782e1cee5cff170ec831e7d25b63db1247377", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/928aeca6fac99c2f885766e7b60a0ebad5cc2c2a7d92487f268f822bc790356948f4607448df70f3a8c0e6381d860cda872dc386705819ef0a2be4f02da6ee1e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8055c462a524b04a657f2eea6f8363b880bcd85baa1c5e9f362f15627bc519bbc79c0e16abc623f19289ad46c3d33aa78b1115cab4dd3b495a3174070c632c02", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/575ca8d04195f0d8a9f15c8ec062933c4b6f3a5f72c25be9db4742341378f6b31d3c89ecef66e06e3bd98485cade3c1518eacb5a3fa3d07c48757575abd2c11c"} build_url:https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011146 build_url: 'https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/84cdf039e6a715d99715e660b3d932514398ff69e722d7a5710965a6520b766e81b88392d123202748582255aac782e1cee5cff170ec831e7d25b63db1247377", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/928aeca6fac99c2f885766e7b60a0ebad5cc2c2a7d92487f268f822bc790356948f4607448df70f3a8c0e6381d860cda872dc386705819ef0a2be4f02da6ee1e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8055c462a524b04a657f2eea6f8363b880bcd85baa1c5e9f362f15627bc519bbc79c0e16abc623f19289ad46c3d33aa78b1115cab4dd3b495a3174070c632c02", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/575ca8d04195f0d8a9f15c8ec062933c4b6f3a5f72c25be9db4742341378f6b31d3c89ecef66e06e3bd98485cade3c1518eacb5a3fa3d07c48757575abd2c11c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-31 04:51:14.825018) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 04:51:14.825376) ========= rm -f oauth.txt in dir /builds/slave/test/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=88e9d754c8c9af9f7805d7f3000001bd-1490959812.945612-1045017474 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004492 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 04:51:14.911184) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-31 04:51:14.911641) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-31 04:51:14.912068) ========= ========= Total master_lag: 0.16 =========