builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-057 starttime: 1501550960.54 results: success (0) revision: 44121dbcac6a9d3ff18ed087a09b3205e5a04db1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:20.541216) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:20.541581) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:20.542100) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:20.574086) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:20.574345) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-31 18:29:20-- 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% 37.5M=0s 2017-07-31 18:29:21 (37.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118899 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:20.706185) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:20.706463) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018959 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:20.755351) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:20.755642) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 44121dbcac6a9d3ff18ed087a09b3205e5a04db1 --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 44121dbcac6a9d3ff18ed087a09b3205e5a04db1 --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=934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-31 18:29:21,138 truncating revision to first 12 chars 2017-07-31 18:29:21,138 Setting DEBUG logging. 2017-07-31 18:29:21,138 attempt 1/10 2017-07-31 18:29:21,138 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/44121dbcac6a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-31 18:29:21,646 unpacking tar archive at: mozilla-central-44121dbcac6a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.861775 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:21.656802) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:21.657089) ========= script_repo_revision: 44121dbcac6a9d3ff18ed087a09b3205e5a04db1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:21.657447) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:21.657713) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:21.678920) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-07-31 18:29:21.679188) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:29:22 INFO - MultiFileLogger online at 20170731 18:29:22 in /builds/slave/test 18:29:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:29:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:29:22 INFO - {'append_to_log': False, 18:29:22 INFO - 'base_work_dir': '/builds/slave/test', 18:29:22 INFO - 'blob_upload_branch': 'Firefox', 18:29:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:29:22 INFO - 'branch': 'Firefox', 18:29:22 INFO - 'buildbot_json_path': 'buildprops.json', 18:29:22 INFO - 'code_coverage': False, 18:29:22 INFO - 'config_files': ('talos/linux_config.py',), 18:29:22 INFO - 'default_actions': ('clobber', 18:29:22 INFO - 'read-buildbot-config', 18:29:22 INFO - 'download-and-extract', 18:29:22 INFO - 'populate-webroot', 18:29:22 INFO - 'create-virtualenv', 18:29:22 INFO - 'install', 18:29:22 INFO - 'setup-mitmproxy', 18:29:22 INFO - 'run-tests'), 18:29:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:29:22 INFO - 'disable_ccov_upload': False, 18:29:22 INFO - 'download_minidump_stackwalk': True, 18:29:22 INFO - 'download_symbols': 'ondemand', 18:29:22 INFO - 'enable_stylo': False, 18:29:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:29:22 INFO - 'tooltool.py': '/tools/tooltool.py', 18:29:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:29:22 INFO - '/tools/misc-python/virtualenv.py')}, 18:29:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:29:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:29:22 INFO - 'gecko_profile': False, 18:29:22 INFO - 'gecko_profile_interval': 0, 18:29:22 INFO - 'installer_path': 'installer.exe', 18:29:22 INFO - 'log_level': 'info', 18:29:22 INFO - 'log_name': 'talos', 18:29:22 INFO - 'log_to_console': True, 18:29:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:29:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:29:22 INFO - 'opt_config_files': (), 18:29:22 INFO - 'pip_index': False, 18:29:22 INFO - 'suite': 'dromaeojs-e10s', 18:29:22 INFO - 'system_bits': '32', 18:29:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:29:22 INFO - 'title': 'talos-linux64-ix-057', 18:29:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:29:22 INFO - 'use_talos_json': True, 18:29:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:29:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:29:22 INFO - 'work_dir': 'build'} 18:29:22 INFO - [mozharness: 2017-08-01 01:29:22.169198Z] Running clobber step. 18:29:22 INFO - Running pre-action listener: _resource_record_pre_action 18:29:22 INFO - Running main action method: clobber 18:29:22 INFO - rmtree: /builds/slave/test/build 18:29:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:29:23 INFO - Running post-action listener: _resource_record_post_action 18:29:23 INFO - [mozharness: 2017-08-01 01:29:23.723829Z] Finished clobber step (success) 18:29:23 INFO - [mozharness: 2017-08-01 01:29:23.723932Z] Running read-buildbot-config step. 18:29:23 INFO - Running pre-action listener: _resource_record_pre_action 18:29:23 INFO - Running main action method: read_buildbot_config 18:29:23 INFO - Using buildbot properties: 18:29:23 INFO - { 18:29:23 INFO - "project": "", 18:29:23 INFO - "product": "firefox", 18:29:23 INFO - "who": "kwierso@gmail.com", 18:29:23 INFO - "installer_path": "public/build/target.tar.bz2", 18:29:23 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 18:29:23 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:29:23 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 18:29:23 INFO - "stage_platform": "linux64-stylo", 18:29:23 INFO - "basedir": "/builds/slave/test", 18:29:23 INFO - "buildnumber": 250, 18:29:23 INFO - "platform": "ubuntu64_hw_stylo", 18:29:23 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:29:23 INFO - "slavebuilddir": "test", 18:29:23 INFO - "taskId": "V97JbNt2RjmWG6qklTrplA", 18:29:23 INFO - "branch": "mozilla-central", 18:29:23 INFO - "script_repo_revision": "production", 18:29:23 INFO - "revision": "44121dbcac6a9d3ff18ed087a09b3205e5a04db1", 18:29:23 INFO - "slavename": "talos-linux64-ix-057", 18:29:23 INFO - "repo_path": "mozilla-central" 18:29:23 INFO - } 18:29:23 INFO - Finding installer, test and symbols from parent task. 18:29:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V97JbNt2RjmWG6qklTrplA'}, attempt #1 18:29:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V97JbNt2RjmWG6qklTrplA'}, attempt #1 18:29:24 INFO - Task dependencies: ZbeB3PV6RPy_XbcR57i83g 18:29:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g'}, attempt #1 18:29:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.tar.bz2 18:29:25 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.test_packages.json 18:29:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.crashreporter-symbols.zip 18:29:25 INFO - Running post-action listener: _resource_record_post_action 18:29:25 INFO - [mozharness: 2017-08-01 01:29:25.066110Z] Finished read-buildbot-config step (success) 18:29:25 INFO - [mozharness: 2017-08-01 01:29:25.066334Z] Running download-and-extract step. 18:29:25 INFO - Running pre-action listener: _resource_record_pre_action 18:29:25 INFO - Running main action method: download_and_extract 18:29:25 INFO - mkdir: /builds/slave/test/build/tests 18:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:29:25 INFO - trying https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.test_packages.json 18:29:25 INFO - Downloading https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:29:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:29:25 INFO - Downloaded 989 bytes. 18:29:25 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:29:25 INFO - Using the following test package requirements: 18:29:25 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:29:25 INFO - u'common': [u'target.common.tests.zip'], 18:29:25 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:29:25 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:29:25 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:29:25 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:29:25 INFO - u'mozbase': [u'target.common.tests.zip'], 18:29:25 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:29:25 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:29:25 INFO - u'web-platform': [u'target.common.tests.zip', 18:29:25 INFO - u'target.web-platform.tests.tar.gz'], 18:29:25 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:29:25 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:29:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.common.tests.zip 18:29:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:29:25 INFO - Fetch https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.common.tests.zip into memory 18:29:28 INFO - Content-Length response header: 72390366 18:29:28 INFO - Bytes received: 72390366 18:29:40 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:29:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.common.tests.zip 18:29:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:29:40 INFO - Fetch https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.common.tests.zip into memory 18:29:42 INFO - Content-Length response header: 72390366 18:29:42 INFO - Bytes received: 72390366 18:29:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.talos.tests.zip 18:29:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:29:54 INFO - Fetch https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.talos.tests.zip into memory 18:29:55 INFO - Content-Length response header: 14496467 18:29:55 INFO - Bytes received: 14496467 18:29:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:29:56 INFO - trying https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.tar.bz2 18:29:56 INFO - Downloading https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:29:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:29:59 INFO - Downloaded 62400473 bytes. 18:29:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.tar.bz2 18:29:59 INFO - mkdir: /builds/slave/test/properties 18:29:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:29:59 INFO - Writing to file /builds/slave/test/properties/build_url 18:29:59 INFO - Contents: 18:29:59 INFO - build_url:https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.tar.bz2 18:29:59 INFO - Running post-action listener: _resource_record_post_action 18:29:59 INFO - Running post-action listener: set_extra_try_arguments 18:29:59 INFO - [mozharness: 2017-08-01 01:29:59.285362Z] Finished download-and-extract step (success) 18:29:59 INFO - [mozharness: 2017-08-01 01:29:59.285615Z] Running populate-webroot step. 18:29:59 INFO - Running pre-action listener: _resource_record_pre_action 18:29:59 INFO - Running main action method: populate_webroot 18:29:59 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:29:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:29:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:29:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:29:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:29:59 INFO - 'tests': ['damp', 'tps']}, 18:29:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:29:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:29:59 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:29:59 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:29:59 INFO - 'other-e10s': {'tests': ['a11yr', 18:29:59 INFO - 'ts_paint', 18:29:59 INFO - 'tpaint', 18:29:59 INFO - 'sessionrestore', 18:29:59 INFO - 'sessionrestore_many_windows', 18:29:59 INFO - 'sessionrestore_no_auto_restore', 18:29:59 INFO - 'tabpaint']}, 18:29:59 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:29:59 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:29:59 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:29:59 INFO - 'talos_options': ['--mitmproxy', 18:29:59 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:29:59 INFO - '--firstNonBlankPaint'], 18:29:59 INFO - 'tests': ['quantum_pageload_google', 18:29:59 INFO - 'quantum_pageload_youtube', 18:29:59 INFO - 'quantum_pageload_amazon', 18:29:59 INFO - 'quantum_pageload_facebook']}, 18:29:59 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:29:59 INFO - 'talos_options': ['--stylo', 18:29:59 INFO - '--mitmproxy', 18:29:59 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:29:59 INFO - '--firstNonBlankPaint'], 18:29:59 INFO - 'tests': ['quantum_pageload_google', 18:29:59 INFO - 'quantum_pageload_youtube', 18:29:59 INFO - 'quantum_pageload_amazon', 18:29:59 INFO - 'quantum_pageload_facebook']}, 18:29:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:29:59 INFO - 'tsvgr_opacity', 18:29:59 INFO - 'tart', 18:29:59 INFO - 'tscrollx', 18:29:59 INFO - 'cart', 18:29:59 INFO - 'tsvg_static']}, 18:29:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:29:59 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:29:59 INFO - 'talos_options': ['--stylo', 18:29:59 INFO - '--stylo-threads=1', 18:29:59 INFO - '--mitmproxy', 18:29:59 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:29:59 INFO - '--firstNonBlankPaint'], 18:29:59 INFO - 'tests': ['tp6_google', 18:29:59 INFO - 'tp6_youtube', 18:29:59 INFO - 'tp6_amazon', 18:29:59 INFO - 'tp6_facebook']}, 18:29:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:29:59 INFO - 'talos_options': ['--xperf_path', 18:29:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:29:59 INFO - 'tests': ['tp5n']}}} 18:29:59 INFO - Running post-action listener: _resource_record_post_action 18:29:59 INFO - [mozharness: 2017-08-01 01:29:59.299282Z] Finished populate-webroot step (success) 18:29:59 INFO - [mozharness: 2017-08-01 01:29:59.299373Z] Running create-virtualenv step. 18:29:59 INFO - Running pre-action listener: _resource_record_pre_action 18:29:59 INFO - Running main action method: create_virtualenv 18:29:59 INFO - Creating virtualenv /builds/slave/test/build/venv 18:29:59 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 18:29:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:29:59 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:29:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:29:59 INFO - Using real prefix '/usr' 18:29:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:29:59 INFO - Installing distribute.............................................................................................................................................................................................done. 18:30:01 INFO - Installing pip.................done. 18:30:01 INFO - Return code: 0 18:30:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:30:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:30:02 INFO - Reading from file tmpfile_stdout 18:30:02 INFO - Output received: 18:30:02 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) 18:30:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:02 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/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 0x145d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ccd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c9eb0>, '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 0x17cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cb240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', '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 18:30:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:30:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:30:02 INFO - Using env: {'DISPLAY': ':0', 18:30:02 INFO - 'HOME': '/home/cltbld', 18:30:02 INFO - 'LANG': 'en_US.UTF-8', 18:30:02 INFO - 'LANGUAGE': 'en_US:en', 18:30:02 INFO - 'LOGNAME': 'cltbld', 18:30:02 INFO - 'MAIL': '/var/mail/cltbld', 18:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:02 INFO - 'MOZ_NO_REMOTE': '1', 18:30:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:02 INFO - 'NO_EM_RESTART': '1', 18:30:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:02 INFO - 'PWD': '/builds/slave/test', 18:30:02 INFO - 'SHELL': '/bin/bash', 18:30:02 INFO - 'SHLVL': '1', 18:30:02 INFO - 'TERM': 'linux', 18:30:02 INFO - 'TMOUT': '86400', 18:30:02 INFO - 'USER': 'cltbld', 18:30:02 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', 18:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:02 INFO - '_': '/tools/buildbot/bin/python'} 18:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:02 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 18:30:02 INFO - Cleaning up... 18:30:02 INFO - Return code: 0 18:30:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:02 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/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 0x145d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ccd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c9eb0>, '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 0x17cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cb240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', '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 18:30:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:30:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:30:02 INFO - Using env: {'DISPLAY': ':0', 18:30:02 INFO - 'HOME': '/home/cltbld', 18:30:02 INFO - 'LANG': 'en_US.UTF-8', 18:30:02 INFO - 'LANGUAGE': 'en_US:en', 18:30:02 INFO - 'LOGNAME': 'cltbld', 18:30:02 INFO - 'MAIL': '/var/mail/cltbld', 18:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:02 INFO - 'MOZ_NO_REMOTE': '1', 18:30:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:02 INFO - 'NO_EM_RESTART': '1', 18:30:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:02 INFO - 'PWD': '/builds/slave/test', 18:30:02 INFO - 'SHELL': '/bin/bash', 18:30:02 INFO - 'SHLVL': '1', 18:30:02 INFO - 'TERM': 'linux', 18:30:02 INFO - 'TMOUT': '86400', 18:30:02 INFO - 'USER': 'cltbld', 18:30:02 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', 18:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:02 INFO - '_': '/tools/buildbot/bin/python'} 18:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:02 INFO - Downloading/unpacking psutil>=3.1.1 18:30: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 18:30: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 18:30: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 18:30: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 18:30:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:30:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:03 INFO - Installing collected packages: psutil 18:30:03 INFO - Running setup.py install for psutil 18:30:03 INFO - building 'psutil._psutil_linux' extension 18:30:03 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 18:30:03 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 18:30:03 INFO - building 'psutil._psutil_posix' extension 18:30:03 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 18:30:04 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 18:30:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:04 INFO - Successfully installed psutil 18:30:04 INFO - Cleaning up... 18:30:04 INFO - Return code: 0 18:30:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:04 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/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 0x145d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ccd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c9eb0>, '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 0x17cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cb240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', '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 18:30:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:30:04 INFO - Using env: {'DISPLAY': ':0', 18:30:04 INFO - 'HOME': '/home/cltbld', 18:30:04 INFO - 'LANG': 'en_US.UTF-8', 18:30:04 INFO - 'LANGUAGE': 'en_US:en', 18:30:04 INFO - 'LOGNAME': 'cltbld', 18:30:04 INFO - 'MAIL': '/var/mail/cltbld', 18:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:04 INFO - 'MOZ_NO_REMOTE': '1', 18:30:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:04 INFO - 'NO_EM_RESTART': '1', 18:30:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:04 INFO - 'PWD': '/builds/slave/test', 18:30:04 INFO - 'SHELL': '/bin/bash', 18:30:04 INFO - 'SHLVL': '1', 18:30:04 INFO - 'TERM': 'linux', 18:30:04 INFO - 'TMOUT': '86400', 18:30:04 INFO - 'USER': 'cltbld', 18:30:04 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', 18:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:04 INFO - '_': '/tools/buildbot/bin/python'} 18:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:30:04 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 18:30:04 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 18:30:04 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 18:30:04 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 18:30:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:30:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:30:05 INFO - Installing collected packages: mozsystemmonitor 18:30:05 INFO - Running setup.py install for mozsystemmonitor 18:30:05 INFO - Successfully installed mozsystemmonitor 18:30:05 INFO - Cleaning up... 18:30:05 INFO - Return code: 0 18:30:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30: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/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 0x145d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ccd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c9eb0>, '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 0x17cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cb240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', '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 18:30:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:30:05 INFO - Using env: {'DISPLAY': ':0', 18:30:05 INFO - 'HOME': '/home/cltbld', 18:30:05 INFO - 'LANG': 'en_US.UTF-8', 18:30:05 INFO - 'LANGUAGE': 'en_US:en', 18:30:05 INFO - 'LOGNAME': 'cltbld', 18:30:05 INFO - 'MAIL': '/var/mail/cltbld', 18:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:05 INFO - 'MOZ_NO_REMOTE': '1', 18:30:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:05 INFO - 'NO_EM_RESTART': '1', 18:30:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:05 INFO - 'PWD': '/builds/slave/test', 18:30:05 INFO - 'SHELL': '/bin/bash', 18:30:05 INFO - 'SHLVL': '1', 18:30:05 INFO - 'TERM': 'linux', 18:30:05 INFO - 'TMOUT': '86400', 18:30:05 INFO - 'USER': 'cltbld', 18:30:05 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', 18:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:05 INFO - '_': '/tools/buildbot/bin/python'} 18:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:05 INFO - Downloading/unpacking jsonschema==2.5.1 18:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:30:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:30:07 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 18:30:07 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 18:30:07 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 18:30:07 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 18:30:07 INFO - Downloading functools32-3.2.3-2.tar.gz 18:30:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:30:07 INFO - warning: no files found matching '*.txt' 18:30:07 INFO - no previously-included directories found matching 'build' 18:30:07 INFO - no previously-included directories found matching 'dist' 18:30:07 INFO - no previously-included directories found matching '.git*' 18:30:07 INFO - Installing collected packages: jsonschema, functools32 18:30:07 INFO - Running setup.py install for functools32 18:30:07 INFO - warning: no files found matching '*.txt' 18:30:07 INFO - no previously-included directories found matching 'build' 18:30:07 INFO - no previously-included directories found matching 'dist' 18:30:07 INFO - no previously-included directories found matching '.git*' 18:30:07 INFO - Successfully installed jsonschema functools32 18:30:07 INFO - Cleaning up... 18:30:07 INFO - Return code: 0 18:30:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:07 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/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 0x145d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ccd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c9eb0>, '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 0x17cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cb240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', '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 18:30:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:30:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:30:07 INFO - Using env: {'DISPLAY': ':0', 18:30:07 INFO - 'HOME': '/home/cltbld', 18:30:07 INFO - 'LANG': 'en_US.UTF-8', 18:30:07 INFO - 'LANGUAGE': 'en_US:en', 18:30:07 INFO - 'LOGNAME': 'cltbld', 18:30:07 INFO - 'MAIL': '/var/mail/cltbld', 18:30:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:07 INFO - 'MOZ_NO_REMOTE': '1', 18:30:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:07 INFO - 'NO_EM_RESTART': '1', 18:30:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:07 INFO - 'PWD': '/builds/slave/test', 18:30:07 INFO - 'SHELL': '/bin/bash', 18:30:07 INFO - 'SHLVL': '1', 18:30:07 INFO - 'TERM': 'linux', 18:30:07 INFO - 'TMOUT': '86400', 18:30:07 INFO - 'USER': 'cltbld', 18:30:07 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', 18:30:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:07 INFO - '_': '/tools/buildbot/bin/python'} 18:30:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:30:07 INFO - Cleaning up... 18:30:07 INFO - Return code: 0 18:30:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:07 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/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 0x145d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ccd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c9eb0>, '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 0x17cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cb240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', '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 18:30:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:30:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:30:07 INFO - Using env: {'DISPLAY': ':0', 18:30:07 INFO - 'HOME': '/home/cltbld', 18:30:07 INFO - 'LANG': 'en_US.UTF-8', 18:30:07 INFO - 'LANGUAGE': 'en_US:en', 18:30:07 INFO - 'LOGNAME': 'cltbld', 18:30:07 INFO - 'MAIL': '/var/mail/cltbld', 18:30:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:07 INFO - 'MOZ_NO_REMOTE': '1', 18:30:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:07 INFO - 'NO_EM_RESTART': '1', 18:30:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:07 INFO - 'PWD': '/builds/slave/test', 18:30:07 INFO - 'SHELL': '/bin/bash', 18:30:07 INFO - 'SHLVL': '1', 18:30:07 INFO - 'TERM': 'linux', 18:30:07 INFO - 'TMOUT': '86400', 18:30:07 INFO - 'USER': 'cltbld', 18:30:07 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', 18:30:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:07 INFO - '_': '/tools/buildbot/bin/python'} 18:30:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:07 INFO - Downloading/unpacking blobuploader==1.2.4 18:30:07 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 18:30:07 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 18:30:07 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 18:30:07 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 18:30:08 INFO - Downloading blobuploader-1.2.4.tar.gz 18:30:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:30:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:30:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:09 INFO - Downloading docopt-0.6.1.tar.gz 18:30:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:30:09 INFO - Installing collected packages: blobuploader, requests, docopt 18:30:09 INFO - Running setup.py install for blobuploader 18:30:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:30:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:30:09 INFO - Running setup.py install for requests 18:30:10 INFO - Running setup.py install for docopt 18:30:10 INFO - Successfully installed blobuploader requests docopt 18:30:10 INFO - Cleaning up... 18:30:10 INFO - Return code: 0 18:30:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:10 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/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 0x145d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ccd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c9eb0>, '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 0x17cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cb240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', '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 18:30:10 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:30:10 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:30:10 INFO - Using env: {'DISPLAY': ':0', 18:30:10 INFO - 'HOME': '/home/cltbld', 18:30:10 INFO - 'LANG': 'en_US.UTF-8', 18:30:10 INFO - 'LANGUAGE': 'en_US:en', 18:30:10 INFO - 'LOGNAME': 'cltbld', 18:30:10 INFO - 'MAIL': '/var/mail/cltbld', 18:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:10 INFO - 'MOZ_NO_REMOTE': '1', 18:30:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:10 INFO - 'NO_EM_RESTART': '1', 18:30:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:10 INFO - 'PWD': '/builds/slave/test', 18:30:10 INFO - 'SHELL': '/bin/bash', 18:30:10 INFO - 'SHLVL': '1', 18:30:10 INFO - 'TERM': 'linux', 18:30:10 INFO - 'TMOUT': '86400', 18:30:10 INFO - 'USER': 'cltbld', 18:30:10 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', 18:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:10 INFO - '_': '/tools/buildbot/bin/python'} 18:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:30:10 INFO - Running setup.py (path:/tmp/pip-U1AYUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:30:10 INFO - Running setup.py (path:/tmp/pip-fh_I0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:30:10 INFO - Running setup.py (path:/tmp/pip-bKYTTp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:30:10 INFO - Running setup.py (path:/tmp/pip-h481Xa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:30:10 INFO - Running setup.py (path:/tmp/pip-qss_Fb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:30:10 INFO - Running setup.py (path:/tmp/pip-DkkaBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:30:10 INFO - Running setup.py (path:/tmp/pip-pJZtsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:30:10 INFO - Running setup.py (path:/tmp/pip-CGScne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:30:10 INFO - Running setup.py (path:/tmp/pip-xfXssf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:30:11 INFO - Running setup.py (path:/tmp/pip-ZvfyNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:30:11 INFO - Running setup.py (path:/tmp/pip-vBtTWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:30:11 INFO - Running setup.py (path:/tmp/pip-h66BYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:30:11 INFO - Running setup.py (path:/tmp/pip-2urFCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:30:11 INFO - Running setup.py (path:/tmp/pip-WeHToz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:11 INFO - Running setup.py (path:/tmp/pip-GnD2gG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:30:11 INFO - Running setup.py (path:/tmp/pip-WGrcZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:30:11 INFO - Running setup.py (path:/tmp/pip-T6DhYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:30:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:30:11 INFO - Running setup.py install for manifestparser 18:30:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:30:11 INFO - Running setup.py install for mozcrash 18:30:11 INFO - Running setup.py install for mozdebug 18:30:11 INFO - Running setup.py install for mozdevice 18:30:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:30:12 INFO - Running setup.py install for mozfile 18:30:12 INFO - Running setup.py install for mozhttpd 18:30:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:30:12 INFO - Running setup.py install for mozinfo 18:30:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:30:12 INFO - Running setup.py install for mozInstall 18:30:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:30:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:30:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:30:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:30:12 INFO - Running setup.py install for mozleak 18:30:12 INFO - Running setup.py install for mozlog 18:30:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:30:12 INFO - Running setup.py install for moznetwork 18:30:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:30:12 INFO - Running setup.py install for mozprocess 18:30:12 INFO - Running setup.py install for mozprofile 18:30:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:30:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:30:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:30:12 INFO - Running setup.py install for mozrunner 18:30:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Running setup.py install for mozscreenshot 18:30:13 INFO - Running setup.py install for moztest 18:30:13 INFO - Running setup.py install for mozversion 18:30:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:30:13 INFO - Cleaning up... 18:30:13 INFO - Return code: 0 18:30:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:13 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/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 0x145d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ccd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c9eb0>, '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 0x17cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cb240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', '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 18:30:13 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:30:13 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:30:13 INFO - Using env: {'DISPLAY': ':0', 18:30:13 INFO - 'HOME': '/home/cltbld', 18:30:13 INFO - 'LANG': 'en_US.UTF-8', 18:30:13 INFO - 'LANGUAGE': 'en_US:en', 18:30:13 INFO - 'LOGNAME': 'cltbld', 18:30:13 INFO - 'MAIL': '/var/mail/cltbld', 18:30:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:13 INFO - 'MOZ_NO_REMOTE': '1', 18:30:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:13 INFO - 'NO_EM_RESTART': '1', 18:30:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:13 INFO - 'PWD': '/builds/slave/test', 18:30:13 INFO - 'SHELL': '/bin/bash', 18:30:13 INFO - 'SHLVL': '1', 18:30:13 INFO - 'TERM': 'linux', 18:30:13 INFO - 'TMOUT': '86400', 18:30:13 INFO - 'USER': 'cltbld', 18:30:13 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', 18:30:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:13 INFO - '_': '/tools/buildbot/bin/python'} 18:30:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:30:13 INFO - Running setup.py (path:/tmp/pip-9s2hEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:30:13 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)) 18:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:30:13 INFO - Running setup.py (path:/tmp/pip-giwQZg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:30:13 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)) 18:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:30:13 INFO - Running setup.py (path:/tmp/pip-fLTfMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:30:13 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)) 18:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:30:13 INFO - Running setup.py (path:/tmp/pip-dETGit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:30:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 18:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:30:13 INFO - Running setup.py (path:/tmp/pip-uNCOzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:30:13 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)) 18:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:30:14 INFO - Running setup.py (path:/tmp/pip-EbBrnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:30:14 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)) 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:30:14 INFO - Running setup.py (path:/tmp/pip-Q3mYQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:30:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:30:14 INFO - Running setup.py (path:/tmp/pip-xNzhkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:30:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:30:14 INFO - Running setup.py (path:/tmp/pip-N4joY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:30:14 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)) 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:30:14 INFO - Running setup.py (path:/tmp/pip-De9JYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:30:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 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)) 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:30:14 INFO - Running setup.py (path:/tmp/pip-4oRh2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:30:14 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)) 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:30:14 INFO - Running setup.py (path:/tmp/pip-KIh12V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:30:14 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)) 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:30:14 INFO - Running setup.py (path:/tmp/pip-UyTJFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:30:14 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)) 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:30:14 INFO - Running setup.py (path:/tmp/pip-4T3SUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:30:14 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)) 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:14 INFO - Running setup.py (path:/tmp/pip-ZbmKa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:14 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)) 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:30:14 INFO - Running setup.py (path:/tmp/pip-LuaDLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:30:14 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)) 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:30:14 INFO - Running setup.py (path:/tmp/pip-RIlBDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:30:14 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)) 18:30:14 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)) 18:30:14 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)) 18:30:14 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)) 18:30:14 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:30:14 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:30:14 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:30:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:30:14 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 18:30:14 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 18:30:14 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 18:30:14 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 18:30:15 INFO - Downloading blessings-1.6.tar.gz 18:30:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:30:16 INFO - Installing collected packages: blessings 18:30:16 INFO - Running setup.py install for blessings 18:30:16 INFO - Successfully installed blessings 18:30:16 INFO - Cleaning up... 18:30:16 INFO - Return code: 0 18:30:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:30:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:30:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:30:16 INFO - Reading from file tmpfile_stdout 18:30:16 INFO - Current package versions: 18:30:16 INFO - argparse == 1.2.1 18:30:16 INFO - blessings == 1.6 18:30:16 INFO - blobuploader == 1.2.4 18:30:16 INFO - docopt == 0.6.1 18:30:16 INFO - functools32 == 3.2.3-2 18:30:16 INFO - jsonschema == 2.5.1 18:30:16 INFO - manifestparser == 1.1 18:30:16 INFO - mozInstall == 1.13 18:30:16 INFO - mozcrash == 1.0 18:30:16 INFO - mozdebug == 0.1 18:30:16 INFO - mozdevice == 0.51 18:30:16 INFO - mozfile == 1.2 18:30:16 INFO - mozhttpd == 0.7 18:30:16 INFO - mozinfo == 0.10 18:30:16 INFO - mozleak == 0.1 18:30:16 INFO - mozlog == 3.5 18:30:16 INFO - moznetwork == 0.27 18:30:16 INFO - mozprocess == 0.25 18:30:16 INFO - mozprofile == 0.28 18:30:16 INFO - mozrunner == 6.13 18:30:16 INFO - mozscreenshot == 0.1 18:30:16 INFO - mozsystemmonitor == 0.3 18:30:16 INFO - moztest == 0.8 18:30:16 INFO - mozversion == 1.4 18:30:16 INFO - psutil == 3.1.1 18:30:16 INFO - requests == 1.2.3 18:30:16 INFO - wsgiref == 0.1.2 18:30:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:16 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/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 0x145d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ccd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c9eb0>, '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 0x17cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cb240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', '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 18:30:16 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:30:16 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:30:16 INFO - Using env: {'DISPLAY': ':0', 18:30:16 INFO - 'HOME': '/home/cltbld', 18:30:16 INFO - 'LANG': 'en_US.UTF-8', 18:30:16 INFO - 'LANGUAGE': 'en_US:en', 18:30:16 INFO - 'LOGNAME': 'cltbld', 18:30:16 INFO - 'MAIL': '/var/mail/cltbld', 18:30:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:16 INFO - 'MOZ_NO_REMOTE': '1', 18:30:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:16 INFO - 'NO_EM_RESTART': '1', 18:30:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:16 INFO - 'PWD': '/builds/slave/test', 18:30:16 INFO - 'SHELL': '/bin/bash', 18:30:16 INFO - 'SHLVL': '1', 18:30:16 INFO - 'TERM': 'linux', 18:30:16 INFO - 'TMOUT': '86400', 18:30:16 INFO - 'USER': 'cltbld', 18:30:16 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', 18:30:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:16 INFO - '_': '/tools/buildbot/bin/python'} 18:30:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:16 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)) 18:30:16 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)) 18:30:16 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)) 18:30:16 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)) 18:30:16 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)) 18:30:16 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)) 18:30:16 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)) 18:30:16 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)) 18:30:16 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)) 18:30:16 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:30:16 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 18:30:16 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 18:30:16 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 18:30:16 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 18:30:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:30:17 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)) 18:30:17 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)) 18:30:17 INFO - Installing collected packages: simplejson 18:30:17 INFO - Running setup.py install for simplejson 18:30:17 INFO - building 'simplejson._speedups' extension 18:30:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 18:30:18 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:30:18 INFO - Successfully installed simplejson 18:30:18 INFO - Cleaning up... 18:30:18 INFO - Return code: 0 18:30:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:18 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/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 0x145d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ccd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c9eb0>, '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 0x17cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cb240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', '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 18:30:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:30:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:30:18 INFO - Using env: {'DISPLAY': ':0', 18:30:18 INFO - 'HOME': '/home/cltbld', 18:30:18 INFO - 'LANG': 'en_US.UTF-8', 18:30:18 INFO - 'LANGUAGE': 'en_US:en', 18:30:18 INFO - 'LOGNAME': 'cltbld', 18:30:18 INFO - 'MAIL': '/var/mail/cltbld', 18:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:18 INFO - 'MOZ_NO_REMOTE': '1', 18:30:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:18 INFO - 'NO_EM_RESTART': '1', 18:30:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:18 INFO - 'PWD': '/builds/slave/test', 18:30:18 INFO - 'SHELL': '/bin/bash', 18:30:18 INFO - 'SHLVL': '1', 18:30:18 INFO - 'TERM': 'linux', 18:30:18 INFO - 'TMOUT': '86400', 18:30:18 INFO - 'USER': 'cltbld', 18:30:18 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', 18:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:18 INFO - '_': '/tools/buildbot/bin/python'} 18:30:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:30:19 INFO - Cleaning up... 18:30:19 INFO - Return code: 0 18:30:19 INFO - Running post-action listener: _resource_record_post_action 18:30:19 INFO - Running post-action listener: _start_resource_monitoring 18:30:19 INFO - Starting resource monitoring. 18:30:19 INFO - [mozharness: 2017-08-01 01:30:19.121551Z] Finished create-virtualenv step (success) 18:30:19 INFO - [mozharness: 2017-08-01 01:30:19.122020Z] Running install step. 18:30:19 INFO - Running pre-action listener: _resource_record_pre_action 18:30:19 INFO - Running main action method: install 18:30:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:30:19 INFO - Reading from file tmpfile_stdout 18:30:19 INFO - Detecting whether we're running mozinstall >=1.0... 18:30:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:30:19 INFO - Reading from file tmpfile_stdout 18:30:19 INFO - Output received: 18:30:19 INFO - Usage: mozinstall [options] installer 18:30:19 INFO - Options: 18:30:19 INFO - -h, --help show this help message and exit 18:30:19 INFO - -d DEST, --destination=DEST 18:30:19 INFO - Directory to install application into. [default: 18:30:19 INFO - "/builds/slave/test"] 18:30:19 INFO - --app=APP Application being installed. [default: firefox] 18:30:19 INFO - mkdir: /builds/slave/test/build/application 18:30:19 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'] 18:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:30:37 INFO - Reading from file tmpfile_stdout 18:30:37 INFO - Output received: 18:30:37 INFO - /builds/slave/test/build/application/firefox/firefox 18:30:37 INFO - Running post-action listener: _resource_record_post_action 18:30:37 INFO - [mozharness: 2017-08-01 01:30:37.563905Z] Finished install step (success) 18:30:37 INFO - [mozharness: 2017-08-01 01:30:37.564158Z] Running setup-mitmproxy step. 18:30:37 INFO - Running pre-action listener: _resource_record_pre_action 18:30:37 INFO - Running main action method: setup_mitmproxy 18:30:37 INFO - Skipping: mitmproxy is not required 18:30:37 INFO - Running post-action listener: _resource_record_post_action 18:30:37 INFO - [mozharness: 2017-08-01 01:30:37.565302Z] Finished setup-mitmproxy step (success) 18:30:37 INFO - [mozharness: 2017-08-01 01:30:37.565603Z] Running run-tests step. 18:30:37 INFO - Running pre-action listener: _resource_record_pre_action 18:30:37 INFO - Running pre-action listener: _set_gcov_prefix 18:30:37 INFO - Running main action method: run_tests 18:30:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:30:37 INFO - Python 2.7.3 18:30:37 INFO - Return code: 0 18:30:37 INFO - grabbing minidump binary from tooltool 18:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:30:37 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 0x17c9eb0>, '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 0x17cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cb240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:30:37 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 18:30:37 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 18:30:37 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 18:30:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:30:37 INFO - Return code: 0 18:30:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:30:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:30:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:30:37 INFO - ENV: RUST_BACKTRACE is now 1 18:30:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:30:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:30:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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 18:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-057 --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 18:30:37 INFO - Using env: {'DISPLAY': ':0', 18:30:37 INFO - 'HOME': '/home/cltbld', 18:30:37 INFO - 'LANG': 'en_US.UTF-8', 18:30:37 INFO - 'LANGUAGE': 'en_US:en', 18:30:37 INFO - 'LOGNAME': 'cltbld', 18:30:37 INFO - 'MAIL': '/var/mail/cltbld', 18:30:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:30:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:30:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:37 INFO - 'MOZ_NO_REMOTE': '1', 18:30:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:30:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:37 INFO - 'NO_EM_RESTART': '1', 18:30:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:37 INFO - 'PWD': '/builds/slave/test', 18:30:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:30:37 INFO - 'RUST_BACKTRACE': '1', 18:30:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:30:37 INFO - 'SHELL': '/bin/bash', 18:30:37 INFO - 'SHLVL': '1', 18:30:37 INFO - 'STYLO_FORCE_ENABLED': '1', 18:30:37 INFO - 'TERM': 'linux', 18:30:37 INFO - 'TMOUT': '86400', 18:30:37 INFO - 'USER': 'cltbld', 18:30:37 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201', 18:30:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:37 INFO - '_': '/tools/buildbot/bin/python'} 18:30:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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 18:30:37 INFO - mozversion application_buildid: 20170801004347 18:30:37 INFO - mozversion application_changeset: 44121dbcac6a9d3ff18ed087a09b3205e5a04db1 18:30:37 INFO - mozversion application_display_name: Nightly 18:30:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:30:37 INFO - mozversion application_name: Firefox 18:30:37 INFO - mozversion application_remotingname: firefox 18:30:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:30:37 INFO - mozversion application_vendor: Mozilla 18:30:37 INFO - mozversion application_version: 56.0a1 18:30:37 INFO - mozversion platform_buildid: 20170801004347 18:30:37 INFO - mozversion platform_changeset: 44121dbcac6a9d3ff18ed087a09b3205e5a04db1 18:30:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:30:37 INFO - mozversion platform_version: 56.0a1 18:30:37 INFO - using testdate: 1501551037 18:30:37 INFO - actual date: 1501551037 18:30:37 INFO - starting webserver on 'localhost:41493' 18:30:37 INFO - SUITE-START | Running 2 tests 18:30:37 INFO - TEST-START | dromaeo_css 18:30:37 INFO - Initialising browser for dromaeo_css test... 18:30:37 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41493/getInfo.html -profile /tmp/tmpLWv7IC/profile 18:30:37 INFO - TEST-INFO | started process 3569 (/builds/slave/test/build/application/firefox/firefox http://localhost:41493/getInfo.html) 18:30:44 INFO - TEST-INFO | 3569: exit 0 18:30:44 INFO - Browser initialized. 18:30:44 INFO - Running cycle 1/1 for dromaeo_css test... 18:30:44 INFO - TEST-INFO | started process 3795 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLWv7IC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:30:45 INFO - PID 3795 | 18:30:45 INFO - PID 3795 | (/builds/slave/test/build/application/firefox/firefox:3849): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:30:45 INFO - PID 3795 | 18:30:55 INFO - PID 3795 | RSS: Main: 173752320 18:30:55 INFO - PID 3795 | 18:33:55 INFO - PID 3795 | Cycle 1(1): loaded http://localhost:41493/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41493/tests/dromaeo/cssquery-ext.html) 18:33:57 INFO - PID 3795 | RSS: Main: 175439872 18:33:57 INFO - PID 3795 | 18:36:59 INFO - PID 3795 | Cycle 1(1): loaded http://localhost:41493/tests/dromaeo/cssquery-ext.html (next: http://localhost:41493/tests/dromaeo/cssquery-jquery.html) 18:36:59 INFO - PID 3795 | RSS: Main: 173109248 18:36:59 INFO - PID 3795 | 18:40:01 INFO - PID 3795 | Cycle 1(1): loaded http://localhost:41493/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41493/tests/dromaeo/cssquery-mootools.html) 18:40:02 INFO - PID 3795 | RSS: Main: 172855296 18:40:02 INFO - PID 3795 | 18:43:04 INFO - PID 3795 | Cycle 1(1): loaded http://localhost:41493/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41493/tests/dromaeo/cssquery-prototype.html) 18:43:04 INFO - PID 3795 | RSS: Main: 173838336 18:43:04 INFO - PID 3795 | 18:46:08 INFO - PID 3795 | Cycle 1(1): loaded http://localhost:41493/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41493/tests/dromaeo/cssquery-yui.html) 18:46:09 INFO - PID 3795 | RSS: Main: 177045504 18:46:09 INFO - PID 3795 | 18:49:11 INFO - PID 3795 | Cycle 1(1): loaded http://localhost:41493/tests/dromaeo/cssquery-yui.html (next: http://localhost:41493/tests/dromaeo/cssquery-dojo.html) 18:49:11 INFO - PID 3795 | RSS: Main: 177618944 18:49:11 INFO - PID 3795 | 18:49:11 INFO - PID 3795 | __start_tp_report 18:49:11 INFO - PID 3795 | _x_x_mozilla_page_load 18:49:11 INFO - PID 3795 | _x_x_mozilla_page_load_details 18:49:11 INFO - PID 3795 | |i|pagename|runs| 18:49:11 INFO - PID 3795 | |0;dojo.html;3148.574942382779;3174.333389988103;3176.65056843747;3179.4435973704603;3251.3984912791134;6116.877662446754;6199.659018753967;6219.844503887405;6231.345708700584;6238.812835614939;6107.511399088074;6227.221597300338;6304.590201636902;6320.209973753281;6335.5565110442285;5725.971370143144;5731.111677689964;5811.506021988136;5826.737796799133;5862.237909071836;5628.380878103405;5682.261306030231;5772.595918285721;5774.3936886626925;5884.558658100659;5665.716714164296;5679.432056794329;5696.51579615731;5704.48659620634;5706.486416222539;5711.257536520267;5719.771209151629;5733.713314334308;5742.9949758792045;5764.625299355571;5544.085225937715;5699.458551437607;5703.686297253649;5736.139579063174;5737.254156959611;6085.208922840046;6135.049067394561;6140.109684095826;6215.751369945242;6216;6579.210494740614;6683.2648408675;6685.097511835888;6690.197176338822;6723.260441351447;6703.664816759191;6731.394174524267;6734.158230221223;6737.292584278629;6743.797686069377;6157.261128664589;6160.476359509447;6226.5641405101205;6234.968825155891;6238.220222472146;6817.522773405814;6885.862282754317;6900.102986611709;6936.959456081528;6959.338862808025;6543.476521878333;6567.605943643397;6587;6638.867222655616;6669.299723529057;6602.141721576164;6674.666266686646;6731.629760363227;6803.727850886008;6804.693788779417;6549.115869357671;6602.86794264112;6680.866382672417;6768.593884366954;6768.76309329181;6640.236372817188;6648.335166483313;6694.163229596301;6727.192736871607;6728.697208625624;5201.193814958688;5452.727363631802;5484.314460692413;5525.696086715189;5532.6680399176175;4653.930191047137;4661.277501987197;4696;4714.504976977437;4718.056388722201;5670.149477578396;5766.106253530709;5769.480746732815;5777.480026797609;5793.362730099686;6697.229818570927;6714.496412769062;6745.763898263635;6779.830504237433;6784.525083244125;5826.82519524415;5839;5839.883202335929;5840.707964601753;5848;5921.585489015813;5977.043673012384;6001.609895356788;6015.0676645120075;6018.067199584071;6514.413702766774;6515.8696826063215;6598.274189839114;6614.933850661528;6616.106825578581;6637.070810086495;6737.79786606383;6743.865122697421;6838.829029274308;6841.897371539331;6516.804495865132;6518.250401203923;6533.803985880431;6538.280789113289;6538.705758240986;6518.543701941008;6570.080188773671;6588.5058620604605;6593.3736295051895;6611.636360000054;6377.649229292433;6769.864602707819;6776.728930842711;6815.045893575003;6818;6512.804615861532;6522.412982831568;6529.249136349381;6583.769568065095;6591.835204119935;6570.901436478361;6602.273749887605;6603.80188594343;6609.867802644017;6615.966920165369;6455.999320105391;6460.483161347175;6549.345065493412;6550.639714815731;6559.934400655932;6367.490600751836;6577.374901443557;6640.701168447335;6679.499037572299;6686.933130668631;6229.875402492015;6268;6356.968215158895;6363.5227357947415;6366.4588509977175 18:49:11 INFO - PID 3795 | |1;ext.html;774.1871035412815;821.5809936932163;861.3410740783299;882.7572417585163;888.458040595237;40180;41015.79492102543;41022.7948860256;41051.79474102629;41058;37415.62584374155;37541.812290938506;37789.62210377895;37836.62163378365;37899.43150852739;5070.08738427083;5239.685618862862;5608.074667679828;5659.207710920454;5682.971585142069;6727.260001399842;6796.388325050744;6821.045053692487;6825.283345248752;6830.556013859084;6812.216595091553;7399.70401183952;7657.387409007266;7659.119201291839;7665.8850117248285;7577.16651168371;7634.465587408884;7634.923650763504;7659.540427574363;7661.42539309554;7464.888026679604;7493.437992150611;7496.250374962515;7553.584552849591;7557.357624601916;37846;37997.810010950045;38087.61912380868;38108.42837357442;38125.23749524994;3496.0385893879134;3534.3108093921696;3535.5580552430947;3535.893923182283;3539;30909.845450772827;31082.844585776926;31097.8445107773;31106.377872442652;31142.844285778425;4751.764541219273;4761.952380476186;4782.30656554801;4817.988222473285;4847.878803029917;211830;220510;221682;222375;223246.99999999837;47621.52378476171;47820.521794782304;48191.27713084307;48383.274250886265;48432.515674843504;49451;52892;53280.73359633177;53413.198802018;53509;16188.59528511773;16302.510924672279;16330.265138068815;16333;16345.101019443808;13436.731265374638;13493.257870817002;13505.662358441117;13556.389962451713;13624.250666213453;14394.280285985868;14524.709505809824;14748.410063597335;14762.940668116842;14891.925540372229;5876.941230587725;5922.555808314393;5924.6149000314845;5945.881082378415;6028;966.5650457294246;975.4878688688495;978.7063880835863;995.998043931688;1011.1657882247175;27763.583546246824;27949.301267468476;28920.566191507143;29058.128256152348;29101.563476547868;1031.7111208861531;1051.3429106808244;1071.6463567022863;1073.978520429587;1096.9725756856142;11912.88087119135;12014.579489717818;12231.633051008484;12244.81632775509;12375.75248495043;223452;224321;225188;226552;226986;44293;45306;52390.21414678783;52409.73795131076;52413.73793131086;1435.9210243436503;1443.5380678183092;1444.588292336679;1445.7253121906804;1464.8095747552964;26500.542470163557;27394.041208558403;27412.862935685192;27443.86278068597;28198.577021345107;50529;50858.745706271235;51018.74490627523;51038;51102.74448627882;201713;207976;208412;209655;210996;34962.65037349696;35128.82435587806;35144.64855351414;35471.82264088663;35584.46623300704;41353.172936541705;43315.56684433115;43556.782216088715;44174.779126104164;46001.53998459972;30059.3988120232;30583.847080765343;32270.35459290848;32620.673793261765;33108;30973.845130774203;31534.369312613162;32134.35731285408;32152.19619509531;32527.837360813046;34791.13022174466;36865.815670921475;36868;36951;37849.62150378461;31064.22339441532;32322.515162273055;33192.502112467846;33331.666683332856;33816.3236735259;23362.415939601648;24284.27147185587;24379.634305485786;24677.753222467545;25154.496910062062 18:49:11 INFO - PID 3795 | |2;jquery.html;576.4926864359363;581.7934633205215;584.8567101060238;593.8990371636821;604.6372176693983;22019.779802201974;22877.542449151006;23010.999999999978;23022.65466018007;23030.76969230307;25750;27095.593566096526;28394.858025709895;28492.43015139696;28803.567946480765;6862.6225557594435;7048;7051.083359163302;7056.08270924779;7069.717211311541;4004.379321205218;4240.766757828318;6912.274211207826;6980.546264492792;6982.266861979495;6587.407133357997;6932.306769323078;6932.930670693304;6953.687084081229;6954;6936.271691472373;6939.132608423947;6948.6525673716615;6949.895751563706;6955.234924158339;6793.320667933217;6794.626295553742;6795.456363490909;6827.21487028993;6830.863382732317;30422.847885760428;31508;31867;32682.182945426317;33077.17307067318;1883.8587105967108;1939.9806001939942;1940.0590713503939;1940.1657287366427;1949.8245157935712;19937.501562460908;20455.89772051145;20545.383638490868;20592.27927022547;20649;2360.2977667493797;2402.1232250228713;2404.5671779079757;2408.1330720940446;2495.8502489850666;140700;141243.29378353147;142418;142584.287078565;143694.99999999895;19894.60210795776;20838.479038024172;22159.889200553895;22201.888990554944;22502.887485562467;44202.55797442049;45004.54995450069;45059;45289.09421811545;45410.77294613506;11850.170488065925;11884.702882427835;11913.702157446132;12048.75902481963;12159;9957.755280589927;10008.44953527563;10046.497675116216;10097.091261786327;10114.393136411838;8965.641374345083;9518.904810951803;11137.208651501336;12062.215955962834;12097.395130243453;2842.0300785679015;2845.900393486218;2848.159792861111;2867.4551835151256;2947.9465734614905;934.359963425056;938.0853667674099;956.5743244256239;956.7466618729487;982.2093214962024;19741.309054183323;20030.99845007773;23763.643545346833;25365.61951570728;25584.8720756395;994.547480896205;995.5221493683073;1008.6721815410021;1024.134606257711;1035.4615599888166;11004.22970392081;11106.111511079094;11177.776444471065;11291.209615327012;11446.828297575543;495229;503329;508521;537357;538759;117791;123170;123323.38338308252;123575;124736.37631811782;1206.7284860906402;1237.5730373021293;1271.8600953895063;1281.628327784932;1284.858665546789;19123.521911952314;20801.58396832085;20935.476613084797;21775.346739597204;21907.12371505122;54526;54638.45361546334;56107.43892561022;56980.43019569751;57093.42906571047;64498.35501645111;64807.35192648013;65102;65544.34455655573;65773.67113164403;12609.369531523203;14350.712985740018;14819.555413337617;15253.694926101638;15319.923400382926;17961.73057404166;19506.512337191685;19605.411837644893;24219.273421797374;24497.632535511624;18529.72205416947;18843.057847107317;19607.215711371955;19619.803801961796;19813.405597832087;16349.67300654004;16613.0862802547;16838.915805420893;17226.310947561957;17766.46700598984;18858.24567017304;20064.49838754043;20294.898525507277;20324.79675203229;20344.18623255053;14986.47547335838;15153.242337883283;15221.923890380478;15229;15239.771403428736;18604.81395186031;18855.057247137862;18963.241470341032;18997.90501047486;19649.410517683922 18:49:11 INFO - PID 3795 | |3;mootools.html;1376.3668712392298;1432.7277817214729;1450.369089446091;1454.556360310105;1464.721702876454;13762.862371376295;14482.5655230343;14484;14500.564983050524;14503.854961450383;13061.542846000391;14200;14231.644208894757;14253.714925701506;14329.641758956031;2640.0627777139125;2716.334498047981;2726.9045583404586;2730.5358089124798;2741.643586333774;2666.706662267149;2686.6104414859833;2696.9595456068173;2761.047438633678;2810.12886005338;2760.4341110072487;2790.1350581319857;2791.3719413132085;2793;2794;2691.6097165910933;2708.8510131942635;2709.91870243893;2712.2541301142146;2720.2791260315935;2638.4854953284116;2644.5239856825765;2673.8128331016837;2675.919722408331;2688.7983401244983;13798.862011379859;14137.858621413758;14205.502807401692;14222.715545689029;14225.502107426191;7594.354479869217;7907.209279072104;7958.323542498894;8002.119766825645;8016.559089250089;5169.65644810114;5345.866353341159;5454.018276710191;5473.589480788957;5488.368837583689;1827.7960690412133;1854.666160091183;1865.2259312385345;1911.1877452082808;1932.8163824436656;23206.883965580066;23355.53288934229;23452.179173729008;23470.530589388287;23475.647865282033;6983.755568555076;7145.163132825785;7246.347828695442;7262.039249402737;7303.926960730325;15740.68518629644;15969.600759981093;15977.520674379788;15986.520404387888;15989.520314390587;7652.502587331805;7806.648700808477;7837;7857.410694197958;7880.921190788019;6877.690503927335;7036.788896333118;7047.224805271416;7170.820729481805;7270.818229544304;5018.397792264952;5204.7918083276245;5213.322268105122;5216.374035115735;5256.500632439912;1370.4792178971927;1431.2772050114625;1466.2668665667165;1492.2091291615461;1504.586238784343;836.7866194120583;838.610046328448;860.638531816626;881.8985816631169;882.6557642519423;7436.81302240219;7514.849703005909;7539.246075392527;7574.886376704354;7630.38956883448;919.2324409118461;919.8344298026286;956.4596003258137;960.3565611040619;981.7398389426808;4411.558844115562;4488.506264310923;4713.835015774432;4780.306855505932;4884.780184891689;18604.627907442045;19176.616467670567;19181.904090479456;19369.225230990603;19390.224391024483;18491.167897444648;19537.511562210773;19636.509087272647;19717.50706232327;19790.60418791616;11083.778324433628;11217.326960382403;11239.66281011571;11254.887451125547;11318.490667919963;6551.836204094936;7239.276072392719;7340;7502.699892004259;7541.849163016818;16359.754603681193;16795.91602041982;16858.494245172664;16979.745303820204;17210.225539850486;16777.916110419857;17178.22697978569;17347.739783902998;17359.2188351527;17488.737668934722;10404.167666586503;10675.679729608124;10679.733006674895;10681.412522311348;10704.411257380918;12895.677608059874;13158;13196.34018299062;13290;13326.200427974352;9941.353812002197;10295.897041029793;10917.672469825919;10961.945190273998;11097.944510277397;10061.044200800912;10355.637552685619;10382.169426446017;10556.261061725912;10591.4704264788;8997.865032024656;9050.773730656787;9054.230390416888;9082.228010619172;9114.31642626819;6316.273628532777;6336.873262534631;6420.775272865428;6490.123833282447;6639.103720997604;6179.474744646755;6231.906521402273;6264.342244064308;6581;6721.932780672326 18:49:11 INFO - PID 3795 | |4;prototype.html;495.39561734683696;499.44810983862806;519.0553193188393;529.1084373365218;531.8032328038626;6181.258249010125;6207.472364848988;6283.183186185795;6288.4907622170695;6308.5899416537895;5892.9705351473185;6085.726142323595;6112.449879510843;6126.468382904274;6133.080037994303;4871.5615594596475;4919.852404427864;4946.438564233764;4958.206686930092;4979.502049795028;4852.393450818648;4876.756162191894;4900.289458028584;4939.382577177853;4950.207966725325;4855.295982082596;4882.829100981467;4910.656254062217;4913.778879950411;4977.129002424579;4836.138086836558;4879.365682461257;4918.458969513368;4943.283223932528;4955.60355171589;4434.889127771799;4447.2439685253585;4453.866384008485;4520.547945205453;4535.160995215896;6136.539759518009;6155.384461553854;6171.660558669271;6174.536909731788;6184.783532576384;5672.631278966854;5702.54379649627;5733.569982251348;5739.856503587402;5788.842231553664;6454.548181627288;6504.77233296837;6615.221606925103;6620.470362370998;6635.93364066358;4914.606831453475;4918.754062296906;4925.088858561177;4931.161702510582;4935;6940.340667636567;7013.2986701329455;7013.719451221945;7048.330408611174;7066.858662826766;4993.325901003391;4997.225430058346;5011.6241281904;5023.748812559357;5050.621203409759;5067.87330316745;5103.285540024325;5135.537801597874;5144.408393034774;5148.613853960968;5630.774769009194;5738.626989245686;5738.655680659173;5755.453231942959;5755.769769209269;6449.3873082057125;6551.541392102602;6573.816712991707;6595.570303442183;6624.503162262849;1661.6925868714327;1663.6256842210405;1685.3679870048732;1734.5750291178742;1749.5713550180033;1195.4560674892903;1211.236920739928;1217.634709587138;1224.6815827884814;1239.3617287097152;5537.446255374431;5620.85947851307;5636.689982050944;5660.811229641821;5683.948469533149;6480.740770369227;6564.4420224281475;6591.6374599397495;6629.442081110935;6690.4170643108655;5635.633683810539;5654.208410822488;5728.656280623176;5737.3688894221605;5752.511036561856;3444.4144495435835;3549.4143466328283;3567.340042092221;3580.480830279595;3618.3667858124722;6342.270638876496;6445.6454894981225;6477.838054048592;6485.77299794505;6532.346765323525;6813.26261803237;6875.690593923286;6911.170659520789;6936.687849046805;6995.475339349569;6405.327440618761;6454.2577603575255;6462.935370646327;6523.575967562188;6544.607323560602;6697.631630260187;6761.695723692544;6776.577828196729;6811.352921472452;6821.522493425413;6619.867602647823;6625.972974189009;6658.866822663423;6692.6319052450635;6717.227518835396;6518.24007517958;6519.348065193442;6523.6738163092605;6526.4452521536205;6584.473242140713;5031.446540880427;5034.59723222134;5041.924371134509;5051.343325367826;5071.225071225042;6393.6663117962535;6714.261431242657;6743.358586363309;6749.156355455568;6790.72837086511;6180.81457556274;6365.522585806176;6407.359264073555;6429.58207716497;6451.3548645135115;6427.071878436357;6450.580712253689;6461.353864613501;6491.383318584727;6519;6339.144215530844;6343.5876668016435;6344.238691357066;6396.904046439214;6427.614343139427;6473.352664733489;6517.706703198272;6523.67381630907;6571.474282057328;6582.736690532325;6285.308616052139;6287.233617208413;6346.333634968262;6357.3006969234275;6390.4248617624635 18:49:11 INFO - PID 3795 | |5;yui.html;893.6916763716515;915.4004127296621;924.9630014799409;929.1776777551867;936.1574582875407;17776.288948442063;18549.443516694504;18558.628827423443;18589.907050464728;18639.813601863978;17533.298668053263;17551.12244387782;17558;19409.61180776387;21784.673229901564;1024.3444195714749;1039.3088596083596;1040.8334666453368;1049.6536143072806;1049.7008352619466;1049.7375656085978;1058.1059005140683;1060.6870973062964;1061.1086687182765;1061.941593212373;1042.525650828871;1076;1079.4494807648116;1079.865016872891;1080.287010573022;1063.2769716592713;1064.4837253931837;1068.6046162919693;1069.0485467933545;1070.0102405275168;783.2519943453988;783.3772151139857;788.0740130346877;792.4492477727981;793.2939683681528;18526.722099168524;19033;19148;19197.904010479997;19749.308774192978;19850.1067451965;20277.493062673402;20668.483287917923;20750.169993200252;20817.791822081886;15789.131597762118;16004.679906401923;16024.919875400665;16200;16200.189990500545;3524.753267271292;3569.7679650822615;3571.6964058055096;3572.1962558424407;3578.624244454321;119989;121662;121854;122658;124043.37978310141;16161.757573636405;16167;16230.431934882223;16234.431794887123;16273.267702953395;29033.419331613248;30354.392912141633;30575.69424305773;30581.38837223243;30624.08127756171;8471.576421178916;8481;8529.402941794015;8615.612297446505;8653.350998675125;4198.6221240088535;4302.569743025735;4314.288142456522;4349.804258808361;4352.042550638855;5263.447338029452;5388.865278368072;5408.675479471166;5456.645318054314;5506.476884695948;1002.183220675162;1019.9953046249439;1034.7826956339254;1038.7662775875367;1039.293280569205;626.4768917953469;646.6411141816243;663.7577284291199;666.6966530228734;678.2166597579803;10386.844197337046;11287.322760634388;11388.20282580228;11414.94292528532;11496;590.4632417208912;663.6913835066621;665.3080795972231;675.4292622733782;680.0547239337325;5176.197689358156;5181.989512045115;5323.520883120537;5338.225957236256;5465.617406781566;93159;93339;94626;96666;99721;87236;91287;91825;92191.53904230436;92399;76736;78311.60844195857;78460;79059;82466.58766706249;11892.76214475723;12203.511859525519;12254.509819607116;12525.311107889154;12536.561220357244;36383.636163638024;36415.45376819403;38614.80909916734;38898.416523752734;40014.599854001084;67364.6631766838;68363.65818170877;72417;72780;79723;18517.81482185215;19232.519187020436;19262.614747705247;19310.034498275312;19363.4190974271;19889;20129;20430;21151.153953841673;21334.466638334165;18041.368552100612;18443.17005734772;18497.260109596005;18499.167537461115;18534.536636584195;19030.61938761245;19370.515737106685;19523.219071236992;19781.505462363555;20489;17508.47474575765;17680.91159544194;18121.36575219861;18321.725174122133;18786.342478013205;12521.749565008831;12776.48894044265;12822.166559173624;12969.675758106123;13476;13077.280749558486;14553.781693274397;14770;14800.259987000392;14945.178015209269 18:49:11 INFO - PID 3795 | __end_tp_report 18:49:11 INFO - PID 3795 | __start_cc_report 18:49:11 INFO - PID 3795 | _x_x_mozilla_cycle_collect,355 18:49:11 INFO - PID 3795 | __end_cc_report 18:49:11 INFO - PID 3795 | __startTimestamp1501552151459__endTimestamp 18:49:11 INFO - PID 3795 | 18:49:11 INFO - PID 3795 | ------- Summary: start ------- 18:49:11 INFO - PID 3795 | Number of tests: 6 18:49:11 INFO - PID 3795 | 18:49:11 INFO - PID 3795 | [#0] dojo.html Cycles:165 Average:6176.35 Median:6456.00 stddev:725.18 (11.2%) stddev-sans-first:687.40 18:49:11 INFO - PID 3795 | Values: 3148.6 3174.3 3176.7 3179.4 3251.4 6116.9 6199.7 6219.8 6231.3 6238.8 6107.5 6227.2 6304.6 6320.2 6335.6 5726.0 5731.1 5811.5 5826.7 5862.2 5628.4 5682.3 5772.6 5774.4 5884.6 5665.7 5679.4 5696.5 5704.5 5706.5 5711.3 5719.8 5733.7 5743.0 5764.6 5544.1 5699.5 5703.7 5736.1 5737.3 6085.2 6135.0 6140.1 6215.8 6216.0 6579.2 6683.3 6685.1 6690.2 6723.3 6703.7 6731.4 6734.2 6737.3 6743.8 6157.3 6160.5 6226.6 6235.0 6238.2 6817.5 6885.9 6900.1 6937.0 6959.3 6543.5 6567.6 6587.0 6638.9 6669.3 6602.1 6674.7 6731.6 6803.7 6804.7 6549.1 6602.9 6680.9 6768.6 6768.8 6640.2 6648.3 6694.2 6727.2 6728.7 5201.2 5452.7 5484.3 5525.7 5532.7 4653.9 4661.3 4696.0 4714.5 4718.1 5670.1 5766.1 5769.5 5777.5 5793.4 6697.2 6714.5 6745.8 6779.8 6784.5 5826.8 5839.0 5839.9 5840.7 5848.0 5921.6 5977.0 6001.6 6015.1 6018.1 6514.4 6515.9 6598.3 6614.9 6616.1 6637.1 6737.8 6743.9 6838.8 6841.9 6516.8 6518.3 6533.8 6538.3 6538.7 6518.5 6570.1 6588.5 6593.4 6611.6 6377.6 6769.9 6776.7 6815.0 6818.0 6512.8 6522.4 6529.2 6583.8 6591.8 6570.9 6602.3 6603.8 6609.9 6616.0 6456.0 6460.5 6549.3 6550.6 6559.9 6367.5 6577.4 6640.7 6679.5 6686.9 6229.9 6268.0 6357.0 6363.5 6366.5 18:49:11 INFO - PID 3795 | 18:49:11 INFO - PID 3795 | [#1] ext.html Cycles:180 Average:38996.13 Median:35528.14 stddev:56450.02 (158.9%) stddev-sans-first:56535.42 18:49:11 INFO - PID 3795 | Values: 774.2 821.6 861.3 882.8 888.5 40180.0 41015.8 41022.8 41051.8 41058.0 37415.6 37541.8 37789.6 37836.6 37899.4 5070.1 5239.7 5608.1 5659.2 5683.0 6727.3 6796.4 6821.0 6825.3 6830.6 6812.2 7399.7 7657.4 7659.1 7665.9 7577.2 7634.5 7634.9 7659.5 7661.4 7464.9 7493.4 7496.3 7553.6 7557.4 37846.0 37997.8 38087.6 38108.4 38125.2 3496.0 3534.3 3535.6 3535.9 3539.0 30909.8 31082.8 31097.8 31106.4 31142.8 4751.8 4762.0 4782.3 4818.0 4847.9 211830.0 220510.0 221682.0 222375.0 223247.0 47621.5 47820.5 48191.3 48383.3 48432.5 49451.0 52892.0 53280.7 53413.2 53509.0 16188.6 16302.5 16330.3 16333.0 16345.1 13436.7 13493.3 13505.7 13556.4 13624.3 14394.3 14524.7 14748.4 14762.9 14891.9 5876.9 5922.6 5924.6 5945.9 6028.0 966.6 975.5 978.7 996.0 1011.2 27763.6 27949.3 28920.6 29058.1 29101.6 1031.7 1051.3 1071.6 1074.0 1097.0 11912.9 12014.6 12231.6 12244.8 12375.8 223452.0 224321.0 225188.0 226552.0 226986.0 44293.0 45306.0 52390.2 52409.7 52413.7 1435.9 1443.5 1444.6 1445.7 1464.8 26500.5 27394.0 27412.9 27443.9 28198.6 50529.0 50858.7 51018.7 51038.0 51102.7 201713.0 207976.0 208412.0 209655.0 210996.0 34962.7 35128.8 35144.6 35471.8 35584.5 41353.2 43315.6 43556.8 44174.8 46001.5 30059.4 30583.8 32270.4 32620.7 33108.0 30973.8 31534.4 32134.4 32152.2 32527.8 34791.1 36865.8 36868.0 36951.0 37849.6 31064.2 32322.5 33192.5 33331.7 33816.3 23362.4 24284.3 24379.6 24677.8 25154.5 18:49:11 INFO - PID 3795 | 18:49:11 INFO - PID 3795 | [#2] jquery.html Cycles:180 Average:36943.47 Median:22690.21 stddev:86832.29 (382.7%) stddev-sans-first:87032.94 18:49:11 INFO - PID 3795 | Values: 576.5 581.8 584.9 593.9 604.6 22019.8 22877.5 23011.0 23022.7 23030.8 25750.0 27095.6 28394.9 28492.4 28803.6 6862.6 7048.0 7051.1 7056.1 7069.7 4004.4 4240.8 6912.3 6980.5 6982.3 6587.4 6932.3 6932.9 6953.7 6954.0 6936.3 6939.1 6948.7 6949.9 6955.2 6793.3 6794.6 6795.5 6827.2 6830.9 30422.8 31508.0 31867.0 32682.2 33077.2 1883.9 1940.0 1940.1 1940.2 1949.8 19937.5 20455.9 20545.4 20592.3 20649.0 2360.3 2402.1 2404.6 2408.1 2495.9 140700.0 141243.3 142418.0 142584.3 143695.0 19894.6 20838.5 22159.9 22201.9 22502.9 44202.6 45004.5 45059.0 45289.1 45410.8 11850.2 11884.7 11913.7 12048.8 12159.0 9957.8 10008.4 10046.5 10097.1 10114.4 8965.6 9518.9 11137.2 12062.2 12097.4 2842.0 2845.9 2848.2 2867.5 2947.9 934.4 938.1 956.6 956.7 982.2 19741.3 20031.0 23763.6 25365.6 25584.9 994.5 995.5 1008.7 1024.1 1035.5 11004.2 11106.1 11177.8 11291.2 11446.8 495229.0 503329.0 508521.0 537357.0 538759.0 117791.0 123170.0 123323.4 123575.0 124736.4 1206.7 1237.6 1271.9 1281.6 1284.9 19123.5 20801.6 20935.5 21775.3 21907.1 54526.0 54638.5 56107.4 56980.4 57093.4 64498.4 64807.4 65102.0 65544.3 65773.7 12609.4 14350.7 14819.6 15253.7 15319.9 17961.7 19506.5 19605.4 24219.3 24497.6 18529.7 18843.1 19607.2 19619.8 19813.4 16349.7 16613.1 16838.9 17226.3 17766.5 18858.2 20064.5 20294.9 20324.8 20344.2 14986.5 15153.2 15221.9 15229.0 15239.8 18604.8 18855.1 18963.2 18997.9 19649.4 18:49:11 INFO - PID 3795 | 18:49:11 INFO - PID 3795 | [#3] mootools.html Cycles:180 Average:8681.88 Median:2687.70 stddev:5959.64 (221.7%) stddev-sans-first:5951.08 18:49:11 INFO - PID 3795 | Values: 1376.4 1432.7 1450.4 1454.6 1464.7 13762.9 14482.6 14484.0 14500.6 14503.9 13061.5 14200.0 14231.6 14253.7 14329.6 2640.1 2716.3 2726.9 2730.5 2741.6 2666.7 2686.6 2697.0 2761.0 2810.1 2760.4 2790.1 2791.4 2793.0 2794.0 2691.6 2708.9 2709.9 2712.3 2720.3 2638.5 2644.5 2673.8 2675.9 2688.8 13798.9 14137.9 14205.5 14222.7 14225.5 7594.4 7907.2 7958.3 8002.1 8016.6 5169.7 5345.9 5454.0 5473.6 5488.4 1827.8 1854.7 1865.2 1911.2 1932.8 23206.9 23355.5 23452.2 23470.5 23475.6 6983.8 7145.2 7246.3 7262.0 7303.9 15740.7 15969.6 15977.5 15986.5 15989.5 7652.5 7806.6 7837.0 7857.4 7880.9 6877.7 7036.8 7047.2 7170.8 7270.8 5018.4 5204.8 5213.3 5216.4 5256.5 1370.5 1431.3 1466.3 1492.2 1504.6 836.8 838.6 860.6 881.9 882.7 7436.8 7514.8 7539.2 7574.9 7630.4 919.2 919.8 956.5 960.4 981.7 4411.6 4488.5 4713.8 4780.3 4884.8 18604.6 19176.6 19181.9 19369.2 19390.2 18491.2 19537.5 19636.5 19717.5 19790.6 11083.8 11217.3 11239.7 11254.9 11318.5 6551.8 7239.3 7340.0 7502.7 7541.8 16359.8 16795.9 16858.5 16979.7 17210.2 16777.9 17178.2 17347.7 17359.2 17488.7 10404.2 10675.7 10679.7 10681.4 10704.4 12895.7 13158.0 13196.3 13290.0 13326.2 9941.4 10295.9 10917.7 10961.9 11097.9 10061.0 10355.6 10382.2 10556.3 10591.5 8997.9 9050.8 9054.2 9082.2 9114.3 6316.3 6336.9 6420.8 6490.1 6639.1 6179.5 6231.9 6264.3 6581.0 6721.9 18:49:11 INFO - PID 3795 | 18:49:11 INFO - PID 3795 | [#4] prototype.html Cycles:180 Average:5494.14 Median:6145.96 stddev:1546.36 (25.2%) stddev-sans-first:1504.50 18:49:11 INFO - PID 3795 | Values: 495.4 499.4 519.1 529.1 531.8 6181.3 6207.5 6283.2 6288.5 6308.6 5893.0 6085.7 6112.4 6126.5 6133.1 4871.6 4919.9 4946.4 4958.2 4979.5 4852.4 4876.8 4900.3 4939.4 4950.2 4855.3 4882.8 4910.7 4913.8 4977.1 4836.1 4879.4 4918.5 4943.3 4955.6 4434.9 4447.2 4453.9 4520.5 4535.2 6136.5 6155.4 6171.7 6174.5 6184.8 5672.6 5702.5 5733.6 5739.9 5788.8 6454.5 6504.8 6615.2 6620.5 6635.9 4914.6 4918.8 4925.1 4931.2 4935.0 6940.3 7013.3 7013.7 7048.3 7066.9 4993.3 4997.2 5011.6 5023.7 5050.6 5067.9 5103.3 5135.5 5144.4 5148.6 5630.8 5738.6 5738.7 5755.5 5755.8 6449.4 6551.5 6573.8 6595.6 6624.5 1661.7 1663.6 1685.4 1734.6 1749.6 1195.5 1211.2 1217.6 1224.7 1239.4 5537.4 5620.9 5636.7 5660.8 5683.9 6480.7 6564.4 6591.6 6629.4 6690.4 5635.6 5654.2 5728.7 5737.4 5752.5 3444.4 3549.4 3567.3 3580.5 3618.4 6342.3 6445.6 6477.8 6485.8 6532.3 6813.3 6875.7 6911.2 6936.7 6995.5 6405.3 6454.3 6462.9 6523.6 6544.6 6697.6 6761.7 6776.6 6811.4 6821.5 6619.9 6626.0 6658.9 6692.6 6717.2 6518.2 6519.3 6523.7 6526.4 6584.5 5031.4 5034.6 5041.9 5051.3 5071.2 6393.7 6714.3 6743.4 6749.2 6790.7 6180.8 6365.5 6407.4 6429.6 6451.4 6427.1 6450.6 6461.4 6491.4 6519.0 6339.1 6343.6 6344.2 6396.9 6427.6 6473.4 6517.7 6523.7 6571.5 6582.7 6285.3 6287.2 6346.3 6357.3 6390.4 18:49:11 INFO - PID 3795 | 18:49:11 INFO - PID 3795 | [#5] yui.html Cycles:180 Average:22752.51 Median:19636.26 stddev:29974.57 (152.6%) stddev-sans-first:30013.71 18:49:11 INFO - PID 3795 | Values: 893.7 915.4 925.0 929.2 936.2 17776.3 18549.4 18558.6 18589.9 18639.8 17533.3 17551.1 17558.0 19409.6 21784.7 1024.3 1039.3 1040.8 1049.7 1049.7 1049.7 1058.1 1060.7 1061.1 1061.9 1042.5 1076.0 1079.4 1079.9 1080.3 1063.3 1064.5 1068.6 1069.0 1070.0 783.3 783.4 788.1 792.4 793.3 18526.7 19033.0 19148.0 19197.9 19749.3 19850.1 20277.5 20668.5 20750.2 20817.8 15789.1 16004.7 16024.9 16200.0 16200.2 3524.8 3569.8 3571.7 3572.2 3578.6 119989.0 121662.0 121854.0 122658.0 124043.4 16161.8 16167.0 16230.4 16234.4 16273.3 29033.4 30354.4 30575.7 30581.4 30624.1 8471.6 8481.0 8529.4 8615.6 8653.4 4198.6 4302.6 4314.3 4349.8 4352.0 5263.4 5388.9 5408.7 5456.6 5506.5 1002.2 1020.0 1034.8 1038.8 1039.3 626.5 646.6 663.8 666.7 678.2 10386.8 11287.3 11388.2 11414.9 11496.0 590.5 663.7 665.3 675.4 680.1 5176.2 5182.0 5323.5 5338.2 5465.6 93159.0 93339.0 94626.0 96666.0 99721.0 87236.0 91287.0 91825.0 92191.5 92399.0 76736.0 78311.6 78460.0 79059.0 82466.6 11892.8 12203.5 12254.5 12525.3 12536.6 36383.6 36415.5 38614.8 38898.4 40014.6 67364.7 68363.7 72417.0 72780.0 79723.0 18517.8 19232.5 19262.6 19310.0 19363.4 19889.0 20129.0 20430.0 21151.2 21334.5 18041.4 18443.2 18497.3 18499.2 18534.5 19030.6 19370.5 19523.2 19781.5 20489.0 17508.5 17680.9 18121.4 18321.7 18786.3 12521.7 12776.5 12822.2 12969.7 13476.0 13077.3 14553.8 14770.0 14800.3 14945.2 18:49:11 INFO - PID 3795 | -------- Summary: end -------- 18:49:11 INFO - PID 3795 | 18:49:12 INFO - TEST-INFO | 3795: exit 0 18:49:12 INFO - TEST-OK | dromaeo_css | took 1114268ms 18:49:12 INFO - TEST-START | kraken 18:49:12 INFO - Initialising browser for kraken test... 18:49:12 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41493/getInfo.html -profile /tmp/tmpE9D4hm/profile 18:49:12 INFO - TEST-INFO | started process 4167 (/builds/slave/test/build/application/firefox/firefox http://localhost:41493/getInfo.html) 18:49:18 INFO - TEST-INFO | 4167: exit 0 18:49:18 INFO - Browser initialized. 18:49:18 INFO - Running cycle 1/1 for kraken test... 18:49:18 INFO - TEST-INFO | started process 4392 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpE9D4hm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:49:19 INFO - PID 4392 | 18:49:19 INFO - PID 4392 | (/builds/slave/test/build/application/firefox/firefox:4447): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:49:19 INFO - PID 4392 | 18:49:29 INFO - PID 4392 | RSS: Main: 174391296 18:49:29 INFO - PID 4392 | 18:49:32 INFO - PID 4392 | Cycle 1(1): loaded http://localhost:41493/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41493/tests/kraken/driver.html?testName=audio-beat-detection) 18:49:33 INFO - PID 4392 | RSS: Main: 179179520 18:49:33 INFO - PID 4392 | 18:49:36 INFO - PID 4392 | Cycle 1(1): loaded http://localhost:41493/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41493/tests/kraken/driver.html?testName=audio-dft) 18:49:36 INFO - PID 4392 | RSS: Main: 180506624 18:49:36 INFO - PID 4392 | 18:49:39 INFO - PID 4392 | Cycle 1(1): loaded http://localhost:41493/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41493/tests/kraken/driver.html?testName=audio-fft) 18:49:40 INFO - PID 4392 | RSS: Main: 174673920 18:49:40 INFO - PID 4392 | 18:49:42 INFO - PID 4392 | Cycle 1(1): loaded http://localhost:41493/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41493/tests/kraken/driver.html?testName=audio-oscillator) 18:49:43 INFO - PID 4392 | RSS: Main: 175255552 18:49:43 INFO - PID 4392 | 18:49:45 INFO - PID 4392 | Cycle 1(1): loaded http://localhost:41493/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41493/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:49:46 INFO - PID 4392 | RSS: Main: 175529984 18:49:46 INFO - PID 4392 | 18:49:49 INFO - PID 4392 | Cycle 1(1): loaded http://localhost:41493/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41493/tests/kraken/driver.html?testName=imaging-darkroom) 18:49:50 INFO - PID 4392 | RSS: Main: 177909760 18:49:50 INFO - PID 4392 | 18:49:53 INFO - PID 4392 | Cycle 1(1): loaded http://localhost:41493/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41493/tests/kraken/driver.html?testName=imaging-desaturate) 18:49:54 INFO - PID 4392 | RSS: Main: 177422336 18:49:54 INFO - PID 4392 | 18:49:57 INFO - PID 4392 | Cycle 1(1): loaded http://localhost:41493/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41493/tests/kraken/driver.html?testName=json-parse-financial) 18:49:57 INFO - PID 4392 | RSS: Main: 178200576 18:49:57 INFO - PID 4392 | 18:49:59 INFO - PID 4392 | Cycle 1(1): loaded http://localhost:41493/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41493/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:50:00 INFO - PID 4392 | RSS: Main: 178286592 18:50:00 INFO - PID 4392 | 18:50:02 INFO - PID 4392 | Cycle 1(1): loaded http://localhost:41493/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41493/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:50:02 INFO - PID 4392 | RSS: Main: 175706112 18:50:02 INFO - PID 4392 | 18:50:04 INFO - PID 4392 | Cycle 1(1): loaded http://localhost:41493/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41493/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:50:05 INFO - PID 4392 | RSS: Main: 175529984 18:50:05 INFO - PID 4392 | 18:50:08 INFO - PID 4392 | Cycle 1(1): loaded http://localhost:41493/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41493/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:50:09 INFO - PID 4392 | RSS: Main: 175714304 18:50:09 INFO - PID 4392 | 18:50:12 INFO - PID 4392 | Cycle 1(1): loaded http://localhost:41493/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41493/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:50:13 INFO - PID 4392 | RSS: Main: 175624192 18:50:13 INFO - PID 4392 | 18:50:14 INFO - PID 4392 | Cycle 1(1): loaded http://localhost:41493/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41493/tests/kraken/driver.html?testName=ai-astar) 18:50:15 INFO - PID 4392 | RSS: Main: 177532928 18:50:15 INFO - PID 4392 | 18:50:15 INFO - PID 4392 | __start_tp_report 18:50:15 INFO - PID 4392 | _x_x_mozilla_page_load 18:50:15 INFO - PID 4392 | _x_x_mozilla_page_load_details 18:50:15 INFO - PID 4392 | |i|pagename|runs| 18:50:15 INFO - PID 4392 | |0;ai-astar;100;107;109;109;111;115;111;100;108;113 18:50:15 INFO - PID 4392 | |1;audio-beat-detection;149;118;150;179;119;147;169;120;187;125 18:50:15 INFO - PID 4392 | |2;audio-dft;172;163;169;162;165;169;166;168;165;164 18:50:15 INFO - PID 4392 | |3;audio-fft;75;77;76;188;75;79;74;87;97;76 18:50:15 INFO - PID 4392 | |4;audio-oscillator;79;79;133;79;86;78;124;81;79;129 18:50:15 INFO - PID 4392 | |5;imaging-gaussian-blur;114;114;121;114;113;122;119;122;114;117 18:50:15 INFO - PID 4392 | |6;imaging-darkroom;152;151;153;151;150;151;150;150;150;151 18:50:15 INFO - PID 4392 | |7;imaging-desaturate;99;98;94;99;95;95;94;94;94;95 18:50:15 INFO - PID 4392 | |8;json-parse-financial;56;55;56;56;55;64;57;57;58;57 18:50:15 INFO - PID 4392 | |9;json-stringify-tinderbox;59;55;54;62;53;55;54;54;55;55 18:50:15 INFO - PID 4392 | |10;stanford-crypto-aes;81;80;81;93;80;80;79;84;88;86 18:50:15 INFO - PID 4392 | |11;stanford-crypto-ccm;137;135;133;145;129;135;135;140;138;175 18:50:15 INFO - PID 4392 | |12;stanford-crypto-pbkdf2;190;187;183;187;199;200;183;185;180;191 18:50:15 INFO - PID 4392 | |13;stanford-crypto-sha256-iterative;67;70;71;75;72;71;66;66;67;79 18:50:15 INFO - PID 4392 | __end_tp_report 18:50:15 INFO - PID 4392 | __start_cc_report 18:50:15 INFO - PID 4392 | _x_x_mozilla_cycle_collect,958 18:50:15 INFO - PID 4392 | __end_cc_report 18:50:15 INFO - PID 4392 | __startTimestamp1501552215120__endTimestamp 18:50:15 INFO - PID 4392 | 18:50:15 INFO - PID 4392 | ------- Summary: start ------- 18:50:15 INFO - PID 4392 | Number of tests: 14 18:50:15 INFO - PID 4392 | 18:50:15 INFO - PID 4392 | [#0] ai-astar Cycles:10 Average:108.30 Median:110.00 stddev:4.97 (4.5%) stddev-sans-first:4.27 18:50:15 INFO - PID 4392 | Values: 100.0 107.0 109.0 109.0 111.0 115.0 111.0 100.0 108.0 113.0 18:50:15 INFO - PID 4392 | 18:50:15 INFO - PID 4392 | [#1] audio-beat-detection Cycles:10 Average:146.30 Median:149.50 stddev:25.72 (17.2%) stddev-sans-first:27.26 18:50:15 INFO - PID 4392 | Values: 149.0 118.0 150.0 179.0 119.0 147.0 169.0 120.0 187.0 125.0 18:50:15 INFO - PID 4392 | 18:50:15 INFO - PID 4392 | [#2] audio-dft Cycles:10 Average:166.30 Median:167.00 stddev:3.13 (1.9%) stddev-sans-first:2.55 18:50:15 INFO - PID 4392 | Values: 172.0 163.0 169.0 162.0 165.0 169.0 166.0 168.0 165.0 164.0 18:50:15 INFO - PID 4392 | 18:50:15 INFO - PID 4392 | [#3] audio-fft Cycles:10 Average:90.40 Median:76.50 stddev:35.04 (45.8%) stddev-sans-first:36.72 18:50:15 INFO - PID 4392 | Values: 75.0 77.0 76.0 188.0 75.0 79.0 74.0 87.0 97.0 76.0 18:50:15 INFO - PID 4392 | 18:50:15 INFO - PID 4392 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:79.00 stddev:23.64 (29.9%) stddev-sans-first:24.38 18:50:15 INFO - PID 4392 | Values: 79.0 79.0 133.0 79.0 86.0 78.0 124.0 81.0 79.0 129.0 18:50:15 INFO - PID 4392 | 18:50:15 INFO - PID 4392 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:118.00 stddev:3.68 (3.1%) stddev-sans-first:3.74 18:50:15 INFO - PID 4392 | Values: 114.0 114.0 121.0 114.0 113.0 122.0 119.0 122.0 114.0 117.0 18:50:15 INFO - PID 4392 | 18:50:15 INFO - PID 4392 | [#6] imaging-darkroom Cycles:10 Average:150.90 Median:151.00 stddev:0.99 (0.7%) stddev-sans-first:0.97 18:50:15 INFO - PID 4392 | Values: 152.0 151.0 153.0 151.0 150.0 151.0 150.0 150.0 150.0 151.0 18:50:15 INFO - PID 4392 | 18:50:15 INFO - PID 4392 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:95.00 stddev:2.11 (2.2%) stddev-sans-first:1.87 18:50:15 INFO - PID 4392 | Values: 99.0 98.0 94.0 99.0 95.0 95.0 94.0 94.0 94.0 95.0 18:50:15 INFO - PID 4392 | 18:50:15 INFO - PID 4392 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:57.00 stddev:2.60 (4.6%) stddev-sans-first:2.73 18:50:15 INFO - PID 4392 | Values: 56.0 55.0 56.0 56.0 55.0 64.0 57.0 57.0 58.0 57.0 18:50:15 INFO - PID 4392 | 18:50:15 INFO - PID 4392 | [#9] json-stringify-tinderbox Cycles:10 Average:55.60 Median:55.00 stddev:2.76 (5.0%) stddev-sans-first:2.64 18:50:15 INFO - PID 4392 | Values: 59.0 55.0 54.0 62.0 53.0 55.0 54.0 54.0 55.0 55.0 18:50:15 INFO - PID 4392 | 18:50:15 INFO - PID 4392 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:82.50 stddev:4.54 (5.5%) stddev-sans-first:4.75 18:50:15 INFO - PID 4392 | Values: 81.0 80.0 81.0 93.0 80.0 80.0 79.0 84.0 88.0 86.0 18:50:15 INFO - PID 4392 | 18:50:15 INFO - PID 4392 | [#11] stanford-crypto-ccm Cycles:10 Average:140.20 Median:137.50 stddev:12.94 (9.4%) stddev-sans-first:13.68 18:50:15 INFO - PID 4392 | Values: 137.0 135.0 133.0 145.0 129.0 135.0 135.0 140.0 138.0 175.0 18:50:15 INFO - PID 4392 | 18:50:15 INFO - PID 4392 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.50 Median:188.50 stddev:6.67 (3.5%) stddev-sans-first:7.05 18:50:15 INFO - PID 4392 | Values: 190.0 187.0 183.0 187.0 199.0 200.0 183.0 185.0 180.0 191.0 18:50:15 INFO - PID 4392 | 18:50:15 INFO - PID 4392 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:71.00 stddev:4.22 (5.9%) stddev-sans-first:4.29 18:50:15 INFO - PID 4392 | Values: 67.0 70.0 71.0 75.0 72.0 71.0 66.0 66.0 67.0 79.0 18:50:15 INFO - PID 4392 | -------- Summary: end -------- 18:50:15 INFO - PID 4392 | 18:50:15 INFO - PID 4392 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:50:15 INFO - TEST-INFO | 4392: exit 0 18:50:15 INFO - TEST-OK | kraken | took 63619ms 18:50:15 INFO - SUITE-END | took 1177s 18:50:16 INFO - Completed test suite (00:19:38) 18:50:16 INFO - javascript benchmark 18:50:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8545.386157578758, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3148.574942382779, 3174.333389988103, 3176.65056843747, 3179.4435973704603, 3251.3984912791134, 6116.877662446754, 6199.659018753967, 6219.844503887405, 6231.345708700584, 6238.812835614939, 6107.511399088074, 6227.221597300338, 6304.590201636902, 6320.209973753281, 6335.5565110442285, 5725.971370143144, 5731.111677689964, 5811.506021988136, 5826.737796799133, 5862.237909071836, 5628.380878103405, 5682.261306030231, 5772.595918285721, 5774.3936886626925, 5884.558658100659, 5665.716714164296, 5679.432056794329, 5696.51579615731, 5704.48659620634, 5706.486416222539, 5711.257536520267, 5719.771209151629, 5733.713314334308, 5742.9949758792045, 5764.625299355571, 5544.085225937715, 5699.458551437607, 5703.686297253649, 5736.139579063174, 5737.254156959611, 6085.208922840046, 6135.049067394561, 6140.109684095826, 6215.751369945242, 6216.0, 6579.210494740614, 6683.2648408675, 6685.097511835888, 6690.197176338822, 6723.260441351447, 6703.664816759191, 6731.394174524267, 6734.158230221223, 6737.292584278629, 6743.797686069377, 6157.261128664589, 6160.476359509447, 6226.5641405101205, 6234.968825155891, 6238.220222472146, 6817.522773405814, 6885.862282754317, 6900.102986611709, 6936.959456081528, 6959.338862808025, 6543.476521878333, 6567.605943643397, 6587.0, 6638.867222655616, 6669.299723529057, 6602.141721576164, 6674.666266686646, 6731.629760363227, 6803.727850886008, 6804.693788779417, 6549.115869357671, 6602.86794264112, 6680.866382672417, 6768.593884366954, 6768.76309329181, 6640.236372817188, 6648.335166483313, 6694.163229596301, 6727.192736871607, 6728.697208625624, 5201.193814958688, 5452.727363631802, 5484.314460692413, 5525.696086715189, 5532.6680399176175, 4653.930191047137, 4661.277501987197, 4696.0, 4714.504976977437, 4718.056388722201, 5670.149477578396, 5766.106253530709, 5769.480746732815, 5777.480026797609, 5793.362730099686, 6697.229818570927, 6714.496412769062, 6745.763898263635, 6779.830504237433, 6784.525083244125, 5826.82519524415, 5839.0, 5839.883202335929, 5840.707964601753, 5848.0, 5921.585489015813, 5977.043673012384, 6001.609895356788, 6015.0676645120075, 6018.067199584071, 6514.413702766774, 6515.8696826063215, 6598.274189839114, 6614.933850661528, 6616.106825578581, 6637.070810086495, 6737.79786606383, 6743.865122697421, 6838.829029274308, 6841.897371539331, 6516.804495865132, 6518.250401203923, 6533.803985880431, 6538.280789113289, 6538.705758240986, 6518.543701941008, 6570.080188773671, 6588.5058620604605, 6593.3736295051895, 6611.636360000054, 6377.649229292433, 6769.864602707819, 6776.728930842711, 6815.045893575003, 6818.0, 6512.804615861532, 6522.412982831568, 6529.249136349381, 6583.769568065095, 6591.835204119935, 6570.901436478361, 6602.273749887605, 6603.80188594343, 6609.867802644017, 6615.966920165369, 6455.999320105391, 6460.483161347175, 6549.345065493412, 6550.639714815731, 6559.934400655932, 6367.490600751836, 6577.374901443557, 6640.701168447335, 6679.499037572299, 6686.933130668631, 6229.875402492015, 6268.0, 6356.968215158895, 6363.5227357947415, 6366.4588509977175], "value": 6122.442570895095, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.1871035412815, 821.5809936932163, 861.3410740783299, 882.7572417585163, 888.458040595237, 40180.0, 41015.79492102543, 41022.7948860256, 41051.79474102629, 41058.0, 37415.62584374155, 37541.812290938506, 37789.62210377895, 37836.62163378365, 37899.43150852739, 5070.08738427083, 5239.685618862862, 5608.074667679828, 5659.207710920454, 5682.971585142069, 6727.260001399842, 6796.388325050744, 6821.045053692487, 6825.283345248752, 6830.556013859084, 6812.216595091553, 7399.70401183952, 7657.387409007266, 7659.119201291839, 7665.8850117248285, 7577.16651168371, 7634.465587408884, 7634.923650763504, 7659.540427574363, 7661.42539309554, 7464.888026679604, 7493.437992150611, 7496.250374962515, 7553.584552849591, 7557.357624601916, 37846.0, 37997.810010950045, 38087.61912380868, 38108.42837357442, 38125.23749524994, 3496.0385893879134, 3534.3108093921696, 3535.5580552430947, 3535.893923182283, 3539.0, 30909.845450772827, 31082.844585776926, 31097.8445107773, 31106.377872442652, 31142.844285778425, 4751.764541219273, 4761.952380476186, 4782.30656554801, 4817.988222473285, 4847.878803029917, 211830.0, 220510.0, 221682.0, 222375.0, 223246.99999999837, 47621.52378476171, 47820.521794782304, 48191.27713084307, 48383.274250886265, 48432.515674843504, 49451.0, 52892.0, 53280.73359633177, 53413.198802018, 53509.0, 16188.59528511773, 16302.510924672279, 16330.265138068815, 16333.0, 16345.101019443808, 13436.731265374638, 13493.257870817002, 13505.662358441117, 13556.389962451713, 13624.250666213453, 14394.280285985868, 14524.709505809824, 14748.410063597335, 14762.940668116842, 14891.925540372229, 5876.941230587725, 5922.555808314393, 5924.6149000314845, 5945.881082378415, 6028.0, 966.5650457294246, 975.4878688688495, 978.7063880835863, 995.998043931688, 1011.1657882247175, 27763.583546246824, 27949.301267468476, 28920.566191507143, 29058.128256152348, 29101.563476547868, 1031.7111208861531, 1051.3429106808244, 1071.6463567022863, 1073.978520429587, 1096.9725756856142, 11912.88087119135, 12014.579489717818, 12231.633051008484, 12244.81632775509, 12375.75248495043, 223452.0, 224321.0, 225188.0, 226552.0, 226986.0, 44293.0, 45306.0, 52390.21414678783, 52409.73795131076, 52413.73793131086, 1435.9210243436503, 1443.5380678183092, 1444.588292336679, 1445.7253121906804, 1464.8095747552964, 26500.542470163557, 27394.041208558403, 27412.862935685192, 27443.86278068597, 28198.577021345107, 50529.0, 50858.745706271235, 51018.74490627523, 51038.0, 51102.74448627882, 201713.0, 207976.0, 208412.0, 209655.0, 210996.0, 34962.65037349696, 35128.82435587806, 35144.64855351414, 35471.82264088663, 35584.46623300704, 41353.172936541705, 43315.56684433115, 43556.782216088715, 44174.779126104164, 46001.53998459972, 30059.3988120232, 30583.847080765343, 32270.35459290848, 32620.673793261765, 33108.0, 30973.845130774203, 31534.369312613162, 32134.35731285408, 32152.19619509531, 32527.837360813046, 34791.13022174466, 36865.815670921475, 36868.0, 36951.0, 37849.62150378461, 31064.22339441532, 32322.515162273055, 33192.502112467846, 33331.666683332856, 33816.3236735259, 23362.415939601648, 24284.27147185587, 24379.634305485786, 24677.753222467545, 25154.496910062062], "value": 17259.328948536626, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.4926864359363, 581.7934633205215, 584.8567101060238, 593.8990371636821, 604.6372176693983, 22019.779802201974, 22877.542449151006, 23010.999999999978, 23022.65466018007, 23030.76969230307, 25750.0, 27095.593566096526, 28394.858025709895, 28492.43015139696, 28803.567946480765, 6862.6225557594435, 7048.0, 7051.083359163302, 7056.08270924779, 7069.717211311541, 4004.379321205218, 4240.766757828318, 6912.274211207826, 6980.546264492792, 6982.266861979495, 6587.407133357997, 6932.306769323078, 6932.930670693304, 6953.687084081229, 6954.0, 6936.271691472373, 6939.132608423947, 6948.6525673716615, 6949.895751563706, 6955.234924158339, 6793.320667933217, 6794.626295553742, 6795.456363490909, 6827.21487028993, 6830.863382732317, 30422.847885760428, 31508.0, 31867.0, 32682.182945426317, 33077.17307067318, 1883.8587105967108, 1939.9806001939942, 1940.0590713503939, 1940.1657287366427, 1949.8245157935712, 19937.501562460908, 20455.89772051145, 20545.383638490868, 20592.27927022547, 20649.0, 2360.2977667493797, 2402.1232250228713, 2404.5671779079757, 2408.1330720940446, 2495.8502489850666, 140700.0, 141243.29378353147, 142418.0, 142584.287078565, 143694.99999999895, 19894.60210795776, 20838.479038024172, 22159.889200553895, 22201.888990554944, 22502.887485562467, 44202.55797442049, 45004.54995450069, 45059.0, 45289.09421811545, 45410.77294613506, 11850.170488065925, 11884.702882427835, 11913.702157446132, 12048.75902481963, 12159.0, 9957.755280589927, 10008.44953527563, 10046.497675116216, 10097.091261786327, 10114.393136411838, 8965.641374345083, 9518.904810951803, 11137.208651501336, 12062.215955962834, 12097.395130243453, 2842.0300785679015, 2845.900393486218, 2848.159792861111, 2867.4551835151256, 2947.9465734614905, 934.359963425056, 938.0853667674099, 956.5743244256239, 956.7466618729487, 982.2093214962024, 19741.309054183323, 20030.99845007773, 23763.643545346833, 25365.61951570728, 25584.8720756395, 994.547480896205, 995.5221493683073, 1008.6721815410021, 1024.134606257711, 1035.4615599888166, 11004.22970392081, 11106.111511079094, 11177.776444471065, 11291.209615327012, 11446.828297575543, 495229.0, 503329.0, 508521.0, 537357.0, 538759.0, 117791.0, 123170.0, 123323.38338308252, 123575.0, 124736.37631811782, 1206.7284860906402, 1237.5730373021293, 1271.8600953895063, 1281.628327784932, 1284.858665546789, 19123.521911952314, 20801.58396832085, 20935.476613084797, 21775.346739597204, 21907.12371505122, 54526.0, 54638.45361546334, 56107.43892561022, 56980.43019569751, 57093.42906571047, 64498.35501645111, 64807.35192648013, 65102.0, 65544.34455655573, 65773.67113164403, 12609.369531523203, 14350.712985740018, 14819.555413337617, 15253.694926101638, 15319.923400382926, 17961.73057404166, 19506.512337191685, 19605.411837644893, 24219.273421797374, 24497.632535511624, 18529.72205416947, 18843.057847107317, 19607.215711371955, 19619.803801961796, 19813.405597832087, 16349.67300654004, 16613.0862802547, 16838.915805420893, 17226.310947561957, 17766.46700598984, 18858.24567017304, 20064.49838754043, 20294.898525507277, 20324.79675203229, 20344.18623255053, 14986.47547335838, 15153.242337883283, 15221.923890380478, 15229.0, 15239.771403428736, 18604.81395186031, 18855.057247137862, 18963.241470341032, 18997.90501047486, 19649.410517683922], "value": 12770.207900620533, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1376.3668712392298, 1432.7277817214729, 1450.369089446091, 1454.556360310105, 1464.721702876454, 13762.862371376295, 14482.5655230343, 14484.0, 14500.564983050524, 14503.854961450383, 13061.542846000391, 14200.0, 14231.644208894757, 14253.714925701506, 14329.641758956031, 2640.0627777139125, 2716.334498047981, 2726.9045583404586, 2730.5358089124798, 2741.643586333774, 2666.706662267149, 2686.6104414859833, 2696.9595456068173, 2761.047438633678, 2810.12886005338, 2760.4341110072487, 2790.1350581319857, 2791.3719413132085, 2793.0, 2794.0, 2691.6097165910933, 2708.8510131942635, 2709.91870243893, 2712.2541301142146, 2720.2791260315935, 2638.4854953284116, 2644.5239856825765, 2673.8128331016837, 2675.919722408331, 2688.7983401244983, 13798.862011379859, 14137.858621413758, 14205.502807401692, 14222.715545689029, 14225.502107426191, 7594.354479869217, 7907.209279072104, 7958.323542498894, 8002.119766825645, 8016.559089250089, 5169.65644810114, 5345.866353341159, 5454.018276710191, 5473.589480788957, 5488.368837583689, 1827.7960690412133, 1854.666160091183, 1865.2259312385345, 1911.1877452082808, 1932.8163824436656, 23206.883965580066, 23355.53288934229, 23452.179173729008, 23470.530589388287, 23475.647865282033, 6983.755568555076, 7145.163132825785, 7246.347828695442, 7262.039249402737, 7303.926960730325, 15740.68518629644, 15969.600759981093, 15977.520674379788, 15986.520404387888, 15989.520314390587, 7652.502587331805, 7806.648700808477, 7837.0, 7857.410694197958, 7880.921190788019, 6877.690503927335, 7036.788896333118, 7047.224805271416, 7170.820729481805, 7270.818229544304, 5018.397792264952, 5204.7918083276245, 5213.322268105122, 5216.374035115735, 5256.500632439912, 1370.4792178971927, 1431.2772050114625, 1466.2668665667165, 1492.2091291615461, 1504.586238784343, 836.7866194120583, 838.610046328448, 860.638531816626, 881.8985816631169, 882.6557642519423, 7436.81302240219, 7514.849703005909, 7539.246075392527, 7574.886376704354, 7630.38956883448, 919.2324409118461, 919.8344298026286, 956.4596003258137, 960.3565611040619, 981.7398389426808, 4411.558844115562, 4488.506264310923, 4713.835015774432, 4780.306855505932, 4884.780184891689, 18604.627907442045, 19176.616467670567, 19181.904090479456, 19369.225230990603, 19390.224391024483, 18491.167897444648, 19537.511562210773, 19636.509087272647, 19717.50706232327, 19790.60418791616, 11083.778324433628, 11217.326960382403, 11239.66281011571, 11254.887451125547, 11318.490667919963, 6551.836204094936, 7239.276072392719, 7340.0, 7502.699892004259, 7541.849163016818, 16359.754603681193, 16795.91602041982, 16858.494245172664, 16979.745303820204, 17210.225539850486, 16777.916110419857, 17178.22697978569, 17347.739783902998, 17359.2188351527, 17488.737668934722, 10404.167666586503, 10675.679729608124, 10679.733006674895, 10681.412522311348, 10704.411257380918, 12895.677608059874, 13158.0, 13196.34018299062, 13290.0, 13326.200427974352, 9941.353812002197, 10295.897041029793, 10917.672469825919, 10961.945190273998, 11097.944510277397, 10061.044200800912, 10355.637552685619, 10382.169426446017, 10556.261061725912, 10591.4704264788, 8997.865032024656, 9050.773730656787, 9054.230390416888, 9082.228010619172, 9114.31642626819, 6316.273628532777, 6336.873262534631, 6420.775272865428, 6490.123833282447, 6639.103720997604, 6179.474744646755, 6231.906521402273, 6264.342244064308, 6581.0, 6721.932780672326], "value": 6347.6418279081645, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.39561734683696, 499.44810983862806, 519.0553193188393, 529.1084373365218, 531.8032328038626, 6181.258249010125, 6207.472364848988, 6283.183186185795, 6288.4907622170695, 6308.5899416537895, 5892.9705351473185, 6085.726142323595, 6112.449879510843, 6126.468382904274, 6133.080037994303, 4871.5615594596475, 4919.852404427864, 4946.438564233764, 4958.206686930092, 4979.502049795028, 4852.393450818648, 4876.756162191894, 4900.289458028584, 4939.382577177853, 4950.207966725325, 4855.295982082596, 4882.829100981467, 4910.656254062217, 4913.778879950411, 4977.129002424579, 4836.138086836558, 4879.365682461257, 4918.458969513368, 4943.283223932528, 4955.60355171589, 4434.889127771799, 4447.2439685253585, 4453.866384008485, 4520.547945205453, 4535.160995215896, 6136.539759518009, 6155.384461553854, 6171.660558669271, 6174.536909731788, 6184.783532576384, 5672.631278966854, 5702.54379649627, 5733.569982251348, 5739.856503587402, 5788.842231553664, 6454.548181627288, 6504.77233296837, 6615.221606925103, 6620.470362370998, 6635.93364066358, 4914.606831453475, 4918.754062296906, 4925.088858561177, 4931.161702510582, 4935.0, 6940.340667636567, 7013.2986701329455, 7013.719451221945, 7048.330408611174, 7066.858662826766, 4993.325901003391, 4997.225430058346, 5011.6241281904, 5023.748812559357, 5050.621203409759, 5067.87330316745, 5103.285540024325, 5135.537801597874, 5144.408393034774, 5148.613853960968, 5630.774769009194, 5738.626989245686, 5738.655680659173, 5755.453231942959, 5755.769769209269, 6449.3873082057125, 6551.541392102602, 6573.816712991707, 6595.570303442183, 6624.503162262849, 1661.6925868714327, 1663.6256842210405, 1685.3679870048732, 1734.5750291178742, 1749.5713550180033, 1195.4560674892903, 1211.236920739928, 1217.634709587138, 1224.6815827884814, 1239.3617287097152, 5537.446255374431, 5620.85947851307, 5636.689982050944, 5660.811229641821, 5683.948469533149, 6480.740770369227, 6564.4420224281475, 6591.6374599397495, 6629.442081110935, 6690.4170643108655, 5635.633683810539, 5654.208410822488, 5728.656280623176, 5737.3688894221605, 5752.511036561856, 3444.4144495435835, 3549.4143466328283, 3567.340042092221, 3580.480830279595, 3618.3667858124722, 6342.270638876496, 6445.6454894981225, 6477.838054048592, 6485.77299794505, 6532.346765323525, 6813.26261803237, 6875.690593923286, 6911.170659520789, 6936.687849046805, 6995.475339349569, 6405.327440618761, 6454.2577603575255, 6462.935370646327, 6523.575967562188, 6544.607323560602, 6697.631630260187, 6761.695723692544, 6776.577828196729, 6811.352921472452, 6821.522493425413, 6619.867602647823, 6625.972974189009, 6658.866822663423, 6692.6319052450635, 6717.227518835396, 6518.24007517958, 6519.348065193442, 6523.6738163092605, 6526.4452521536205, 6584.473242140713, 5031.446540880427, 5034.59723222134, 5041.924371134509, 5051.343325367826, 5071.225071225042, 6393.6663117962535, 6714.261431242657, 6743.358586363309, 6749.156355455568, 6790.72837086511, 6180.81457556274, 6365.522585806176, 6407.359264073555, 6429.58207716497, 6451.3548645135115, 6427.071878436357, 6450.580712253689, 6461.353864613501, 6491.383318584727, 6519.0, 6339.144215530844, 6343.5876668016435, 6344.238691357066, 6396.904046439214, 6427.614343139427, 6473.352664733489, 6517.706703198272, 6523.67381630907, 6571.474282057328, 6582.736690532325, 6285.308616052139, 6287.233617208413, 6346.333634968262, 6357.3006969234275, 6390.4248617624635], "value": 5038.13509622543, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [893.6916763716515, 915.4004127296621, 924.9630014799409, 929.1776777551867, 936.1574582875407, 17776.288948442063, 18549.443516694504, 18558.628827423443, 18589.907050464728, 18639.813601863978, 17533.298668053263, 17551.12244387782, 17558.0, 19409.61180776387, 21784.673229901564, 1024.3444195714749, 1039.3088596083596, 1040.8334666453368, 1049.6536143072806, 1049.7008352619466, 1049.7375656085978, 1058.1059005140683, 1060.6870973062964, 1061.1086687182765, 1061.941593212373, 1042.525650828871, 1076.0, 1079.4494807648116, 1079.865016872891, 1080.287010573022, 1063.2769716592713, 1064.4837253931837, 1068.6046162919693, 1069.0485467933545, 1070.0102405275168, 783.2519943453988, 783.3772151139857, 788.0740130346877, 792.4492477727981, 793.2939683681528, 18526.722099168524, 19033.0, 19148.0, 19197.904010479997, 19749.308774192978, 19850.1067451965, 20277.493062673402, 20668.483287917923, 20750.169993200252, 20817.791822081886, 15789.131597762118, 16004.679906401923, 16024.919875400665, 16200.0, 16200.189990500545, 3524.753267271292, 3569.7679650822615, 3571.6964058055096, 3572.1962558424407, 3578.624244454321, 119989.0, 121662.0, 121854.0, 122658.0, 124043.37978310141, 16161.757573636405, 16167.0, 16230.431934882223, 16234.431794887123, 16273.267702953395, 29033.419331613248, 30354.392912141633, 30575.69424305773, 30581.38837223243, 30624.08127756171, 8471.576421178916, 8481.0, 8529.402941794015, 8615.612297446505, 8653.350998675125, 4198.6221240088535, 4302.569743025735, 4314.288142456522, 4349.804258808361, 4352.042550638855, 5263.447338029452, 5388.865278368072, 5408.675479471166, 5456.645318054314, 5506.476884695948, 1002.183220675162, 1019.9953046249439, 1034.7826956339254, 1038.7662775875367, 1039.293280569205, 626.4768917953469, 646.6411141816243, 663.7577284291199, 666.6966530228734, 678.2166597579803, 10386.844197337046, 11287.322760634388, 11388.20282580228, 11414.94292528532, 11496.0, 590.4632417208912, 663.6913835066621, 665.3080795972231, 675.4292622733782, 680.0547239337325, 5176.197689358156, 5181.989512045115, 5323.520883120537, 5338.225957236256, 5465.617406781566, 93159.0, 93339.0, 94626.0, 96666.0, 99721.0, 87236.0, 91287.0, 91825.0, 92191.53904230436, 92399.0, 76736.0, 78311.60844195857, 78460.0, 79059.0, 82466.58766706249, 11892.76214475723, 12203.511859525519, 12254.509819607116, 12525.311107889154, 12536.561220357244, 36383.636163638024, 36415.45376819403, 38614.80909916734, 38898.416523752734, 40014.599854001084, 67364.6631766838, 68363.65818170877, 72417.0, 72780.0, 79723.0, 18517.81482185215, 19232.519187020436, 19262.614747705247, 19310.034498275312, 19363.4190974271, 19889.0, 20129.0, 20430.0, 21151.153953841673, 21334.466638334165, 18041.368552100612, 18443.17005734772, 18497.260109596005, 18499.167537461115, 18534.536636584195, 19030.61938761245, 19370.515737106685, 19523.219071236992, 19781.505462363555, 20489.0, 17508.47474575765, 17680.91159544194, 18121.36575219861, 18321.725174122133, 18786.342478013205, 12521.749565008831, 12776.48894044265, 12822.166559173624, 12969.675758106123, 13476.0, 13077.280749558486, 14553.781693274397, 14770.0, 14800.259987000392, 14945.178015209269], "value": 9022.635659511403, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1564.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 107.0, 109.0, 109.0, 111.0, 115.0, 111.0, 100.0, 108.0, 113.0], "value": 108.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 118.0, 150.0, 179.0, 119.0, 147.0, 169.0, 120.0, 187.0, 125.0], "value": 146.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 163.0, 169.0, 162.0, 165.0, 169.0, 166.0, 168.0, 165.0, 164.0], "value": 166.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 76.0, 188.0, 75.0, 79.0, 74.0, 87.0, 97.0, 76.0], "value": 90.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 133.0, 79.0, 86.0, 78.0, 124.0, 81.0, 79.0, 129.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 121.0, 114.0, 113.0, 122.0, 119.0, 122.0, 114.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 153.0, 151.0, 150.0, 151.0, 150.0, 150.0, 150.0, 151.0], "value": 150.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 98.0, 94.0, 99.0, 95.0, 95.0, 94.0, 94.0, 94.0, 95.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 56.0, 55.0, 64.0, 57.0, 57.0, 58.0, 57.0], "value": 57.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 55.0, 54.0, 62.0, 53.0, 55.0, 54.0, 54.0, 55.0, 55.0], "value": 55.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 81.0, 93.0, 80.0, 80.0, 79.0, 84.0, 88.0, 86.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 135.0, 133.0, 145.0, 129.0, 135.0, 135.0, 140.0, 138.0, 175.0], "value": 140.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 187.0, 183.0, 187.0, 199.0, 200.0, 183.0, 185.0, 180.0, 191.0], "value": 188.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 71.0, 75.0, 72.0, 71.0, 66.0, 66.0, 67.0, 79.0], "value": 70.4, "unit": "score"}]}]} 18:50:16 INFO - Return code: 0 18:50:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:50:16 INFO - # TBPL SUCCESS # 18:50:16 INFO - Running post-action listener: _package_coverage_data 18:50:16 INFO - Running post-action listener: _resource_record_post_action 18:50:16 INFO - [mozharness: 2017-08-01 01:50:16.398710Z] Finished run-tests step (success) 18:50:16 INFO - Running post-run listener: _resource_record_post_run 18:50:16 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 16384; Write bytes: 432381952; Read time: 232; Write time: 278684 18:50:16 INFO - TinderboxPrint: CPU usage
13.2% 18:50:16 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 232 18:50:16 INFO - TinderboxPrint: I/O write bytes / time
432,381,952 / 278,684 18:50:16 INFO - TinderboxPrint: CPU idle
8,284.9 (86.8%) 18:50:16 INFO - TinderboxPrint: CPU user
1,207.2 (12.6%) 18:50:16 INFO - TinderboxPrint: Swap in / out
0 / 0 18:50:16 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 8192; Write bytes: 109604864; Read time: 36; Write time: 93472 18:50:16 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:50:16 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 4096; Write bytes: 322777088; Read time: 180; Write time: 185212 18:50:17 INFO - Running post-run listener: _upload_blobber_files 18:50:17 INFO - Blob upload gear active. 18:50:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:50:17 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 18:50:17 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:50:17 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:50:17 INFO - (blobuploader) - INFO - Open directory for files ... 18:50:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:50:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:50:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:18 INFO - (blobuploader) - INFO - Done attempting. 18:50:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:50:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:50:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:24 INFO - (blobuploader) - INFO - Done attempting. 18:50:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:50:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:50:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:26 INFO - (blobuploader) - INFO - Done attempting. 18:50:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:50:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:50:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:27 INFO - (blobuploader) - INFO - Done attempting. 18:50:27 INFO - (blobuploader) - INFO - Iteration through files over. 18:50:27 INFO - Return code: 0 18:50:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:50:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:50:27 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9800ce257fc33f524c8d9989987f6f5f6f7e56e7ee5f7891ca4c66a9baff4b47ba7b2e1a2deb67b9ed5ac4de9ae470f62ba52c57e80b69b3415dacc42c2c033e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ca518f7f157dbaedadb1f525164ae0dda918f6c888f122e8a3177385a1457006bb8a2c42074792eedb670f979495fd6b9b1cc3ffadaf96de311312b81442dcc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfb0320666e33bdc5f95611fdadca2fa385def8b4f04c5e26ebe4499019a7193304b2661e3aaa3a2377c6b57b4ee6757812c63fa53a9439b18b2310130ee7ef3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91e02b7807ec339414b14ec3e8b121b20538b9d85923955c7c7b95256ad94cfe373fac7248e524c88d091daec126567cb2f3dc0c72b99d2e1f51b7ce39d6f9ee"} 18:50:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:50:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:50:27 INFO - Contents: 18:50:27 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9800ce257fc33f524c8d9989987f6f5f6f7e56e7ee5f7891ca4c66a9baff4b47ba7b2e1a2deb67b9ed5ac4de9ae470f62ba52c57e80b69b3415dacc42c2c033e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ca518f7f157dbaedadb1f525164ae0dda918f6c888f122e8a3177385a1457006bb8a2c42074792eedb670f979495fd6b9b1cc3ffadaf96de311312b81442dcc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfb0320666e33bdc5f95611fdadca2fa385def8b4f04c5e26ebe4499019a7193304b2661e3aaa3a2377c6b57b4ee6757812c63fa53a9439b18b2310130ee7ef3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91e02b7807ec339414b14ec3e8b121b20538b9d85923955c7c7b95256ad94cfe373fac7248e524c88d091daec126567cb2f3dc0c72b99d2e1f51b7ce39d6f9ee"} 18:50:27 INFO - Running post-run listener: copy_logs_to_upload_dir 18:50:27 INFO - Copying logs to upload dir... 18:50:27 INFO - mkdir: /builds/slave/test/build/upload/logs 18:50:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.109703 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-07-31 18:50:26.828226) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-31 18:50:26.837233) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9800ce257fc33f524c8d9989987f6f5f6f7e56e7ee5f7891ca4c66a9baff4b47ba7b2e1a2deb67b9ed5ac4de9ae470f62ba52c57e80b69b3415dacc42c2c033e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ca518f7f157dbaedadb1f525164ae0dda918f6c888f122e8a3177385a1457006bb8a2c42074792eedb670f979495fd6b9b1cc3ffadaf96de311312b81442dcc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfb0320666e33bdc5f95611fdadca2fa385def8b4f04c5e26ebe4499019a7193304b2661e3aaa3a2377c6b57b4ee6757812c63fa53a9439b18b2310130ee7ef3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91e02b7807ec339414b14ec3e8b121b20538b9d85923955c7c7b95256ad94cfe373fac7248e524c88d091daec126567cb2f3dc0c72b99d2e1f51b7ce39d6f9ee"} build_url:https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011281 build_url: 'https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9800ce257fc33f524c8d9989987f6f5f6f7e56e7ee5f7891ca4c66a9baff4b47ba7b2e1a2deb67b9ed5ac4de9ae470f62ba52c57e80b69b3415dacc42c2c033e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ca518f7f157dbaedadb1f525164ae0dda918f6c888f122e8a3177385a1457006bb8a2c42074792eedb670f979495fd6b9b1cc3ffadaf96de311312b81442dcc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfb0320666e33bdc5f95611fdadca2fa385def8b4f04c5e26ebe4499019a7193304b2661e3aaa3a2377c6b57b4ee6757812c63fa53a9439b18b2310130ee7ef3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91e02b7807ec339414b14ec3e8b121b20538b9d85923955c7c7b95256ad94cfe373fac7248e524c88d091daec126567cb2f3dc0c72b99d2e1f51b7ce39d6f9ee"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-31 18:50:26.861394) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 18:50:26.861708) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1501550303.884787-915589201 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004451 ========= master_lag: 0.13 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 18:50:26.991848) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-31 18:50:26.992161) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-31 18:50:26.992448) ========= ========= Total master_lag: 0.26 =========