builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-021 starttime: 1502559482.72 results: success (0) revision: 1f363abd841d1ad9fb8ac1e67ea7c8636895d766 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-12 10:38:02.718985) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-12 10:38:02.719459) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-12 10:38:02.719755) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-12 10:38:02.762954) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 10:38:02.763244) ========= 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=df17445c3590ff7897617a0e00000225-1502557983.913160-761455044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-12 10:38:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.7M=0s 2017-08-12 10:38:02 (46.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.220834 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 10:38:03.023401) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 10:38:03.023720) ========= 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=df17445c3590ff7897617a0e00000225-1502557983.913160-761455044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018412 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 10:38:03.076496) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-12 10:38:03.076799) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1f363abd841d1ad9fb8ac1e67ea7c8636895d766 --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 1f363abd841d1ad9fb8ac1e67ea7c8636895d766 --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=df17445c3590ff7897617a0e00000225-1502557983.913160-761455044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-12 10:38:02,989 truncating revision to first 12 chars 2017-08-12 10:38:02,989 Setting DEBUG logging. 2017-08-12 10:38:02,989 attempt 1/10 2017-08-12 10:38:02,989 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1f363abd841d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-12 10:38:03,597 attempt 1/10 2017-08-12 10:38:04,233 current task status: no status available at this point. state: STARTED 2017-08-12 10:38:04,233 sleeping for 10.00s (attempt 1/10) 2017-08-12 10:38:14,244 attempt 2/10 2017-08-12 10:38:14,562 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-12 10:38:14,790 unpacking tar archive at: mozilla-central-1f363abd841d/testing/mozharness/ program finished with exit code 0 elapsedTime=12.115350 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-12 10:38:15.204519) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-12 10:38:15.204810) ========= script_repo_revision: 1f363abd841d1ad9fb8ac1e67ea7c8636895d766 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-12 10:38:15.208225) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-12 10:38:15.208510) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-12 10:38:15.218393) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-08-12 10:38:15.218667) ========= /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=df17445c3590ff7897617a0e00000225-1502557983.913160-761455044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:38:15 INFO - MultiFileLogger online at 20170812 10:38:15 in /builds/slave/test 10:38:15 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 10:38:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:38:15 INFO - {'append_to_log': False, 10:38:15 INFO - 'base_work_dir': '/builds/slave/test', 10:38:15 INFO - 'blob_upload_branch': 'Firefox', 10:38:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:38:15 INFO - 'branch': 'Firefox', 10:38:15 INFO - 'buildbot_json_path': 'buildprops.json', 10:38:15 INFO - 'code_coverage': False, 10:38:15 INFO - 'config_files': ('talos/linux_config.py',), 10:38:15 INFO - 'default_actions': ('clobber', 10:38:15 INFO - 'read-buildbot-config', 10:38:15 INFO - 'download-and-extract', 10:38:15 INFO - 'populate-webroot', 10:38:15 INFO - 'create-virtualenv', 10:38:15 INFO - 'install', 10:38:15 INFO - 'setup-mitmproxy', 10:38:15 INFO - 'run-tests'), 10:38:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:38:15 INFO - 'disable_ccov_upload': False, 10:38:15 INFO - 'download_minidump_stackwalk': True, 10:38:15 INFO - 'download_symbols': 'ondemand', 10:38:15 INFO - 'enable_stylo': False, 10:38:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:38:15 INFO - 'tooltool.py': '/tools/tooltool.py', 10:38:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:38:15 INFO - '/tools/misc-python/virtualenv.py')}, 10:38:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:38:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:38:15 INFO - 'gecko_profile': False, 10:38:15 INFO - 'gecko_profile_interval': 0, 10:38:15 INFO - 'installer_path': 'installer.exe', 10:38:15 INFO - 'log_level': 'info', 10:38:15 INFO - 'log_name': 'talos', 10:38:15 INFO - 'log_to_console': True, 10:38:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:38:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:38:15 INFO - 'opt_config_files': (), 10:38:15 INFO - 'pip_index': False, 10:38:15 INFO - 'suite': 'dromaeojs-e10s', 10:38:15 INFO - 'system_bits': '32', 10:38:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:38:15 INFO - 'title': 'talos-linux64-ix-021', 10:38:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:38:15 INFO - 'use_talos_json': True, 10:38:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:38:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:38:15 INFO - 'work_dir': 'build'} 10:38:15 INFO - [mozharness: 2017-08-12 17:38:15.309087Z] Running clobber step. 10:38:15 INFO - Running pre-action listener: _resource_record_pre_action 10:38:15 INFO - Running main action method: clobber 10:38:15 INFO - rmtree: /builds/slave/test/build 10:38:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:38:16 INFO - Running post-action listener: _resource_record_post_action 10:38:16 INFO - [mozharness: 2017-08-12 17:38:16.301275Z] Finished clobber step (success) 10:38:16 INFO - [mozharness: 2017-08-12 17:38:16.301396Z] Running read-buildbot-config step. 10:38:16 INFO - Running pre-action listener: _resource_record_pre_action 10:38:16 INFO - Running main action method: read_buildbot_config 10:38:16 INFO - Using buildbot properties: 10:38:16 INFO - { 10:38:16 INFO - "project": "", 10:38:16 INFO - "product": "firefox", 10:38:16 INFO - "who": "ffxbld@noreply.mozilla.org", 10:38:16 INFO - "installer_path": "public/build/target.tar.bz2", 10:38:16 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 10:38:16 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 10:38:16 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 10:38:16 INFO - "stage_platform": "linux64-stylo", 10:38:16 INFO - "basedir": "/builds/slave/test", 10:38:16 INFO - "buildnumber": 24, 10:38:16 INFO - "platform": "ubuntu64_hw_stylo", 10:38:16 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 10:38:16 INFO - "slavebuilddir": "test", 10:38:16 INFO - "taskId": "fH_kdXcNRV-ClFmtsJgR_Q", 10:38:16 INFO - "branch": "mozilla-central", 10:38:16 INFO - "script_repo_revision": "production", 10:38:16 INFO - "revision": "1f363abd841d1ad9fb8ac1e67ea7c8636895d766", 10:38:16 INFO - "slavename": "talos-linux64-ix-021", 10:38:16 INFO - "repo_path": "mozilla-central" 10:38:16 INFO - } 10:38:16 INFO - Finding installer, test and symbols from parent task. 10:38:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fH_kdXcNRV-ClFmtsJgR_Q'}, attempt #1 10:38:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fH_kdXcNRV-ClFmtsJgR_Q'}, attempt #1 10:38:17 INFO - Task dependencies: equmlGMdQTeuA3YD9CXIvQ 10:38:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ'}, attempt #1 10:38:17 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.tar.bz2 10:38:17 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.test_packages.json 10:38:17 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.crashreporter-symbols.zip 10:38:17 INFO - Running post-action listener: _resource_record_post_action 10:38:17 INFO - [mozharness: 2017-08-12 17:38:17.808207Z] Finished read-buildbot-config step (success) 10:38:17 INFO - [mozharness: 2017-08-12 17:38:17.808427Z] Running download-and-extract step. 10:38:17 INFO - Running pre-action listener: _resource_record_pre_action 10:38:17 INFO - Running main action method: download_and_extract 10:38:17 INFO - mkdir: /builds/slave/test/build/tests 10:38:17 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')]} 10:38:17 INFO - trying https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.test_packages.json 10:38:17 INFO - Downloading https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 10:38:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 10:38:18 INFO - Downloaded 989 bytes. 10:38:18 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 10:38:18 INFO - Using the following test package requirements: 10:38:18 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 10:38:18 INFO - u'common': [u'target.common.tests.zip'], 10:38:18 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 10:38:18 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 10:38:18 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 10:38:18 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 10:38:18 INFO - u'mozbase': [u'target.common.tests.zip'], 10:38:18 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 10:38:18 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 10:38:18 INFO - u'web-platform': [u'target.common.tests.zip', 10:38:18 INFO - u'target.web-platform.tests.tar.gz'], 10:38:18 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 10:38:18 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 10:38:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.common.tests.zip 10:38:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:38:18 INFO - Fetch https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.common.tests.zip into memory 10:38:23 INFO - Content-Length response header: 54036626 10:38:23 INFO - Bytes received: 54036626 10:38:34 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 10:38:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.common.tests.zip 10:38:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:38:34 INFO - Fetch https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.common.tests.zip into memory 10:38:36 INFO - Content-Length response header: 54036626 10:38:36 INFO - Bytes received: 54036626 10:38:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.talos.tests.zip 10:38:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 10:38:50 INFO - Fetch https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.talos.tests.zip into memory 10:38:51 INFO - Content-Length response header: 14496050 10:38:51 INFO - Bytes received: 14496050 10:38:51 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')]} 10:38:51 INFO - trying https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.tar.bz2 10:38:51 INFO - Downloading https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 10:38:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 10:38:53 INFO - Downloaded 61894138 bytes. 10:38:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.tar.bz2 10:38:53 INFO - mkdir: /builds/slave/test/properties 10:38:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:38:53 INFO - Writing to file /builds/slave/test/properties/build_url 10:38:53 INFO - Contents: 10:38:53 INFO - build_url:https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.tar.bz2 10:38:53 INFO - Running post-action listener: _resource_record_post_action 10:38:53 INFO - Running post-action listener: set_extra_try_arguments 10:38:53 INFO - [mozharness: 2017-08-12 17:38:53.995021Z] Finished download-and-extract step (success) 10:38:53 INFO - [mozharness: 2017-08-12 17:38:53.995248Z] Running populate-webroot step. 10:38:53 INFO - Running pre-action listener: _resource_record_pre_action 10:38:53 INFO - Running main action method: populate_webroot 10:38:54 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:38:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:38:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:38:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:38:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:38:54 INFO - 'tests': ['damp', 'tps']}, 10:38:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:38:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:38:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:38:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:38:54 INFO - 'other-e10s': {'tests': ['a11yr', 10:38:54 INFO - 'ts_paint', 10:38:54 INFO - 'tpaint', 10:38:54 INFO - 'sessionrestore', 10:38:54 INFO - 'sessionrestore_many_windows', 10:38:54 INFO - 'sessionrestore_no_auto_restore', 10:38:54 INFO - 'tabpaint']}, 10:38:54 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 10:38:54 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 10:38:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:38:54 INFO - 'tsvgr_opacity', 10:38:54 INFO - 'tart', 10:38:54 INFO - 'tscrollx', 10:38:54 INFO - 'tsvg_static']}, 10:38:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:38:54 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 10:38:54 INFO - 'talos_options': ['--mitmproxy', 10:38:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 10:38:54 INFO - '--firstNonBlankPaint'], 10:38:54 INFO - 'tests': ['tp6_google', 10:38:54 INFO - 'tp6_youtube', 10:38:54 INFO - 'tp6_amazon', 10:38:54 INFO - 'tp6_facebook']}, 10:38:54 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 10:38:54 INFO - 'talos_options': ['--stylo', 10:38:54 INFO - '--mitmproxy', 10:38:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 10:38:54 INFO - '--firstNonBlankPaint'], 10:38:54 INFO - 'tests': ['tp6_google', 10:38:54 INFO - 'tp6_youtube', 10:38:54 INFO - 'tp6_amazon', 10:38:54 INFO - 'tp6_facebook']}, 10:38:54 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 10:38:54 INFO - 'talos_options': ['--stylo', 10:38:54 INFO - '--stylo-threads=1', 10:38:54 INFO - '--mitmproxy', 10:38:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 10:38:54 INFO - '--firstNonBlankPaint'], 10:38:54 INFO - 'tests': ['tp6_google', 10:38:54 INFO - 'tp6_youtube', 10:38:54 INFO - 'tp6_amazon', 10:38:54 INFO - 'tp6_facebook']}, 10:38:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:38:54 INFO - 'talos_options': ['--xperf_path', 10:38:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:38:54 INFO - 'tests': ['tp5n']}}} 10:38:54 INFO - Running post-action listener: _resource_record_post_action 10:38:54 INFO - [mozharness: 2017-08-12 17:38:54.010070Z] Finished populate-webroot step (success) 10:38:54 INFO - [mozharness: 2017-08-12 17:38:54.010160Z] Running create-virtualenv step. 10:38:54 INFO - Running pre-action listener: _resource_record_pre_action 10:38:54 INFO - Running main action method: create_virtualenv 10:38:54 INFO - Creating virtualenv /builds/slave/test/build/venv 10:38:54 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 10:38:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:38:54 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 10:38:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:38:54 INFO - Using real prefix '/usr' 10:38:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:38:54 INFO - Installing distribute.............................................................................................................................................................................................done. 10:38:56 INFO - Installing pip.................done. 10:38:56 INFO - Return code: 0 10:38:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:38:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:38:57 INFO - Reading from file tmpfile_stdout 10:38:57 INFO - Output received: 10:38:57 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) 10:38:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:38:57 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')]} 10:38:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:38:57 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')]} 10:38:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:38:57 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 0x1f4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2117a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ba700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, '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 0x22b87a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b8c30>, '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': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', '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 10:38:57 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 10:38:57 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 10:38:57 INFO - Using env: {'DISPLAY': ':0', 10:38:57 INFO - 'HOME': '/home/cltbld', 10:38:57 INFO - 'LANG': 'en_US.UTF-8', 10:38:57 INFO - 'LANGUAGE': 'en_US:en', 10:38:57 INFO - 'LOGNAME': 'cltbld', 10:38:57 INFO - 'MAIL': '/var/mail/cltbld', 10:38:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:38:57 INFO - 'MOZ_NO_REMOTE': '1', 10:38:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:38:57 INFO - 'NO_EM_RESTART': '1', 10:38:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:38:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:38:57 INFO - 'PWD': '/builds/slave/test', 10:38:57 INFO - 'SHELL': '/bin/bash', 10:38:57 INFO - 'SHLVL': '1', 10:38:57 INFO - 'TERM': 'linux', 10:38:57 INFO - 'TMOUT': '86400', 10:38:57 INFO - 'USER': 'cltbld', 10:38:57 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', 10:38:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:38:57 INFO - '_': '/tools/buildbot/bin/python'} 10:38:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:38:57 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 10:38:57 INFO - Cleaning up... 10:38:57 INFO - Return code: 0 10:38:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:38:57 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')]} 10:38:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:38:57 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')]} 10:38:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:38:57 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 0x1f4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2117a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ba700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, '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 0x22b87a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b8c30>, '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': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', '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 10:38:57 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 10:38:57 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 10:38:57 INFO - Using env: {'DISPLAY': ':0', 10:38:57 INFO - 'HOME': '/home/cltbld', 10:38:57 INFO - 'LANG': 'en_US.UTF-8', 10:38:57 INFO - 'LANGUAGE': 'en_US:en', 10:38:57 INFO - 'LOGNAME': 'cltbld', 10:38:57 INFO - 'MAIL': '/var/mail/cltbld', 10:38:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:38:57 INFO - 'MOZ_NO_REMOTE': '1', 10:38:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:38:57 INFO - 'NO_EM_RESTART': '1', 10:38:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:38:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:38:57 INFO - 'PWD': '/builds/slave/test', 10:38:57 INFO - 'SHELL': '/bin/bash', 10:38:57 INFO - 'SHLVL': '1', 10:38:57 INFO - 'TERM': 'linux', 10:38:57 INFO - 'TMOUT': '86400', 10:38:57 INFO - 'USER': 'cltbld', 10:38:57 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', 10:38:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:38:57 INFO - '_': '/tools/buildbot/bin/python'} 10:38:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:38:57 INFO - Downloading/unpacking psutil>=3.1.1 10:38:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:38:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:38:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:38:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:38:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:38:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:38:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:38:58 INFO - Installing collected packages: psutil 10:38:58 INFO - Running setup.py install for psutil 10:38:58 INFO - building 'psutil._psutil_linux' extension 10:38:58 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 10:39:00 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 10:39:00 INFO - building 'psutil._psutil_posix' extension 10:39:00 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 10:39:00 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 10:39:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:39:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:39:00 INFO - Successfully installed psutil 10:39:00 INFO - Cleaning up... 10:39:00 INFO - Return code: 0 10:39:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:39:00 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')]} 10:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:00 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')]} 10:39:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:00 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 0x1f4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2117a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ba700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, '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 0x22b87a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b8c30>, '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': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', '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 10:39:00 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 10:39:00 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 10:39:00 INFO - Using env: {'DISPLAY': ':0', 10:39:00 INFO - 'HOME': '/home/cltbld', 10:39:00 INFO - 'LANG': 'en_US.UTF-8', 10:39:00 INFO - 'LANGUAGE': 'en_US:en', 10:39:00 INFO - 'LOGNAME': 'cltbld', 10:39:00 INFO - 'MAIL': '/var/mail/cltbld', 10:39:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:00 INFO - 'MOZ_NO_REMOTE': '1', 10:39:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:00 INFO - 'NO_EM_RESTART': '1', 10:39:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:00 INFO - 'PWD': '/builds/slave/test', 10:39:00 INFO - 'SHELL': '/bin/bash', 10:39:00 INFO - 'SHLVL': '1', 10:39:00 INFO - 'TERM': 'linux', 10:39:00 INFO - 'TMOUT': '86400', 10:39:00 INFO - 'USER': 'cltbld', 10:39:00 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', 10:39:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:00 INFO - '_': '/tools/buildbot/bin/python'} 10:39:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:39:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:39:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:39:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:39:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:39:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:39:01 INFO - Installing collected packages: mozsystemmonitor 10:39:01 INFO - Running setup.py install for mozsystemmonitor 10:39:01 INFO - Successfully installed mozsystemmonitor 10:39:01 INFO - Cleaning up... 10:39:02 INFO - Return code: 0 10:39:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:39: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')]} 10:39:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39: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')]} 10:39:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39: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', '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 0x1f4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2117a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ba700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, '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 0x22b87a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b8c30>, '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': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', '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 10:39: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:39: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 jsonschema==2.5.1 10:39:02 INFO - Using env: {'DISPLAY': ':0', 10:39:02 INFO - 'HOME': '/home/cltbld', 10:39:02 INFO - 'LANG': 'en_US.UTF-8', 10:39:02 INFO - 'LANGUAGE': 'en_US:en', 10:39:02 INFO - 'LOGNAME': 'cltbld', 10:39:02 INFO - 'MAIL': '/var/mail/cltbld', 10:39:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:02 INFO - 'MOZ_NO_REMOTE': '1', 10:39:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:02 INFO - 'NO_EM_RESTART': '1', 10:39:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:02 INFO - 'PWD': '/builds/slave/test', 10:39:02 INFO - 'SHELL': '/bin/bash', 10:39:02 INFO - 'SHLVL': '1', 10:39:02 INFO - 'TERM': 'linux', 10:39:02 INFO - 'TMOUT': '86400', 10:39:02 INFO - 'USER': 'cltbld', 10:39:02 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', 10:39:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:02 INFO - '_': '/tools/buildbot/bin/python'} 10:39:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:02 INFO - Downloading/unpacking jsonschema==2.5.1 10:39: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 10:39: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 10:39: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 10:39: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 10:39:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:39:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:39:03 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 10:39:03 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 10:39:03 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 10:39:03 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 10:39:03 INFO - Downloading functools32-3.2.3-2.tar.gz 10:39:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:39:03 INFO - warning: no files found matching '*.txt' 10:39:03 INFO - no previously-included directories found matching 'build' 10:39:03 INFO - no previously-included directories found matching 'dist' 10:39:03 INFO - no previously-included directories found matching '.git*' 10:39:03 INFO - Installing collected packages: jsonschema, functools32 10:39:03 INFO - Running setup.py install for functools32 10:39:03 INFO - warning: no files found matching '*.txt' 10:39:03 INFO - no previously-included directories found matching 'build' 10:39:03 INFO - no previously-included directories found matching 'dist' 10:39:03 INFO - no previously-included directories found matching '.git*' 10:39:03 INFO - Successfully installed jsonschema functools32 10:39:03 INFO - Cleaning up... 10:39:03 INFO - Return code: 0 10:39:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:39:03 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')]} 10:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:03 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')]} 10:39:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:03 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 0x1f4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2117a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ba700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, '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 0x22b87a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b8c30>, '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': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', '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 10:39:03 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 10:39:03 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 10:39:03 INFO - Using env: {'DISPLAY': ':0', 10:39:03 INFO - 'HOME': '/home/cltbld', 10:39:03 INFO - 'LANG': 'en_US.UTF-8', 10:39:03 INFO - 'LANGUAGE': 'en_US:en', 10:39:03 INFO - 'LOGNAME': 'cltbld', 10:39:03 INFO - 'MAIL': '/var/mail/cltbld', 10:39:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:03 INFO - 'MOZ_NO_REMOTE': '1', 10:39:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:03 INFO - 'NO_EM_RESTART': '1', 10:39:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:03 INFO - 'PWD': '/builds/slave/test', 10:39:03 INFO - 'SHELL': '/bin/bash', 10:39:03 INFO - 'SHLVL': '1', 10:39:03 INFO - 'TERM': 'linux', 10:39:03 INFO - 'TMOUT': '86400', 10:39:03 INFO - 'USER': 'cltbld', 10:39:03 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', 10:39:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:03 INFO - '_': '/tools/buildbot/bin/python'} 10:39:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:39:03 INFO - Cleaning up... 10:39:03 INFO - Return code: 0 10:39:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:39:03 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')]} 10:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:03 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')]} 10:39:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:03 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 0x1f4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2117a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ba700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, '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 0x22b87a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b8c30>, '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': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', '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 10:39:03 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 10:39:03 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 10:39:03 INFO - Using env: {'DISPLAY': ':0', 10:39:03 INFO - 'HOME': '/home/cltbld', 10:39:03 INFO - 'LANG': 'en_US.UTF-8', 10:39:03 INFO - 'LANGUAGE': 'en_US:en', 10:39:03 INFO - 'LOGNAME': 'cltbld', 10:39:03 INFO - 'MAIL': '/var/mail/cltbld', 10:39:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:03 INFO - 'MOZ_NO_REMOTE': '1', 10:39:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:03 INFO - 'NO_EM_RESTART': '1', 10:39:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:03 INFO - 'PWD': '/builds/slave/test', 10:39:03 INFO - 'SHELL': '/bin/bash', 10:39:03 INFO - 'SHLVL': '1', 10:39:03 INFO - 'TERM': 'linux', 10:39:03 INFO - 'TMOUT': '86400', 10:39:03 INFO - 'USER': 'cltbld', 10:39:03 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', 10:39:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:03 INFO - '_': '/tools/buildbot/bin/python'} 10:39:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:04 INFO - Downloading/unpacking blobuploader==1.2.4 10:39: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 10:39: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 10:39: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 10:39: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 10:39:05 INFO - Downloading blobuploader-1.2.4.tar.gz 10:39:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:39:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:39: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 10:39: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 10:39: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 10:39: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 10:39:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:39:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:39: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 10:39: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 10:39: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 10:39: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 10:39:05 INFO - Downloading docopt-0.6.1.tar.gz 10:39:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:39:06 INFO - Installing collected packages: blobuploader, requests, docopt 10:39:06 INFO - Running setup.py install for blobuploader 10:39:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:39:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:39:06 INFO - Running setup.py install for requests 10:39:06 INFO - Running setup.py install for docopt 10:39:06 INFO - Successfully installed blobuploader requests docopt 10:39:06 INFO - Cleaning up... 10:39:06 INFO - Return code: 0 10:39:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:39:06 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')]} 10:39:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:06 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')]} 10:39:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:06 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 0x1f4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2117a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ba700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, '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 0x22b87a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b8c30>, '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': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', '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 10:39:06 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 10:39:06 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 10:39:06 INFO - Using env: {'DISPLAY': ':0', 10:39:06 INFO - 'HOME': '/home/cltbld', 10:39:06 INFO - 'LANG': 'en_US.UTF-8', 10:39:06 INFO - 'LANGUAGE': 'en_US:en', 10:39:06 INFO - 'LOGNAME': 'cltbld', 10:39:06 INFO - 'MAIL': '/var/mail/cltbld', 10:39:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:06 INFO - 'MOZ_NO_REMOTE': '1', 10:39:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:06 INFO - 'NO_EM_RESTART': '1', 10:39:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:06 INFO - 'PWD': '/builds/slave/test', 10:39:06 INFO - 'SHELL': '/bin/bash', 10:39:06 INFO - 'SHLVL': '1', 10:39:06 INFO - 'TERM': 'linux', 10:39:06 INFO - 'TMOUT': '86400', 10:39:06 INFO - 'USER': 'cltbld', 10:39:06 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', 10:39:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:06 INFO - '_': '/tools/buildbot/bin/python'} 10:39:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:39:06 INFO - Running setup.py (path:/tmp/pip-NKx6NA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:39:06 INFO - Running setup.py (path:/tmp/pip-_h741k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:39:06 INFO - Running setup.py (path:/tmp/pip-MzZ0i3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:39:07 INFO - Running setup.py (path:/tmp/pip-VTDonC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:39:07 INFO - Running setup.py (path:/tmp/pip-7tDx9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:39:07 INFO - Running setup.py (path:/tmp/pip-IPlO5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:39:07 INFO - Running setup.py (path:/tmp/pip-hRbhu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:39:07 INFO - Running setup.py (path:/tmp/pip-nfA6gT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:39:07 INFO - Running setup.py (path:/tmp/pip-JjT32Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:39:07 INFO - Running setup.py (path:/tmp/pip-J11P0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:39:07 INFO - Running setup.py (path:/tmp/pip-I7MWQh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:39:07 INFO - Running setup.py (path:/tmp/pip-5n6VQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:39:07 INFO - Running setup.py (path:/tmp/pip-rfLTtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:39:07 INFO - Running setup.py (path:/tmp/pip-igD4bi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:39:07 INFO - Running setup.py (path:/tmp/pip-ysf1Wp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:39:07 INFO - Running setup.py (path:/tmp/pip-Wrixmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:39:07 INFO - Running setup.py (path:/tmp/pip-mVGOFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:39:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:39:08 INFO - Running setup.py install for manifestparser 10:39:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:39:08 INFO - Running setup.py install for mozcrash 10:39:08 INFO - Running setup.py install for mozdebug 10:39:08 INFO - Running setup.py install for mozdevice 10:39:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:39:08 INFO - Running setup.py install for mozfile 10:39:08 INFO - Running setup.py install for mozhttpd 10:39:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:39:08 INFO - Running setup.py install for mozinfo 10:39:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:39:08 INFO - Running setup.py install for mozInstall 10:39:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:39:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:39:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:39:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:39:08 INFO - Running setup.py install for mozleak 10:39:08 INFO - Running setup.py install for mozlog 10:39:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:39:09 INFO - Running setup.py install for moznetwork 10:39:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:39:09 INFO - Running setup.py install for mozprocess 10:39:09 INFO - Running setup.py install for mozprofile 10:39:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:39:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:39:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:39:09 INFO - Running setup.py install for mozrunner 10:39:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:39:09 INFO - Running setup.py install for mozscreenshot 10:39:09 INFO - Running setup.py install for moztest 10:39:09 INFO - Running setup.py install for mozversion 10:39:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:39:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:39:09 INFO - Cleaning up... 10:39:09 INFO - Return code: 0 10:39:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:39:09 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')]} 10:39:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:09 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')]} 10:39:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:09 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 0x1f4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2117a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ba700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, '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 0x22b87a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b8c30>, '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': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', '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 10:39:09 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 10:39:09 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 10:39:09 INFO - Using env: {'DISPLAY': ':0', 10:39:09 INFO - 'HOME': '/home/cltbld', 10:39:09 INFO - 'LANG': 'en_US.UTF-8', 10:39:09 INFO - 'LANGUAGE': 'en_US:en', 10:39:09 INFO - 'LOGNAME': 'cltbld', 10:39:09 INFO - 'MAIL': '/var/mail/cltbld', 10:39:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:09 INFO - 'MOZ_NO_REMOTE': '1', 10:39:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:09 INFO - 'NO_EM_RESTART': '1', 10:39:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:09 INFO - 'PWD': '/builds/slave/test', 10:39:09 INFO - 'SHELL': '/bin/bash', 10:39:09 INFO - 'SHLVL': '1', 10:39:09 INFO - 'TERM': 'linux', 10:39:09 INFO - 'TMOUT': '86400', 10:39:09 INFO - 'USER': 'cltbld', 10:39:09 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', 10:39:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:09 INFO - '_': '/tools/buildbot/bin/python'} 10:39:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:39:10 INFO - Running setup.py (path:/tmp/pip-BTAA48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:39:10 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)) 10:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:39:10 INFO - Running setup.py (path:/tmp/pip-RbF3Uk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:39:10 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)) 10:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:39:10 INFO - Running setup.py (path:/tmp/pip-k3AXzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:39:10 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)) 10:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:39:10 INFO - Running setup.py (path:/tmp/pip-6QA9Fz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:39:10 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)) 10:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:39:10 INFO - Running setup.py (path:/tmp/pip-mXCLV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:39:10 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)) 10:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:39:10 INFO - Running setup.py (path:/tmp/pip-Kbw2DQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:39:10 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)) 10:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:39:10 INFO - Running setup.py (path:/tmp/pip-6bXJHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:39:10 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)) 10:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:39:10 INFO - Running setup.py (path:/tmp/pip-VMHPJU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:39:10 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)) 10:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:39:10 INFO - Running setup.py (path:/tmp/pip-YEqgEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:39:10 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)) 10:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:39:10 INFO - Running setup.py (path:/tmp/pip-SSzX5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:39:10 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)) 10:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:39:10 INFO - Running setup.py (path:/tmp/pip-9oYUxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:39:10 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)) 10:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:39:10 INFO - Running setup.py (path:/tmp/pip-_qBqRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:39:10 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)) 10:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:39:10 INFO - Running setup.py (path:/tmp/pip-UwL5bV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:39:10 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)) 10:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:39:10 INFO - Running setup.py (path:/tmp/pip-0L7GYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:39:11 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)) 10:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:39:11 INFO - Running setup.py (path:/tmp/pip-P91hYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:39:11 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)) 10:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:39:11 INFO - Running setup.py (path:/tmp/pip-eKwNzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:39:11 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)) 10:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:39:11 INFO - Running setup.py (path:/tmp/pip-LYX4Y7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:39:11 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)) 10:39:11 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)) 10:39:11 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)) 10:39:11 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)) 10:39:11 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)) 10:39:11 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)) 10:39:11 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)) 10:39:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:39:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:39:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:39:12 INFO - Downloading blessings-1.6.tar.gz 10:39:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:39:12 INFO - Installing collected packages: blessings 10:39:12 INFO - Running setup.py install for blessings 10:39:12 INFO - Successfully installed blessings 10:39:12 INFO - Cleaning up... 10:39:12 INFO - Return code: 0 10:39:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:39:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:39:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:39:12 INFO - Reading from file tmpfile_stdout 10:39:12 INFO - Current package versions: 10:39:12 INFO - argparse == 1.2.1 10:39:12 INFO - blessings == 1.6 10:39:12 INFO - blobuploader == 1.2.4 10:39:12 INFO - docopt == 0.6.1 10:39:12 INFO - functools32 == 3.2.3-2 10:39:12 INFO - jsonschema == 2.5.1 10:39:12 INFO - manifestparser == 1.1 10:39:12 INFO - mozInstall == 1.13 10:39:12 INFO - mozcrash == 1.0 10:39:12 INFO - mozdebug == 0.1 10:39:12 INFO - mozdevice == 0.51 10:39:12 INFO - mozfile == 1.2 10:39:12 INFO - mozhttpd == 0.7 10:39:12 INFO - mozinfo == 0.10 10:39:12 INFO - mozleak == 0.1 10:39:12 INFO - mozlog == 3.5 10:39:12 INFO - moznetwork == 0.27 10:39:12 INFO - mozprocess == 0.25 10:39:12 INFO - mozprofile == 0.28 10:39:12 INFO - mozrunner == 6.13 10:39:12 INFO - mozscreenshot == 0.1 10:39:12 INFO - mozsystemmonitor == 0.3 10:39:12 INFO - moztest == 0.8 10:39:12 INFO - mozversion == 1.4 10:39:12 INFO - psutil == 3.1.1 10:39:12 INFO - requests == 1.2.3 10:39:12 INFO - wsgiref == 0.1.2 10:39:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:39:12 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')]} 10:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:12 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')]} 10:39:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:12 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 0x1f4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2117a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ba700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, '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 0x22b87a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b8c30>, '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': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', '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 10:39:12 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 10:39:12 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 10:39:12 INFO - Using env: {'DISPLAY': ':0', 10:39:12 INFO - 'HOME': '/home/cltbld', 10:39:12 INFO - 'LANG': 'en_US.UTF-8', 10:39:12 INFO - 'LANGUAGE': 'en_US:en', 10:39:12 INFO - 'LOGNAME': 'cltbld', 10:39:12 INFO - 'MAIL': '/var/mail/cltbld', 10:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:12 INFO - 'MOZ_NO_REMOTE': '1', 10:39:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:12 INFO - 'NO_EM_RESTART': '1', 10:39:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:12 INFO - 'PWD': '/builds/slave/test', 10:39:12 INFO - 'SHELL': '/bin/bash', 10:39:12 INFO - 'SHLVL': '1', 10:39:12 INFO - 'TERM': 'linux', 10:39:12 INFO - 'TMOUT': '86400', 10:39:12 INFO - 'USER': 'cltbld', 10:39:12 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', 10:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:12 INFO - '_': '/tools/buildbot/bin/python'} 10:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:13 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)) 10:39:13 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)) 10:39:13 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)) 10:39:13 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)) 10:39:13 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)) 10:39:13 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)) 10:39:13 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)) 10:39:13 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)) 10:39:13 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)) 10:39:13 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:39:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:39:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:39:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:39:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:39:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 10:39:14 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)) 10:39:14 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)) 10:39:14 INFO - Installing collected packages: simplejson 10:39:14 INFO - Running setup.py install for simplejson 10:39:14 INFO - building 'simplejson._speedups' extension 10:39:14 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 10:39:15 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 10:39:15 INFO - Successfully installed simplejson 10:39:15 INFO - Cleaning up... 10:39:15 INFO - Return code: 0 10:39:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:39:15 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')]} 10:39:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:15 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')]} 10:39:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:15 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 0x1f4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2117a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ba700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, '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 0x22b87a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b8c30>, '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': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', '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 10:39:15 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 10:39:15 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 10:39:15 INFO - Using env: {'DISPLAY': ':0', 10:39:15 INFO - 'HOME': '/home/cltbld', 10:39:15 INFO - 'LANG': 'en_US.UTF-8', 10:39:15 INFO - 'LANGUAGE': 'en_US:en', 10:39:15 INFO - 'LOGNAME': 'cltbld', 10:39:15 INFO - 'MAIL': '/var/mail/cltbld', 10:39:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:15 INFO - 'MOZ_NO_REMOTE': '1', 10:39:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:15 INFO - 'NO_EM_RESTART': '1', 10:39:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:15 INFO - 'PWD': '/builds/slave/test', 10:39:15 INFO - 'SHELL': '/bin/bash', 10:39:15 INFO - 'SHLVL': '1', 10:39:15 INFO - 'TERM': 'linux', 10:39:15 INFO - 'TMOUT': '86400', 10:39:15 INFO - 'USER': 'cltbld', 10:39:15 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', 10:39:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:15 INFO - '_': '/tools/buildbot/bin/python'} 10:39:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:39:15 INFO - Cleaning up... 10:39:15 INFO - Return code: 0 10:39:15 INFO - Running post-action listener: _resource_record_post_action 10:39:15 INFO - Running post-action listener: _start_resource_monitoring 10:39:15 INFO - Starting resource monitoring. 10:39:15 INFO - [mozharness: 2017-08-12 17:39:15.563020Z] Finished create-virtualenv step (success) 10:39:15 INFO - [mozharness: 2017-08-12 17:39:15.564032Z] Running install step. 10:39:15 INFO - Running pre-action listener: _resource_record_pre_action 10:39:15 INFO - Running main action method: install 10:39:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:39:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:39:15 INFO - Reading from file tmpfile_stdout 10:39:15 INFO - Detecting whether we're running mozinstall >=1.0... 10:39:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:39:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:39:15 INFO - Reading from file tmpfile_stdout 10:39:15 INFO - Output received: 10:39:15 INFO - Usage: mozinstall [options] installer 10:39:15 INFO - Options: 10:39:15 INFO - -h, --help show this help message and exit 10:39:15 INFO - -d DEST, --destination=DEST 10:39:15 INFO - Directory to install application into. [default: 10:39:15 INFO - "/builds/slave/test"] 10:39:15 INFO - --app=APP Application being installed. [default: firefox] 10:39:15 INFO - mkdir: /builds/slave/test/build/application 10:39:15 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'] 10:39:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 10:39:33 INFO - Reading from file tmpfile_stdout 10:39:33 INFO - Output received: 10:39:33 INFO - /builds/slave/test/build/application/firefox/firefox 10:39:33 INFO - Running post-action listener: _resource_record_post_action 10:39:33 INFO - [mozharness: 2017-08-12 17:39:33.813112Z] Finished install step (success) 10:39:33 INFO - [mozharness: 2017-08-12 17:39:33.813501Z] Running setup-mitmproxy step. 10:39:33 INFO - Running pre-action listener: _resource_record_pre_action 10:39:33 INFO - Running main action method: setup_mitmproxy 10:39:33 INFO - Skipping: mitmproxy is not required 10:39:33 INFO - Running post-action listener: _resource_record_post_action 10:39:33 INFO - [mozharness: 2017-08-12 17:39:33.814646Z] Finished setup-mitmproxy step (success) 10:39:33 INFO - [mozharness: 2017-08-12 17:39:33.814867Z] Running run-tests step. 10:39:33 INFO - Running pre-action listener: _resource_record_pre_action 10:39:33 INFO - Running pre-action listener: _set_gcov_prefix 10:39:33 INFO - Running main action method: run_tests 10:39:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:39:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:39:33 INFO - Python 2.7.3 10:39:33 INFO - Return code: 0 10:39:33 INFO - grabbing minidump binary from tooltool 10:39:33 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')]} 10:39:33 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 0x22b78a0>, '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 0x22b87a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b8c30>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:39:33 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 10:39:33 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 10:39:33 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 10:39:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:39:33 INFO - Return code: 0 10:39:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:39:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:39:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:39:33 INFO - ENV: RUST_BACKTRACE is now full 10:39:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:39:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:39:33 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/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 10:39:33 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/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-021 --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 10:39:33 INFO - Using env: {'DISPLAY': ':0', 10:39:33 INFO - 'HOME': '/home/cltbld', 10:39:33 INFO - 'LANG': 'en_US.UTF-8', 10:39:33 INFO - 'LANGUAGE': 'en_US:en', 10:39:33 INFO - 'LOGNAME': 'cltbld', 10:39:33 INFO - 'MAIL': '/var/mail/cltbld', 10:39:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:39:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:39:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:33 INFO - 'MOZ_NO_REMOTE': '1', 10:39:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:39:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:33 INFO - 'NO_EM_RESTART': '1', 10:39:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:33 INFO - 'PWD': '/builds/slave/test', 10:39:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:39:33 INFO - 'RUST_BACKTRACE': 'full', 10:39:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:39:33 INFO - 'SHELL': '/bin/bash', 10:39:33 INFO - 'SHLVL': '1', 10:39:33 INFO - 'STYLO_FORCE_ENABLED': '1', 10:39:33 INFO - 'TERM': 'linux', 10:39:33 INFO - 'TMOUT': '86400', 10:39:33 INFO - 'USER': 'cltbld', 10:39:33 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1502557983.913160-761455044', 10:39:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:33 INFO - '_': '/tools/buildbot/bin/python'} 10:39:33 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/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 10:39:34 INFO - mozversion application_buildid: 20170812165514 10:39:34 INFO - mozversion application_changeset: 1f363abd841d1ad9fb8ac1e67ea7c8636895d766 10:39:34 INFO - mozversion application_display_name: Nightly 10:39:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:39:34 INFO - mozversion application_name: Firefox 10:39:34 INFO - mozversion application_remotingname: firefox 10:39:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:39:34 INFO - mozversion application_vendor: Mozilla 10:39:34 INFO - mozversion application_version: 57.0a1 10:39:34 INFO - mozversion platform_buildid: 20170812165514 10:39:34 INFO - mozversion platform_changeset: 1f363abd841d1ad9fb8ac1e67ea7c8636895d766 10:39:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:39:34 INFO - mozversion platform_version: 57.0a1 10:39:34 INFO - using testdate: 1502559574 10:39:34 INFO - actual date: 1502559574 10:39:34 INFO - starting webserver on 'localhost:43043' 10:39:34 INFO - SUITE-START | Running 2 tests 10:39:34 INFO - TEST-START | dromaeo_css 10:39:34 INFO - Initialising browser for dromaeo_css test... 10:39:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43043/getInfo.html -profile /tmp/tmpfnp2iL/profile 10:39:34 INFO - TEST-INFO | started process 3317 (/builds/slave/test/build/application/firefox/firefox http://localhost:43043/getInfo.html) 10:39:47 INFO - TEST-INFO | 3317: exit 0 10:39:47 INFO - Browser initialized. 10:39:47 INFO - Running cycle 1/1 for dromaeo_css test... 10:39:47 INFO - TEST-INFO | started process 3553 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfnp2iL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 10:39:48 INFO - PID 3553 | 10:39:48 INFO - PID 3553 | (/builds/slave/test/build/application/firefox/firefox:3607): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 10:39:48 INFO - PID 3553 | 10:39:58 INFO - PID 3553 | RSS: Main: 171110400 10:39:58 INFO - PID 3553 | 10:42:58 INFO - PID 3553 | Cycle 1(1): loaded http://localhost:43043/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43043/tests/dromaeo/cssquery-ext.html) 10:42:59 INFO - PID 3553 | RSS: Main: 175677440 10:42:59 INFO - PID 3553 | 10:46:01 INFO - PID 3553 | Cycle 1(1): loaded http://localhost:43043/tests/dromaeo/cssquery-ext.html (next: http://localhost:43043/tests/dromaeo/cssquery-jquery.html) 10:46:01 INFO - PID 3553 | RSS: Main: 173408256 10:46:01 INFO - PID 3553 | 10:49:03 INFO - PID 3553 | Cycle 1(1): loaded http://localhost:43043/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43043/tests/dromaeo/cssquery-mootools.html) 10:49:04 INFO - PID 3553 | RSS: Main: 175407104 10:49:04 INFO - PID 3553 | 10:52:06 INFO - PID 3553 | Cycle 1(1): loaded http://localhost:43043/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43043/tests/dromaeo/cssquery-prototype.html) 10:52:06 INFO - PID 3553 | RSS: Main: 173674496 10:52:06 INFO - PID 3553 | 10:55:10 INFO - PID 3553 | Cycle 1(1): loaded http://localhost:43043/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43043/tests/dromaeo/cssquery-yui.html) 10:55:10 INFO - PID 3553 | RSS: Main: 177643520 10:55:10 INFO - PID 3553 | 10:58:12 INFO - PID 3553 | Cycle 1(1): loaded http://localhost:43043/tests/dromaeo/cssquery-yui.html (next: http://localhost:43043/tests/dromaeo/cssquery-dojo.html) 10:58:13 INFO - PID 3553 | RSS: Main: 177496064 10:58:13 INFO - PID 3553 | 10:58:13 INFO - PID 3553 | __start_tp_report 10:58:13 INFO - PID 3553 | _x_x_mozilla_page_load 10:58:13 INFO - PID 3553 | _x_x_mozilla_page_load_details 10:58:13 INFO - PID 3553 | |i|pagename|runs| 10:58:13 INFO - PID 3553 | |0;dojo.html;3147.260393807455;3160.2099475131217;3176.4917613181897;3182.5703530023447;3195.6644552322005;6216.813495595128;6272.404121608445;6273.309935907047;6301.779437719681;6315.368463153676;6058.182145410368;6143.539234557404;6171.814845554641;6187.0719392091205;6202.131701561785;5636.830895073133;5669.914951275734;5701.657900525982;5704.942950570504;5751.482366587006;5686.800961966332;5705.77176912923;5744.79893203738;5764.25064741583;5767;5684.175794509814;5686.459786320293;5716.314042314928;5797.101449275369;5800.3619601843975;5512.80705175317;5652.434756524356;5661.943380566183;5745.913811292834;5763.88472230553;5627.409122042209;5671.886562268773;5714.399988001242;5717.942820571824;5745.109508026221;5961.850953726148;6019.097135429677;6072.271327440735;6078.149059131725;6207.534434917399;6626.668666566652;6650.634215118166;6664.333566643345;6666.9333306666795;6667.033280174348;6730.730770769163;6736.528443008991;6743.35938085881;6744.224414192382;6747.662616869137;6206.906896396557;6227.439530442282;6228.87542249157;6285.2457705075685;6298.559100862942;6759.5606285591275;6822.590644561342;6835.07726456922;6855.348741869514;6856.554323968926;6527.67361631926;6563.310852360433;6576.112224849584;6617.404433600999;6622.205335359788;6489.643069631121;6575.835604109936;6581.440577550962;6585.835354116185;6603;6602.7028783704845;6607.702653380609;6616;6619.940809470461;6624.86750264992;6520.869582608321;6551;6552.148220731275;6559.081728558006;6622.2715501294815;5081.28861959326;5252.474752524717;5260.815871444558;5323.441038690959;5340.786368545292;4538.11506756186;4594.33382159585;4607.585317321457;4612.215923292981;4629.791659375336;5745.913811292834;5824.213731146326;5828;5828.271466066742;5883.117532370179;6638.17022872141;6649.401553860176;6697.564658297292;6713.462922966151;6724.899126513106;5731.856703582361;5825.941740582624;5830.795922142789;5840.036393994943;5916.467517923407;5813.651180929158;5829.271341082364;5877.882442351129;5954.255718035246;5982;6565.835854103686;6566.704498297588;6598.10925525048;6601.438877695354;6613.6362500062;6609.173853268341;6705.731770729116;6775.559588626724;6788.830279242861;6825.658717064226;6335.429811316819;6451.903221451776;6476.579022363531;6545.770898018546;6572.539922205399;6481.611103333815;6580.407763301326;6603.405693487609;6632.104665870046;6632.469402447696;6675.599464032173;6701.66491675424;6760.628165450948;6763.560368576027;6765.458763299022;6543.476521878333;6565.146530950946;6571.737130514726;6579.078928949855;6585.506087043395;6405.263394709482;6421.454176395058;6489.870202595827;6507.349265073455;6508.381703738138;6317.4630156437215;6406.263279722705;6416.550841441054;6507.544471886922;6510.967445162744;6378.553501254868;6531.640759758069;6544.214694236722;6625.204975402982;6671.099401580725;6258.468030217483;6289.371062893674;6322.209723784527;6345.302016778243;6363.872722545616 10:58:13 INFO - PID 3553 | |1;ext.html;845.3110714767464;848.1010129262978;873.1355957601974;897.1611543207102;909.7680091576647;43009.56990430099;43807.12385752291;44020.55979440205;44127;44132.558674413245;39459.802700986525;39472.40791388132;39549.40675889857;39563.60436395635;39848;6828.931710682892;6934.861302773941;6936.930630693679;6946.79159625212;6950.0269962205075;6852.794416167523;6934.202566704843;6934.618595977219;6937.861242775141;6945.965270173668;7542.321191092828;7701.460897737161;7710.961445192795;7716.729914452995;7731.536107833548;7581.924180758177;7659.387249020093;7660.080790305199;7680.500767450126;7682.884756728654;7523.548587084792;7530.397568194531;7706.344960678349;7733.304002639789;7733.922660773376;39853.80073099645;40079;40191.598084019075;40232;40248.798756006036;3429.828508574586;3457;3461.7922924624604;3461.809600471973;3463.688268055862;32501.83749081263;32650.67349326524;32657.346853062805;32746.6725332746;32843.50734738981;5168.922466162973;5175.146100893348;5182.119039763249;5192.8961420771375;5214.4524824893215;189750;194793.0520694789;196816;199563;206574;52162.21756673653;52221.477785221665;52228;52241;52394;54220.45779542233;54594.45405545973;54630.45369546333;54647;54705.45294547083;15599.532014039596;15786.131762753159;15795.526134215763;15797;15944.920275398548;12661.683457913627;12682.429290681941;12707.745845083047;12731.93634031824;12753.234805911676;15018.69962600742;15095.169765662993;15157;15215.08709477435;15318.617034574225;5895.322037965603;5920;5937.851553711192;5991.101334799815;5997.550183736237;980.1962390839585;996.3673067602125;1018.1651046142092;1021.2953062386929;1035.4563853976722;23356.182533611583;23518.529629407316;23719.407014824767;23787.881060594587;24080.79596020192;1127.1827924754487;1128.136975213962;1137.6132115080911;1139.2367114033618;1146.128942004083;12417.31704756229;12481.313527756061;12504.437300321508;12659.746805064031;12673.429695663717;234421;234776;235479;235624;236108;54731.45268547343;54861;55059.72470137704;55066.449335506935;55228.447715523136;1445.1834713387113;1460.2698650674663;1485.829129650104;1486.7918491410994;1496.4837131189722;22887.542249155256;22911.31266062021;23031.769682302962;23285.3014409568;23447.765522345242;38896.22207555889;39023.2195356097;39025.804870975466;39040.21919561536;39044.414333785586;196326;196435;196577;196661;196779;35792;37787.81106094452;40133.598664012985;40244.1951160981;40263.79868100758;48360.274595880386;49129;49176.50823491863;49268.2609760861;49564.50435495599;35910.28179436449;36101.63898361088;36230.45654315135;36387.45418818667;36761.89714308575;35656.4651530222;35661.821690892415;35782;35791;35799;38874.80562597169;38998.61001390063;39137.80431097826;39156.412653809646;39496.802515987234;34418.311633767684;34457.48313775346;34673.65326346805;34782;35274.294514110086;23788.88105559461;24736.876315618305;24906.501869962136;25356.492870142123;25523.23430297094 10:58:13 INFO - PID 3553 | |2;jquery.html;567.6877717255511;571.2174321179984;572.9770809167634;579.9072148456247;584.918111464395;22361.440963975907;22897.656535151986;23217.76782232177;23306.300810975677;23327.883360583215;25976.48047039058;26613.733862661367;26693.599596006028;26793;26834.463310733772;6560.540762146652;6650.5344625876205;6679.565828221174;6684.598924064548;6708.127943367355;6009.369016253274;6518.771842985497;6525.738970441176;6531.118299029617;6604;6467.126937863399;6493.512986526006;6498.870022599545;6509.869802603968;6510.511711621623;6479.967600161993;6487.18910136233;6491.383318584727;6504.869902601945;6507.251666058417;6037.426444487766;6081.969590152021;6093.055576385667;6094.299155597118;6118.571699981003;27944.580831287545;29167.562486562718;29268.41463170746;29423.852880735674;29636.851815741;1958.451633542596;1986.9503262418411;1990.800919908012;1991.2035185925745;1991.6415045291842;19107.140178691854;19644.214231430724;19692.40922772319;19695;19715.605687886164;2357.2456813819585;2410.9758902410927;2411.1801987324393;2428.7935525480357;2480.3055144559553;135931.32034339863;143781;146213.26893365465;146390.26804866013;147046.26476867654;14762.114273143647;15169.469068582546;15326.616834579225;15431.456854314478;15660.843391566166;23682.171124010576;23744.525109497714;23749.762502375102;23774.16790412362;23889.880550597485;10571.418571978615;10681.893181068246;10689.198310126772;10755.408452535186;10863.945680271549;10099.798004039878;10110.090091891765;10118.949405252926;10136.239782016379;10273.229507786797;9292.442453452815;9302;9523.095305945924;9600.855987160197;9742.902570974342;2631.2369412870053;2660.8935642574465;2694.9865250674015;2778.5554311310498;2809.90165344212;941.1388579449726;966.1256562810668;970.9126178643814;975.8487434447673;980.0738302304254;19169.233230670616;19432;19489;19714.30999915025;19888.3039093631;1004.8743907011624;1050.0864248104199;1054.267284237463;1059.1368035051407;1060.2101434431245;10662.520186591622;11277.266977646426;13281.26953017574;14049.859501405059;14212.360443780055;487040;510937;540846;542921;546383;125779;126023;126109;127502.36248818882;128062.35968820282;1270.7776139175608;1270.993645031769;1311.6851955530603;1315.6579289384638;1325.8077578750833;20505.487362815453;20684.793152068287;20869.1652333911;20873.582528349045;20920.47698807481;57021;57236.42763572311;57727.4227257722;59735;60422.69788651029;71308.64345678239;71460.64269678618;71734.64132679511;72049.47826826035;72298.63850680712;12218.633440996784;14616.780748288997;14702;14831.332590033258;14858.108513489226;23027.424314391603;23345.76654233436;23997.520049598563;25442.363940901625;25570.23289301324;19060.1422935971;19146.329878454188;19394.127264272858;19442.805571944664;19468.31860884862;13911.652208694864;17123.486295411156;17785.644287114443;17814.198361074043;18014;18722.438326850217;20041.899790500953;20056.298029568894;20115.698264525752;20253.49366265855;14794.63013424673;15199.392024319346;15200.847991519944;15214.163221022507;15218.163001034607;18097.81902181014;18124.18441170124;18265.543361416072;18293.725594115833;18373.632527349644 10:58:13 INFO - PID 3553 | |3;mootools.html;1356.9932150339248;1423.0963338280192;1427.8001079848823;1430.299153414827;1440.2419366912363;14114.788278175824;14215.928920355409;14720.26398680067;14725.337359818806;14748.778768318483;14702.705945881076;15033.774493382609;15064.698706025873;15086.3211155498;15088.47190348338;2706.174616741893;2760.9585856212175;2764.5162096633007;2767.9169624911283;2776.1532732516575;2592.64999225105;2724.0738149029326;2766.0318888389;2766.0318888389;2769.2661444717164;2681.5575430054114;2687.422204226091;2725.2641786717572;2727.2227415186676;2729.5359788835844;2660.082271616299;2664.800139989499;2678.504476671822;2679.289988153141;2682.4500977299804;2642.656454660901;2651.6420283261705;2655.933601659974;2663.8401695898115;2670.6661667291587;13884.861151388457;14089.154650720888;14136;14259.714805703828;14293.642658933506;7553.169151393342;7853.057633084067;7874.133845277015;7877.527348359117;7885.842283154362;5195.244996240008;5311.654742441729;5432.348118225838;5449;5497.615166938316;1887.565859852228;1935.3516571948414;1953.306576165455;1983.2166294313665;1999.3402177281462;23873.641895371584;24145.275641730776;24535;24567;24584.631230531555;6951.200611929592;7076.452477108237;7146.464015198881;7183.8563228736175;7245.529040612343;18072.819271807377;18079.27682892696;18084.90957545204;18090.819091808913;18110.366137185134;7583;7937.087234967938;8142.959285203536;8144.470609410369;8160.714374996846;7149.9285007149265;7219.3502584766575;7264.237255088246;7294.744683936036;7300.6349682515665;5120.283160357553;5229.581633469314;5256.684598924076;5293.867653308698;5352.652077614943;1516.408600645749;1521.9619509512129;1527.1982209340185;1528.2817075974274;1538.5845821628322;818;835.1898658301438;842.9747107586783;848.6478111583624;864.6800683746928;6716.361945615159;6733.764318248838;6758.05387245786;6823.897641535381;6871.793846184622;707.2397173038901;712.8716830970371;728.1771598094225;730.9086364204475;732.0410262556069;4363.890902727394;4758.5241475852135;4850.83022094225;4851.296561998491;4866.440359358719;19901;20393.98030098519;20546.486337841376;20582.27962021322;20628.484287892923;19373;19940.50148746293;19951.700724489143;19984.100715467837;20000;10600.151987840954;10942.343459392461;10952.233343665866;11055.72360690973;11172.329660220414;7446.478746487694;7667.884981725167;7734.187910269566;7810.179931107379;7849.254320839511;17286.394976175772;17347.306107755547;17480.82519174792;17768.111594420487;18066.728999065286;18030.2787888483;18268.543286417946;18306.26774928988;18307.35924242645;18500;10501.527431265458;10718.303310284806;10825.188110891557;10838.512266947848;10849.67450976472;13318.733625327246;13472.461101555828;13543.525976590772;13610.115342502706;13625.182489050689;10213.029762172582;10288.691339259834;10354.326968747007;10511.159107271553;10574.788504230026;10653.360798352123;10770;11030.22788404804;11039.227254092137;11327.263727857662;9625.807483850424;9816.803663926825;9832.262580306362;9835.114839664464;9836.852447213441;6540.182477190351;6604.768833090819;6606.735730570724;6665;7171.928280717335;7156.713731450684;7216.242294559206;7277.3086556777025;7287.1984081752025;7291.6354182289615 10:58:13 INFO - PID 3553 | |4;prototype.html;495.000099798407;497.83073754923316;530.1014779947985;530.9275263966787;533.6424595521;6200.844978875531;6214.254289485262;6255.562110652256;6277.497800175986;6309;5854.414558544144;6037.184980027695;6084.452399284063;6112.633242005483;6146.784862529812;4947.455779864212;4952.529509696573;4960.751962401883;4978.676386034906;4997.275395067713;4944.7638090477385;4953;5008;5062.493750624926;5140.871478213037;5107.157319042372;5129.281900533928;5150.33045704058;5174.240758142231;5275.525202731754;5110.080185566597;5131.997781297945;5157.5616072633875;5204.661696989702;5241.9213711794355;4783.4977327380475;4836.903261934742;4846.5880400166025;4846.806127754886;4890.902181956377;5998.3701711320555;6091.360407157229;6123.357047510036;6153.600015998945;6183.1961844960315;6050.033739034831;6056.21269235001;6056.697165141725;6091.2995005574485;6144.354842741493;6660.300668429793;6671.633060181688;6688.598684078971;6748.966255168692;6759;5298;5364.9731751341;5375.381831089397;5378.865528361822;5378.892422151535;6987.965060174666;7010.439164866799;7041.859162816766;7111;7118.572885626827;5515.255440515479;5521.834344969658;5557.194206839986;5560.972195138998;5577.9442205577425;5596.3284405870745;5646.774129034792;5649.293838270216;5663.94147160537;5673.461021202979;6004.995004995005;6092.482139018144;6127.693615319216;6128.4790792783115;6134.325224225332;6586.769463068769;6614.966925165343;6687.832804179837;6694.799156025327;6699.631520266432;1832.7709036370454;1838.503604026932;1876.2495001999037;1940.835029022549;1962.1464662871697;1449.5651304608575;1461.758809796393;1463.2391156598721;1463.948761793332;1478.1574502533454;5821.621594596338;5829.7376618052285;5838.182654428383;5841.50347220491;5845.473907348359;6659.500537459709;6706.664666766642;6794.8301292467095;6831.4629208427705;6867.519273650896;5816.883662326814;5826.2134611827705;5827.24245848037;5834.445727655866;5909.852253693692;3515.8417871195347;3595.047312462147;3602.6757591816863;3627.8548858045383;3648.1426864686778;6593.80218593443;6595.538312318092;6601.61366113112;6636.83407914806;6667.566608170453;7017.228104908456;7032.156141262979;7045.788626341218;7046.295370462913;7067.5052746307265;6504.9674751625935;6580.5722628029025;6598.340165983459;6623.575931174821;6642.867142657216;6745;6768.830779230559;6795.966020169867;6802.93197068023;6803.251642319242;6745.662716864235;6746.4940129489505;6754.223264324665;6767.661616919036;6799.728010879509;6535.57518761279;6575.638339891352;6592.934070659424;6599.053279282719;6621.834454138492;5037;5078.314427552233;5124.513890971725;5128.1794912813775;5133.6919784813035;6885.1115707847985;6891.138607674041;6910.619915904473;6912.36642136657;6919.342662447059;6314.936850631435;6430.903536446864;6467.288598254094;6554;6591.6374599397495;6548.410643042149;6565.474762019123;6594.868102637824;6600.2409722882485;6609.735610575523;6511.153550038464;6529.183852018497;6532.673366331569;6543.70553325092;6559.245686745894;6531.542792004704;6548.3124271950755;6598.934010659832;6625.687156421789;6631.867362652816;6323.494120470259;6331.303556608861;6385.7126429309865;6392.776252831314;6482.124913136666 10:58:13 INFO - PID 3553 | |5;yui.html;820.1060843680391;820.6389188756942;838.1367191792448;909.6179604566081;925.4817302310704;18386.172622232;18869.71695424568;18949.99999999998;19272.710909336372;22758.544829103408;20220.59558808823;21571.24500642478;21642.134314627398;21672.3498295051;21745.13019479219;984.670135504605;1005.9094681478665;1008.425197637347;1008.4755926917999;1009.369144284822;980.3921568627461;1075.42464781342;1080.0495563903753;1081.5457507846684;1081.9891801081967;1106.115107913666;1115.0577761791274;1118.3848883114294;1118.8601424821898;1119.0432180485689;1117.284937639911;1118.6308518189019;1118.8713297970755;1120.2830188679252;1122.5229277557046;821.1501096365233;821.5850995247422;822.0669540072001;822.1039067416544;822.7202751064607;19513.219471221135;19837.702434463496;19917.30289439877;19977.200911963504;20094.598108037757;21511.46221344479;21528;22050.4487387815;22117.33647990563;22185.667214991787;17197.140142992925;17234.396796112076;17294.537622399926;17388.304467821272;17514.12429378526;3699.7780133192095;3701.796401197933;3709.443583462475;3716.6655001050035;3719.5722491913234;126616;126649;126804.36597817045;130970;131559;16561;17059.402920897708;17210;17317.91341043312;17787.822121778874;31204;31709.36581268408;31820.522692159637;32290.83854580759;32481.35037299241;7646.9617651912495;7809.297163255334;8078.111407745143;8927.866082008775;9116.954415227881;4283.443152390169;4418.823247070145;4426.11477704454;4429.534898835575;4439.289713645866;5369.946300537023;5414.485623865805;5432.185172224119;5480.588955828329;5493.038718224375;1103.939283339424;1108.8059589571792;1124.4995976790412;1125.2966895690195;1131.9037881779975;658.8418779492887;672.5460314287836;681.6285124607101;684.6439851277407;688.6006116452446;11349.319040857576;11529.308241505536;12090.576829810914;12869.163504372185;12976.740465190644;647.3235468934975;653.3433898931544;664.1930054983122;668.4051194436955;669.7321071571411;5427.538659214011;5428.511433970962;5560.193771903051;5962;6412.9679351602945;107320;108881.45559272153;112379.4381028106;112948.43525782482;118286;93467;93859;95196.0480395201;96126.51936740271;97352;86957.56521217353;87092;87779;90130.09869901217;90194.54902725444;12651.114421990373;12767.106302558732;12845.293508857103;12907.419166137734;12949.158304710163;38576.22847543089;38874.61125388823;39011.6098839008;39473.60526394699;41002.58997409988;49726.25410618916;50852.237216442525;51597.74201129121;52329.738351308;53227.201591976926;18022.189001494702;18677.90661046686;19332;19356.61286774285;19537.706934395712;19885;20295.28966486166;20686.689699654216;20827;21023.369298920446;17911;18226.270949162415;18362;18787.812121879153;19714.21143154315;17566.736498952272;18597.34909278169;19624.607507849476;20113.497162571053;20338.288159914333;17604.823951760318;18594.442166735018;18687.972161531245;18710;18789.530261743566;13135.868641313464;13360;13506;13615;13778.173309601456;13897.112048523817;14127.43490260378;14310.928445357707;14771;14929.10425374481 10:58:13 INFO - PID 3553 | __end_tp_report 10:58:13 INFO - PID 3553 | __start_cc_report 10:58:13 INFO - PID 3553 | _x_x_mozilla_cycle_collect,379 10:58:13 INFO - PID 3553 | __end_cc_report 10:58:13 INFO - PID 3553 | __startTimestamp1502560693032__endTimestamp 10:58:13 INFO - PID 3553 | 10:58:13 INFO - PID 3553 | ------- Summary: start ------- 10:58:13 INFO - PID 3553 | Number of tests: 6 10:58:13 INFO - PID 3553 | 10:58:13 INFO - PID 3553 | [#0] dojo.html Cycles:165 Average:6137.09 Median:6378.55 stddev:725.94 (11.4%) stddev-sans-first:689.24 10:58:13 INFO - PID 3553 | Values: 3147.3 3160.2 3176.5 3182.6 3195.7 6216.8 6272.4 6273.3 6301.8 6315.4 6058.2 6143.5 6171.8 6187.1 6202.1 5636.8 5669.9 5701.7 5704.9 5751.5 5686.8 5705.8 5744.8 5764.3 5767.0 5684.2 5686.5 5716.3 5797.1 5800.4 5512.8 5652.4 5661.9 5745.9 5763.9 5627.4 5671.9 5714.4 5717.9 5745.1 5961.9 6019.1 6072.3 6078.1 6207.5 6626.7 6650.6 6664.3 6666.9 6667.0 6730.7 6736.5 6743.4 6744.2 6747.7 6206.9 6227.4 6228.9 6285.2 6298.6 6759.6 6822.6 6835.1 6855.3 6856.6 6527.7 6563.3 6576.1 6617.4 6622.2 6489.6 6575.8 6581.4 6585.8 6603.0 6602.7 6607.7 6616.0 6619.9 6624.9 6520.9 6551.0 6552.1 6559.1 6622.3 5081.3 5252.5 5260.8 5323.4 5340.8 4538.1 4594.3 4607.6 4612.2 4629.8 5745.9 5824.2 5828.0 5828.3 5883.1 6638.2 6649.4 6697.6 6713.5 6724.9 5731.9 5825.9 5830.8 5840.0 5916.5 5813.7 5829.3 5877.9 5954.3 5982.0 6565.8 6566.7 6598.1 6601.4 6613.6 6609.2 6705.7 6775.6 6788.8 6825.7 6335.4 6451.9 6476.6 6545.8 6572.5 6481.6 6580.4 6603.4 6632.1 6632.5 6675.6 6701.7 6760.6 6763.6 6765.5 6543.5 6565.1 6571.7 6579.1 6585.5 6405.3 6421.5 6489.9 6507.3 6508.4 6317.5 6406.3 6416.6 6507.5 6511.0 6378.6 6531.6 6544.2 6625.2 6671.1 6258.5 6289.4 6322.2 6345.3 6363.9 10:58:13 INFO - PID 3553 | 10:58:13 INFO - PID 3553 | [#1] ext.html Cycles:180 Average:38997.98 Median:38885.51 stddev:54622.11 (140.5%) stddev-sans-first:54700.21 10:58:13 INFO - PID 3553 | Values: 845.3 848.1 873.1 897.2 909.8 43009.6 43807.1 44020.6 44127.0 44132.6 39459.8 39472.4 39549.4 39563.6 39848.0 6828.9 6934.9 6936.9 6946.8 6950.0 6852.8 6934.2 6934.6 6937.9 6946.0 7542.3 7701.5 7711.0 7716.7 7731.5 7581.9 7659.4 7660.1 7680.5 7682.9 7523.5 7530.4 7706.3 7733.3 7733.9 39853.8 40079.0 40191.6 40232.0 40248.8 3429.8 3457.0 3461.8 3461.8 3463.7 32501.8 32650.7 32657.3 32746.7 32843.5 5168.9 5175.1 5182.1 5192.9 5214.5 189750.0 194793.1 196816.0 199563.0 206574.0 52162.2 52221.5 52228.0 52241.0 52394.0 54220.5 54594.5 54630.5 54647.0 54705.5 15599.5 15786.1 15795.5 15797.0 15944.9 12661.7 12682.4 12707.7 12731.9 12753.2 15018.7 15095.2 15157.0 15215.1 15318.6 5895.3 5920.0 5937.9 5991.1 5997.6 980.2 996.4 1018.2 1021.3 1035.5 23356.2 23518.5 23719.4 23787.9 24080.8 1127.2 1128.1 1137.6 1139.2 1146.1 12417.3 12481.3 12504.4 12659.7 12673.4 234421.0 234776.0 235479.0 235624.0 236108.0 54731.5 54861.0 55059.7 55066.4 55228.4 1445.2 1460.3 1485.8 1486.8 1496.5 22887.5 22911.3 23031.8 23285.3 23447.8 38896.2 39023.2 39025.8 39040.2 39044.4 196326.0 196435.0 196577.0 196661.0 196779.0 35792.0 37787.8 40133.6 40244.2 40263.8 48360.3 49129.0 49176.5 49268.3 49564.5 35910.3 36101.6 36230.5 36387.5 36761.9 35656.5 35661.8 35782.0 35791.0 35799.0 38874.8 38998.6 39137.8 39156.4 39496.8 34418.3 34457.5 34673.7 34782.0 35274.3 23788.9 24736.9 24906.5 25356.5 25523.2 10:58:13 INFO - PID 3553 | 10:58:13 INFO - PID 3553 | [#2] jquery.html Cycles:180 Average:36615.93 Median:22629.55 stddev:88607.07 (391.6%) stddev-sans-first:88814.30 10:58:13 INFO - PID 3553 | Values: 567.7 571.2 573.0 579.9 584.9 22361.4 22897.7 23217.8 23306.3 23327.9 25976.5 26613.7 26693.6 26793.0 26834.5 6560.5 6650.5 6679.6 6684.6 6708.1 6009.4 6518.8 6525.7 6531.1 6604.0 6467.1 6493.5 6498.9 6509.9 6510.5 6480.0 6487.2 6491.4 6504.9 6507.3 6037.4 6082.0 6093.1 6094.3 6118.6 27944.6 29167.6 29268.4 29423.9 29636.9 1958.5 1987.0 1990.8 1991.2 1991.6 19107.1 19644.2 19692.4 19695.0 19715.6 2357.2 2411.0 2411.2 2428.8 2480.3 135931.3 143781.0 146213.3 146390.3 147046.3 14762.1 15169.5 15326.6 15431.5 15660.8 23682.2 23744.5 23749.8 23774.2 23889.9 10571.4 10681.9 10689.2 10755.4 10863.9 10099.8 10110.1 10118.9 10136.2 10273.2 9292.4 9302.0 9523.1 9600.9 9742.9 2631.2 2660.9 2695.0 2778.6 2809.9 941.1 966.1 970.9 975.8 980.1 19169.2 19432.0 19489.0 19714.3 19888.3 1004.9 1050.1 1054.3 1059.1 1060.2 10662.5 11277.3 13281.3 14049.9 14212.4 487040.0 510937.0 540846.0 542921.0 546383.0 125779.0 126023.0 126109.0 127502.4 128062.4 1270.8 1271.0 1311.7 1315.7 1325.8 20505.5 20684.8 20869.2 20873.6 20920.5 57021.0 57236.4 57727.4 59735.0 60422.7 71308.6 71460.6 71734.6 72049.5 72298.6 12218.6 14616.8 14702.0 14831.3 14858.1 23027.4 23345.8 23997.5 25442.4 25570.2 19060.1 19146.3 19394.1 19442.8 19468.3 13911.7 17123.5 17785.6 17814.2 18014.0 18722.4 20041.9 20056.3 20115.7 20253.5 14794.6 15199.4 15200.8 15214.2 15218.2 18097.8 18124.2 18265.5 18293.7 18373.6 10:58:13 INFO - PID 3553 | 10:58:13 INFO - PID 3553 | [#3] mootools.html Cycles:180 Average:8958.31 Median:2664.32 stddev:6294.42 (236.2%) stddev-sans-first:6286.16 10:58:13 INFO - PID 3553 | Values: 1357.0 1423.1 1427.8 1430.3 1440.2 14114.8 14215.9 14720.3 14725.3 14748.8 14702.7 15033.8 15064.7 15086.3 15088.5 2706.2 2761.0 2764.5 2767.9 2776.2 2592.6 2724.1 2766.0 2766.0 2769.3 2681.6 2687.4 2725.3 2727.2 2729.5 2660.1 2664.8 2678.5 2679.3 2682.5 2642.7 2651.6 2655.9 2663.8 2670.7 13884.9 14089.2 14136.0 14259.7 14293.6 7553.2 7853.1 7874.1 7877.5 7885.8 5195.2 5311.7 5432.3 5449.0 5497.6 1887.6 1935.4 1953.3 1983.2 1999.3 23873.6 24145.3 24535.0 24567.0 24584.6 6951.2 7076.5 7146.5 7183.9 7245.5 18072.8 18079.3 18084.9 18090.8 18110.4 7583.0 7937.1 8143.0 8144.5 8160.7 7149.9 7219.4 7264.2 7294.7 7300.6 5120.3 5229.6 5256.7 5293.9 5352.7 1516.4 1522.0 1527.2 1528.3 1538.6 818.0 835.2 843.0 848.6 864.7 6716.4 6733.8 6758.1 6823.9 6871.8 707.2 712.9 728.2 730.9 732.0 4363.9 4758.5 4850.8 4851.3 4866.4 19901.0 20394.0 20546.5 20582.3 20628.5 19373.0 19940.5 19951.7 19984.1 20000.0 10600.2 10942.3 10952.2 11055.7 11172.3 7446.5 7667.9 7734.2 7810.2 7849.3 17286.4 17347.3 17480.8 17768.1 18066.7 18030.3 18268.5 18306.3 18307.4 18500.0 10501.5 10718.3 10825.2 10838.5 10849.7 13318.7 13472.5 13543.5 13610.1 13625.2 10213.0 10288.7 10354.3 10511.2 10574.8 10653.4 10770.0 11030.2 11039.2 11327.3 9625.8 9816.8 9832.3 9835.1 9836.9 6540.2 6604.8 6606.7 6665.0 7171.9 7156.7 7216.2 7277.3 7287.2 7291.6 10:58:13 INFO - PID 3553 | 10:58:13 INFO - PID 3553 | [#4] prototype.html Cycles:180 Average:5655.19 Median:6139.34 stddev:1528.96 (24.9%) stddev-sans-first:1483.38 10:58:13 INFO - PID 3553 | Values: 495.0 497.8 530.1 530.9 533.6 6200.8 6214.3 6255.6 6277.5 6309.0 5854.4 6037.2 6084.5 6112.6 6146.8 4947.5 4952.5 4960.8 4978.7 4997.3 4944.8 4953.0 5008.0 5062.5 5140.9 5107.2 5129.3 5150.3 5174.2 5275.5 5110.1 5132.0 5157.6 5204.7 5241.9 4783.5 4836.9 4846.6 4846.8 4890.9 5998.4 6091.4 6123.4 6153.6 6183.2 6050.0 6056.2 6056.7 6091.3 6144.4 6660.3 6671.6 6688.6 6749.0 6759.0 5298.0 5365.0 5375.4 5378.9 5378.9 6988.0 7010.4 7041.9 7111.0 7118.6 5515.3 5521.8 5557.2 5561.0 5577.9 5596.3 5646.8 5649.3 5663.9 5673.5 6005.0 6092.5 6127.7 6128.5 6134.3 6586.8 6615.0 6687.8 6694.8 6699.6 1832.8 1838.5 1876.2 1940.8 1962.1 1449.6 1461.8 1463.2 1463.9 1478.2 5821.6 5829.7 5838.2 5841.5 5845.5 6659.5 6706.7 6794.8 6831.5 6867.5 5816.9 5826.2 5827.2 5834.4 5909.9 3515.8 3595.0 3602.7 3627.9 3648.1 6593.8 6595.5 6601.6 6636.8 6667.6 7017.2 7032.2 7045.8 7046.3 7067.5 6505.0 6580.6 6598.3 6623.6 6642.9 6745.0 6768.8 6796.0 6802.9 6803.3 6745.7 6746.5 6754.2 6767.7 6799.7 6535.6 6575.6 6592.9 6599.1 6621.8 5037.0 5078.3 5124.5 5128.2 5133.7 6885.1 6891.1 6910.6 6912.4 6919.3 6314.9 6430.9 6467.3 6554.0 6591.6 6548.4 6565.5 6594.9 6600.2 6609.7 6511.2 6529.2 6532.7 6543.7 6559.2 6531.5 6548.3 6598.9 6625.7 6631.9 6323.5 6331.3 6385.7 6392.8 6482.1 10:58:13 INFO - PID 3553 | 10:58:13 INFO - PID 3553 | [#5] yui.html Cycles:180 Average:23583.74 Median:20104.05 stddev:31798.33 (158.2%) stddev-sans-first:31841.60 10:58:13 INFO - PID 3553 | Values: 820.1 820.6 838.1 909.6 925.5 18386.2 18869.7 18950.0 19272.7 22758.5 20220.6 21571.2 21642.1 21672.3 21745.1 984.7 1005.9 1008.4 1008.5 1009.4 980.4 1075.4 1080.0 1081.5 1082.0 1106.1 1115.1 1118.4 1118.9 1119.0 1117.3 1118.6 1118.9 1120.3 1122.5 821.2 821.6 822.1 822.1 822.7 19513.2 19837.7 19917.3 19977.2 20094.6 21511.5 21528.0 22050.4 22117.3 22185.7 17197.1 17234.4 17294.5 17388.3 17514.1 3699.8 3701.8 3709.4 3716.7 3719.6 126616.0 126649.0 126804.4 130970.0 131559.0 16561.0 17059.4 17210.0 17317.9 17787.8 31204.0 31709.4 31820.5 32290.8 32481.4 7647.0 7809.3 8078.1 8927.9 9117.0 4283.4 4418.8 4426.1 4429.5 4439.3 5369.9 5414.5 5432.2 5480.6 5493.0 1103.9 1108.8 1124.5 1125.3 1131.9 658.8 672.5 681.6 684.6 688.6 11349.3 11529.3 12090.6 12869.2 12976.7 647.3 653.3 664.2 668.4 669.7 5427.5 5428.5 5560.2 5962.0 6413.0 107320.0 108881.5 112379.4 112948.4 118286.0 93467.0 93859.0 95196.0 96126.5 97352.0 86957.6 87092.0 87779.0 90130.1 90194.5 12651.1 12767.1 12845.3 12907.4 12949.2 38576.2 38874.6 39011.6 39473.6 41002.6 49726.3 50852.2 51597.7 52329.7 53227.2 18022.2 18677.9 19332.0 19356.6 19537.7 19885.0 20295.3 20686.7 20827.0 21023.4 17911.0 18226.3 18362.0 18787.8 19714.2 17566.7 18597.3 19624.6 20113.5 20338.3 17604.8 18594.4 18688.0 18710.0 18789.5 13135.9 13360.0 13506.0 13615.0 13778.2 13897.1 14127.4 14310.9 14771.0 14929.1 10:58:13 INFO - PID 3553 | -------- Summary: end -------- 10:58:13 INFO - PID 3553 | 10:58:13 INFO - TEST-INFO | 3553: exit 0 10:58:13 INFO - TEST-OK | dromaeo_css | took 1119612ms 10:58:13 INFO - TEST-START | kraken 10:58:13 INFO - Initialising browser for kraken test... 10:58:13 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43043/getInfo.html -profile /tmp/tmphqFwlv/profile 10:58:13 INFO - TEST-INFO | started process 3929 (/builds/slave/test/build/application/firefox/firefox http://localhost:43043/getInfo.html) 10:58:20 INFO - TEST-INFO | 3929: exit 0 10:58:20 INFO - Browser initialized. 10:58:20 INFO - Running cycle 1/1 for kraken test... 10:58:20 INFO - TEST-INFO | started process 4163 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphqFwlv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 10:58:21 INFO - PID 4163 | 10:58:21 INFO - PID 4163 | (/builds/slave/test/build/application/firefox/firefox:4218): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 10:58:21 INFO - PID 4163 | 10:58:31 INFO - PID 4163 | RSS: Main: 167837696 10:58:31 INFO - PID 4163 | 10:58:34 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:43043/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43043/tests/kraken/driver.html?testName=audio-beat-detection) 10:58:35 INFO - PID 4163 | RSS: Main: 174362624 10:58:35 INFO - PID 4163 | 10:58:37 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:43043/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43043/tests/kraken/driver.html?testName=audio-dft) 10:58:38 INFO - PID 4163 | RSS: Main: 174915584 10:58:38 INFO - PID 4163 | 10:58:41 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:43043/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43043/tests/kraken/driver.html?testName=audio-fft) 10:58:42 INFO - PID 4163 | RSS: Main: 169717760 10:58:42 INFO - PID 4163 | 10:58:44 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:43043/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43043/tests/kraken/driver.html?testName=audio-oscillator) 10:58:45 INFO - PID 4163 | RSS: Main: 170442752 10:58:45 INFO - PID 4163 | 10:58:47 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:43043/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43043/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:58:47 INFO - PID 4163 | RSS: Main: 170553344 10:58:47 INFO - PID 4163 | 10:58:51 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:43043/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43043/tests/kraken/driver.html?testName=imaging-darkroom) 10:58:51 INFO - PID 4163 | RSS: Main: 173293568 10:58:51 INFO - PID 4163 | 10:58:55 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:43043/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43043/tests/kraken/driver.html?testName=imaging-desaturate) 10:58:55 INFO - PID 4163 | RSS: Main: 172761088 10:58:55 INFO - PID 4163 | 10:58:58 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:43043/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43043/tests/kraken/driver.html?testName=json-parse-financial) 10:58:59 INFO - PID 4163 | RSS: Main: 173469696 10:58:59 INFO - PID 4163 | 10:59:00 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:43043/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43043/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:59:01 INFO - PID 4163 | RSS: Main: 173625344 10:59:01 INFO - PID 4163 | 10:59:03 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:43043/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43043/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:59:04 INFO - PID 4163 | RSS: Main: 173740032 10:59:04 INFO - PID 4163 | 10:59:06 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:43043/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43043/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:59:06 INFO - PID 4163 | RSS: Main: 173690880 10:59:06 INFO - PID 4163 | 10:59:09 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:43043/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43043/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:59:10 INFO - PID 4163 | RSS: Main: 173907968 10:59:10 INFO - PID 4163 | 10:59:13 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:43043/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43043/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:59:14 INFO - PID 4163 | RSS: Main: 173989888 10:59:14 INFO - PID 4163 | 10:59:16 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:43043/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43043/tests/kraken/driver.html?testName=ai-astar) 10:59:16 INFO - PID 4163 | RSS: Main: 175562752 10:59:16 INFO - PID 4163 | 10:59:16 INFO - PID 4163 | __start_tp_report 10:59:16 INFO - PID 4163 | _x_x_mozilla_page_load 10:59:16 INFO - PID 4163 | _x_x_mozilla_page_load_details 10:59:16 INFO - PID 4163 | |i|pagename|runs| 10:59:16 INFO - PID 4163 | |0;ai-astar;96;109;103;108;108;114;111;110;108;108 10:59:16 INFO - PID 4163 | |1;audio-beat-detection;142;117;149;180;122;145;144;131;188;119 10:59:16 INFO - PID 4163 | |2;audio-dft;162;161;164;162;164;167;166;165;163;165 10:59:16 INFO - PID 4163 | |3;audio-fft;79;75;75;153;76;76;74;89;109;76 10:59:16 INFO - PID 4163 | |4;audio-oscillator;80;79;135;79;81;79;133;79;79;124 10:59:16 INFO - PID 4163 | |5;imaging-gaussian-blur;127;115;114;114;115;115;117;115;114;116 10:59:16 INFO - PID 4163 | |6;imaging-darkroom;152;156;149;150;151;150;152;149;154;150 10:59:16 INFO - PID 4163 | |7;imaging-desaturate;95;98;94;94;95;94;96;94;94;94 10:59:16 INFO - PID 4163 | |8;json-parse-financial;56;57;56;59;58;62;58;55;55;56 10:59:16 INFO - PID 4163 | |9;json-stringify-tinderbox;56;67;55;55;56;56;58;55;58;62 10:59:16 INFO - PID 4163 | |10;stanford-crypto-aes;77;78;89;78;78;79;78;84;78;78 10:59:16 INFO - PID 4163 | |11;stanford-crypto-ccm;134;126;129;126;122;138;131;124;134;169 10:59:16 INFO - PID 4163 | |12;stanford-crypto-pbkdf2;184;175;186;177;176;175;181;170;180;173 10:59:16 INFO - PID 4163 | |13;stanford-crypto-sha256-iterative;70;70;76;67;67;76;68;70;70;70 10:59:16 INFO - PID 4163 | __end_tp_report 10:59:16 INFO - PID 4163 | __start_cc_report 10:59:16 INFO - PID 4163 | _x_x_mozilla_cycle_collect,951 10:59:16 INFO - PID 4163 | __end_cc_report 10:59:16 INFO - PID 4163 | __startTimestamp1502560756287__endTimestamp 10:59:16 INFO - PID 4163 | 10:59:16 INFO - PID 4163 | ------- Summary: start ------- 10:59:16 INFO - PID 4163 | Number of tests: 14 10:59:16 INFO - PID 4163 | 10:59:16 INFO - PID 4163 | [#0] ai-astar Cycles:10 Average:107.50 Median:109.50 stddev:4.90 (4.5%) stddev-sans-first:2.95 10:59:16 INFO - PID 4163 | Values: 96.0 109.0 103.0 108.0 108.0 114.0 111.0 110.0 108.0 108.0 10:59:16 INFO - PID 4163 | 10:59:16 INFO - PID 4163 | [#1] audio-beat-detection Cycles:10 Average:143.70 Median:144.50 stddev:24.19 (16.7%) stddev-sans-first:25.65 10:59:16 INFO - PID 4163 | Values: 142.0 117.0 149.0 180.0 122.0 145.0 144.0 131.0 188.0 119.0 10:59:16 INFO - PID 4163 | 10:59:16 INFO - PID 4163 | [#2] audio-dft Cycles:10 Average:163.90 Median:164.50 stddev:1.91 (1.2%) stddev-sans-first:1.90 10:59:16 INFO - PID 4163 | Values: 162.0 161.0 164.0 162.0 164.0 167.0 166.0 165.0 163.0 165.0 10:59:16 INFO - PID 4163 | 10:59:16 INFO - PID 4163 | [#3] audio-fft Cycles:10 Average:88.20 Median:76.00 stddev:25.20 (33.2%) stddev-sans-first:26.50 10:59:16 INFO - PID 4163 | Values: 79.0 75.0 75.0 153.0 76.0 76.0 74.0 89.0 109.0 76.0 10:59:16 INFO - PID 4163 | 10:59:16 INFO - PID 4163 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:79.00 stddev:24.91 (31.5%) stddev-sans-first:25.84 10:59:16 INFO - PID 4163 | Values: 80.0 79.0 135.0 79.0 81.0 79.0 133.0 79.0 79.0 124.0 10:59:16 INFO - PID 4163 | 10:59:16 INFO - PID 4163 | [#5] imaging-gaussian-blur Cycles:10 Average:116.20 Median:115.00 stddev:3.91 (3.4%) stddev-sans-first:1.00 10:59:16 INFO - PID 4163 | Values: 127.0 115.0 114.0 114.0 115.0 115.0 117.0 115.0 114.0 116.0 10:59:16 INFO - PID 4163 | 10:59:16 INFO - PID 4163 | [#6] imaging-darkroom Cycles:10 Average:151.30 Median:151.50 stddev:2.26 (1.5%) stddev-sans-first:2.39 10:59:16 INFO - PID 4163 | Values: 152.0 156.0 149.0 150.0 151.0 150.0 152.0 149.0 154.0 150.0 10:59:16 INFO - PID 4163 | 10:59:16 INFO - PID 4163 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:94.50 stddev:1.32 (1.4%) stddev-sans-first:1.39 10:59:16 INFO - PID 4163 | Values: 95.0 98.0 94.0 94.0 95.0 94.0 96.0 94.0 94.0 94.0 10:59:16 INFO - PID 4163 | 10:59:16 INFO - PID 4163 | [#8] json-parse-financial Cycles:10 Average:57.20 Median:57.50 stddev:2.15 (3.7%) stddev-sans-first:2.24 10:59:16 INFO - PID 4163 | Values: 56.0 57.0 56.0 59.0 58.0 62.0 58.0 55.0 55.0 56.0 10:59:16 INFO - PID 4163 | 10:59:16 INFO - PID 4163 | [#9] json-stringify-tinderbox Cycles:10 Average:57.80 Median:57.00 stddev:3.88 (6.8%) stddev-sans-first:4.06 10:59:16 INFO - PID 4163 | Values: 56.0 67.0 55.0 55.0 56.0 56.0 58.0 55.0 58.0 62.0 10:59:16 INFO - PID 4163 | 10:59:16 INFO - PID 4163 | [#10] stanford-crypto-aes Cycles:10 Average:79.70 Median:78.00 stddev:3.80 (4.9%) stddev-sans-first:3.91 10:59:16 INFO - PID 4163 | Values: 77.0 78.0 89.0 78.0 78.0 79.0 78.0 84.0 78.0 78.0 10:59:16 INFO - PID 4163 | 10:59:16 INFO - PID 4163 | [#11] stanford-crypto-ccm Cycles:10 Average:133.30 Median:132.50 stddev:13.51 (10.2%) stddev-sans-first:14.32 10:59:16 INFO - PID 4163 | Values: 134.0 126.0 129.0 126.0 122.0 138.0 131.0 124.0 134.0 169.0 10:59:16 INFO - PID 4163 | 10:59:16 INFO - PID 4163 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.70 Median:178.50 stddev:4.99 (2.8%) stddev-sans-first:4.74 10:59:16 INFO - PID 4163 | Values: 184.0 175.0 186.0 177.0 176.0 175.0 181.0 170.0 180.0 173.0 10:59:16 INFO - PID 4163 | 10:59:16 INFO - PID 4163 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:70.00 stddev:3.20 (4.6%) stddev-sans-first:3.40 10:59:16 INFO - PID 4163 | Values: 70.0 70.0 76.0 67.0 67.0 76.0 68.0 70.0 70.0 70.0 10:59:16 INFO - PID 4163 | -------- Summary: end -------- 10:59:16 INFO - PID 4163 | 10:59:16 INFO - PID 4163 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 10:59:16 INFO - TEST-INFO | 4163: exit 0 10:59:17 INFO - TEST-OK | kraken | took 63141ms 10:59:17 INFO - SUITE-END | took 1182s 10:59:17 INFO - Completed test suite (00:19:43) 10:59:17 INFO - javascript benchmark 10:59:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8624.749925568842, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3147.260393807455, 3160.2099475131217, 3176.4917613181897, 3182.5703530023447, 3195.6644552322005, 6216.813495595128, 6272.404121608445, 6273.309935907047, 6301.779437719681, 6315.368463153676, 6058.182145410368, 6143.539234557404, 6171.814845554641, 6187.0719392091205, 6202.131701561785, 5636.830895073133, 5669.914951275734, 5701.657900525982, 5704.942950570504, 5751.482366587006, 5686.800961966332, 5705.77176912923, 5744.79893203738, 5764.25064741583, 5767.0, 5684.175794509814, 5686.459786320293, 5716.314042314928, 5797.101449275369, 5800.3619601843975, 5512.80705175317, 5652.434756524356, 5661.943380566183, 5745.913811292834, 5763.88472230553, 5627.409122042209, 5671.886562268773, 5714.399988001242, 5717.942820571824, 5745.109508026221, 5961.850953726148, 6019.097135429677, 6072.271327440735, 6078.149059131725, 6207.534434917399, 6626.668666566652, 6650.634215118166, 6664.333566643345, 6666.9333306666795, 6667.033280174348, 6730.730770769163, 6736.528443008991, 6743.35938085881, 6744.224414192382, 6747.662616869137, 6206.906896396557, 6227.439530442282, 6228.87542249157, 6285.2457705075685, 6298.559100862942, 6759.5606285591275, 6822.590644561342, 6835.07726456922, 6855.348741869514, 6856.554323968926, 6527.67361631926, 6563.310852360433, 6576.112224849584, 6617.404433600999, 6622.205335359788, 6489.643069631121, 6575.835604109936, 6581.440577550962, 6585.835354116185, 6603.0, 6602.7028783704845, 6607.702653380609, 6616.0, 6619.940809470461, 6624.86750264992, 6520.869582608321, 6551.0, 6552.148220731275, 6559.081728558006, 6622.2715501294815, 5081.28861959326, 5252.474752524717, 5260.815871444558, 5323.441038690959, 5340.786368545292, 4538.11506756186, 4594.33382159585, 4607.585317321457, 4612.215923292981, 4629.791659375336, 5745.913811292834, 5824.213731146326, 5828.0, 5828.271466066742, 5883.117532370179, 6638.17022872141, 6649.401553860176, 6697.564658297292, 6713.462922966151, 6724.899126513106, 5731.856703582361, 5825.941740582624, 5830.795922142789, 5840.036393994943, 5916.467517923407, 5813.651180929158, 5829.271341082364, 5877.882442351129, 5954.255718035246, 5982.0, 6565.835854103686, 6566.704498297588, 6598.10925525048, 6601.438877695354, 6613.6362500062, 6609.173853268341, 6705.731770729116, 6775.559588626724, 6788.830279242861, 6825.658717064226, 6335.429811316819, 6451.903221451776, 6476.579022363531, 6545.770898018546, 6572.539922205399, 6481.611103333815, 6580.407763301326, 6603.405693487609, 6632.104665870046, 6632.469402447696, 6675.599464032173, 6701.66491675424, 6760.628165450948, 6763.560368576027, 6765.458763299022, 6543.476521878333, 6565.146530950946, 6571.737130514726, 6579.078928949855, 6585.506087043395, 6405.263394709482, 6421.454176395058, 6489.870202595827, 6507.349265073455, 6508.381703738138, 6317.4630156437215, 6406.263279722705, 6416.550841441054, 6507.544471886922, 6510.967445162744, 6378.553501254868, 6531.640759758069, 6544.214694236722, 6625.204975402982, 6671.099401580725, 6258.468030217483, 6289.371062893674, 6322.209723784527, 6345.302016778243, 6363.872722545616], "value": 6082.680772121435, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [845.3110714767464, 848.1010129262978, 873.1355957601974, 897.1611543207102, 909.7680091576647, 43009.56990430099, 43807.12385752291, 44020.55979440205, 44127.0, 44132.558674413245, 39459.802700986525, 39472.40791388132, 39549.40675889857, 39563.60436395635, 39848.0, 6828.931710682892, 6934.861302773941, 6936.930630693679, 6946.79159625212, 6950.0269962205075, 6852.794416167523, 6934.202566704843, 6934.618595977219, 6937.861242775141, 6945.965270173668, 7542.321191092828, 7701.460897737161, 7710.961445192795, 7716.729914452995, 7731.536107833548, 7581.924180758177, 7659.387249020093, 7660.080790305199, 7680.500767450126, 7682.884756728654, 7523.548587084792, 7530.397568194531, 7706.344960678349, 7733.304002639789, 7733.922660773376, 39853.80073099645, 40079.0, 40191.598084019075, 40232.0, 40248.798756006036, 3429.828508574586, 3457.0, 3461.7922924624604, 3461.809600471973, 3463.688268055862, 32501.83749081263, 32650.67349326524, 32657.346853062805, 32746.6725332746, 32843.50734738981, 5168.922466162973, 5175.146100893348, 5182.119039763249, 5192.8961420771375, 5214.4524824893215, 189750.0, 194793.0520694789, 196816.0, 199563.0, 206574.0, 52162.21756673653, 52221.477785221665, 52228.0, 52241.0, 52394.0, 54220.45779542233, 54594.45405545973, 54630.45369546333, 54647.0, 54705.45294547083, 15599.532014039596, 15786.131762753159, 15795.526134215763, 15797.0, 15944.920275398548, 12661.683457913627, 12682.429290681941, 12707.745845083047, 12731.93634031824, 12753.234805911676, 15018.69962600742, 15095.169765662993, 15157.0, 15215.08709477435, 15318.617034574225, 5895.322037965603, 5920.0, 5937.851553711192, 5991.101334799815, 5997.550183736237, 980.1962390839585, 996.3673067602125, 1018.1651046142092, 1021.2953062386929, 1035.4563853976722, 23356.182533611583, 23518.529629407316, 23719.407014824767, 23787.881060594587, 24080.79596020192, 1127.1827924754487, 1128.136975213962, 1137.6132115080911, 1139.2367114033618, 1146.128942004083, 12417.31704756229, 12481.313527756061, 12504.437300321508, 12659.746805064031, 12673.429695663717, 234421.0, 234776.0, 235479.0, 235624.0, 236108.0, 54731.45268547343, 54861.0, 55059.72470137704, 55066.449335506935, 55228.447715523136, 1445.1834713387113, 1460.2698650674663, 1485.829129650104, 1486.7918491410994, 1496.4837131189722, 22887.542249155256, 22911.31266062021, 23031.769682302962, 23285.3014409568, 23447.765522345242, 38896.22207555889, 39023.2195356097, 39025.804870975466, 39040.21919561536, 39044.414333785586, 196326.0, 196435.0, 196577.0, 196661.0, 196779.0, 35792.0, 37787.81106094452, 40133.598664012985, 40244.1951160981, 40263.79868100758, 48360.274595880386, 49129.0, 49176.50823491863, 49268.2609760861, 49564.50435495599, 35910.28179436449, 36101.63898361088, 36230.45654315135, 36387.45418818667, 36761.89714308575, 35656.4651530222, 35661.821690892415, 35782.0, 35791.0, 35799.0, 38874.80562597169, 38998.61001390063, 39137.80431097826, 39156.412653809646, 39496.802515987234, 34418.311633767684, 34457.48313775346, 34673.65326346805, 34782.0, 35274.294514110086, 23788.88105559461, 24736.876315618305, 24906.501869962136, 25356.492870142123, 25523.23430297094], "value": 17644.816406933696, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.6877717255511, 571.2174321179984, 572.9770809167634, 579.9072148456247, 584.918111464395, 22361.440963975907, 22897.656535151986, 23217.76782232177, 23306.300810975677, 23327.883360583215, 25976.48047039058, 26613.733862661367, 26693.599596006028, 26793.0, 26834.463310733772, 6560.540762146652, 6650.5344625876205, 6679.565828221174, 6684.598924064548, 6708.127943367355, 6009.369016253274, 6518.771842985497, 6525.738970441176, 6531.118299029617, 6604.0, 6467.126937863399, 6493.512986526006, 6498.870022599545, 6509.869802603968, 6510.511711621623, 6479.967600161993, 6487.18910136233, 6491.383318584727, 6504.869902601945, 6507.251666058417, 6037.426444487766, 6081.969590152021, 6093.055576385667, 6094.299155597118, 6118.571699981003, 27944.580831287545, 29167.562486562718, 29268.41463170746, 29423.852880735674, 29636.851815741, 1958.451633542596, 1986.9503262418411, 1990.800919908012, 1991.2035185925745, 1991.6415045291842, 19107.140178691854, 19644.214231430724, 19692.40922772319, 19695.0, 19715.605687886164, 2357.2456813819585, 2410.9758902410927, 2411.1801987324393, 2428.7935525480357, 2480.3055144559553, 135931.32034339863, 143781.0, 146213.26893365465, 146390.26804866013, 147046.26476867654, 14762.114273143647, 15169.469068582546, 15326.616834579225, 15431.456854314478, 15660.843391566166, 23682.171124010576, 23744.525109497714, 23749.762502375102, 23774.16790412362, 23889.880550597485, 10571.418571978615, 10681.893181068246, 10689.198310126772, 10755.408452535186, 10863.945680271549, 10099.798004039878, 10110.090091891765, 10118.949405252926, 10136.239782016379, 10273.229507786797, 9292.442453452815, 9302.0, 9523.095305945924, 9600.855987160197, 9742.902570974342, 2631.2369412870053, 2660.8935642574465, 2694.9865250674015, 2778.5554311310498, 2809.90165344212, 941.1388579449726, 966.1256562810668, 970.9126178643814, 975.8487434447673, 980.0738302304254, 19169.233230670616, 19432.0, 19489.0, 19714.30999915025, 19888.3039093631, 1004.8743907011624, 1050.0864248104199, 1054.267284237463, 1059.1368035051407, 1060.2101434431245, 10662.520186591622, 11277.266977646426, 13281.26953017574, 14049.859501405059, 14212.360443780055, 487040.0, 510937.0, 540846.0, 542921.0, 546383.0, 125779.0, 126023.0, 126109.0, 127502.36248818882, 128062.35968820282, 1270.7776139175608, 1270.993645031769, 1311.6851955530603, 1315.6579289384638, 1325.8077578750833, 20505.487362815453, 20684.793152068287, 20869.1652333911, 20873.582528349045, 20920.47698807481, 57021.0, 57236.42763572311, 57727.4227257722, 59735.0, 60422.69788651029, 71308.64345678239, 71460.64269678618, 71734.64132679511, 72049.47826826035, 72298.63850680712, 12218.633440996784, 14616.780748288997, 14702.0, 14831.332590033258, 14858.108513489226, 23027.424314391603, 23345.76654233436, 23997.520049598563, 25442.363940901625, 25570.23289301324, 19060.1422935971, 19146.329878454188, 19394.127264272858, 19442.805571944664, 19468.31860884862, 13911.652208694864, 17123.486295411156, 17785.644287114443, 17814.198361074043, 18014.0, 18722.438326850217, 20041.899790500953, 20056.298029568894, 20115.698264525752, 20253.49366265855, 14794.63013424673, 15199.392024319346, 15200.847991519944, 15214.163221022507, 15218.163001034607, 18097.81902181014, 18124.18441170124, 18265.543361416072, 18293.725594115833, 18373.632527349644], "value": 12317.791662511467, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.9932150339248, 1423.0963338280192, 1427.8001079848823, 1430.299153414827, 1440.2419366912363, 14114.788278175824, 14215.928920355409, 14720.26398680067, 14725.337359818806, 14748.778768318483, 14702.705945881076, 15033.774493382609, 15064.698706025873, 15086.3211155498, 15088.47190348338, 2706.174616741893, 2760.9585856212175, 2764.5162096633007, 2767.9169624911283, 2776.1532732516575, 2592.64999225105, 2724.0738149029326, 2766.0318888389, 2766.0318888389, 2769.2661444717164, 2681.5575430054114, 2687.422204226091, 2725.2641786717572, 2727.2227415186676, 2729.5359788835844, 2660.082271616299, 2664.800139989499, 2678.504476671822, 2679.289988153141, 2682.4500977299804, 2642.656454660901, 2651.6420283261705, 2655.933601659974, 2663.8401695898115, 2670.6661667291587, 13884.861151388457, 14089.154650720888, 14136.0, 14259.714805703828, 14293.642658933506, 7553.169151393342, 7853.057633084067, 7874.133845277015, 7877.527348359117, 7885.842283154362, 5195.244996240008, 5311.654742441729, 5432.348118225838, 5449.0, 5497.615166938316, 1887.565859852228, 1935.3516571948414, 1953.306576165455, 1983.2166294313665, 1999.3402177281462, 23873.641895371584, 24145.275641730776, 24535.0, 24567.0, 24584.631230531555, 6951.200611929592, 7076.452477108237, 7146.464015198881, 7183.8563228736175, 7245.529040612343, 18072.819271807377, 18079.27682892696, 18084.90957545204, 18090.819091808913, 18110.366137185134, 7583.0, 7937.087234967938, 8142.959285203536, 8144.470609410369, 8160.714374996846, 7149.9285007149265, 7219.3502584766575, 7264.237255088246, 7294.744683936036, 7300.6349682515665, 5120.283160357553, 5229.581633469314, 5256.684598924076, 5293.867653308698, 5352.652077614943, 1516.408600645749, 1521.9619509512129, 1527.1982209340185, 1528.2817075974274, 1538.5845821628322, 818.0, 835.1898658301438, 842.9747107586783, 848.6478111583624, 864.6800683746928, 6716.361945615159, 6733.764318248838, 6758.05387245786, 6823.897641535381, 6871.793846184622, 707.2397173038901, 712.8716830970371, 728.1771598094225, 730.9086364204475, 732.0410262556069, 4363.890902727394, 4758.5241475852135, 4850.83022094225, 4851.296561998491, 4866.440359358719, 19901.0, 20393.98030098519, 20546.486337841376, 20582.27962021322, 20628.484287892923, 19373.0, 19940.50148746293, 19951.700724489143, 19984.100715467837, 20000.0, 10600.151987840954, 10942.343459392461, 10952.233343665866, 11055.72360690973, 11172.329660220414, 7446.478746487694, 7667.884981725167, 7734.187910269566, 7810.179931107379, 7849.254320839511, 17286.394976175772, 17347.306107755547, 17480.82519174792, 17768.111594420487, 18066.728999065286, 18030.2787888483, 18268.543286417946, 18306.26774928988, 18307.35924242645, 18500.0, 10501.527431265458, 10718.303310284806, 10825.188110891557, 10838.512266947848, 10849.67450976472, 13318.733625327246, 13472.461101555828, 13543.525976590772, 13610.115342502706, 13625.182489050689, 10213.029762172582, 10288.691339259834, 10354.326968747007, 10511.159107271553, 10574.788504230026, 10653.360798352123, 10770.0, 11030.22788404804, 11039.227254092137, 11327.263727857662, 9625.807483850424, 9816.803663926825, 9832.262580306362, 9835.114839664464, 9836.852447213441, 6540.182477190351, 6604.768833090819, 6606.735730570724, 6665.0, 7171.928280717335, 7156.713731450684, 7216.242294559206, 7277.3086556777025, 7287.1984081752025, 7291.6354182289615], "value": 6433.678745777002, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.000099798407, 497.83073754923316, 530.1014779947985, 530.9275263966787, 533.6424595521, 6200.844978875531, 6214.254289485262, 6255.562110652256, 6277.497800175986, 6309.0, 5854.414558544144, 6037.184980027695, 6084.452399284063, 6112.633242005483, 6146.784862529812, 4947.455779864212, 4952.529509696573, 4960.751962401883, 4978.676386034906, 4997.275395067713, 4944.7638090477385, 4953.0, 5008.0, 5062.493750624926, 5140.871478213037, 5107.157319042372, 5129.281900533928, 5150.33045704058, 5174.240758142231, 5275.525202731754, 5110.080185566597, 5131.997781297945, 5157.5616072633875, 5204.661696989702, 5241.9213711794355, 4783.4977327380475, 4836.903261934742, 4846.5880400166025, 4846.806127754886, 4890.902181956377, 5998.3701711320555, 6091.360407157229, 6123.357047510036, 6153.600015998945, 6183.1961844960315, 6050.033739034831, 6056.21269235001, 6056.697165141725, 6091.2995005574485, 6144.354842741493, 6660.300668429793, 6671.633060181688, 6688.598684078971, 6748.966255168692, 6759.0, 5298.0, 5364.9731751341, 5375.381831089397, 5378.865528361822, 5378.892422151535, 6987.965060174666, 7010.439164866799, 7041.859162816766, 7111.0, 7118.572885626827, 5515.255440515479, 5521.834344969658, 5557.194206839986, 5560.972195138998, 5577.9442205577425, 5596.3284405870745, 5646.774129034792, 5649.293838270216, 5663.94147160537, 5673.461021202979, 6004.995004995005, 6092.482139018144, 6127.693615319216, 6128.4790792783115, 6134.325224225332, 6586.769463068769, 6614.966925165343, 6687.832804179837, 6694.799156025327, 6699.631520266432, 1832.7709036370454, 1838.503604026932, 1876.2495001999037, 1940.835029022549, 1962.1464662871697, 1449.5651304608575, 1461.758809796393, 1463.2391156598721, 1463.948761793332, 1478.1574502533454, 5821.621594596338, 5829.7376618052285, 5838.182654428383, 5841.50347220491, 5845.473907348359, 6659.500537459709, 6706.664666766642, 6794.8301292467095, 6831.4629208427705, 6867.519273650896, 5816.883662326814, 5826.2134611827705, 5827.24245848037, 5834.445727655866, 5909.852253693692, 3515.8417871195347, 3595.047312462147, 3602.6757591816863, 3627.8548858045383, 3648.1426864686778, 6593.80218593443, 6595.538312318092, 6601.61366113112, 6636.83407914806, 6667.566608170453, 7017.228104908456, 7032.156141262979, 7045.788626341218, 7046.295370462913, 7067.5052746307265, 6504.9674751625935, 6580.5722628029025, 6598.340165983459, 6623.575931174821, 6642.867142657216, 6745.0, 6768.830779230559, 6795.966020169867, 6802.93197068023, 6803.251642319242, 6745.662716864235, 6746.4940129489505, 6754.223264324665, 6767.661616919036, 6799.728010879509, 6535.57518761279, 6575.638339891352, 6592.934070659424, 6599.053279282719, 6621.834454138492, 5037.0, 5078.314427552233, 5124.513890971725, 5128.1794912813775, 5133.6919784813035, 6885.1115707847985, 6891.138607674041, 6910.619915904473, 6912.36642136657, 6919.342662447059, 6314.936850631435, 6430.903536446864, 6467.288598254094, 6554.0, 6591.6374599397495, 6548.410643042149, 6565.474762019123, 6594.868102637824, 6600.2409722882485, 6609.735610575523, 6511.153550038464, 6529.183852018497, 6532.673366331569, 6543.70553325092, 6559.245686745894, 6531.542792004704, 6548.3124271950755, 6598.934010659832, 6625.687156421789, 6631.867362652816, 6323.494120470259, 6331.303556608861, 6385.7126429309865, 6392.776252831314, 6482.124913136666], "value": 5215.57101870073, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.1060843680391, 820.6389188756942, 838.1367191792448, 909.6179604566081, 925.4817302310704, 18386.172622232, 18869.71695424568, 18949.99999999998, 19272.710909336372, 22758.544829103408, 20220.59558808823, 21571.24500642478, 21642.134314627398, 21672.3498295051, 21745.13019479219, 984.670135504605, 1005.9094681478665, 1008.425197637347, 1008.4755926917999, 1009.369144284822, 980.3921568627461, 1075.42464781342, 1080.0495563903753, 1081.5457507846684, 1081.9891801081967, 1106.115107913666, 1115.0577761791274, 1118.3848883114294, 1118.8601424821898, 1119.0432180485689, 1117.284937639911, 1118.6308518189019, 1118.8713297970755, 1120.2830188679252, 1122.5229277557046, 821.1501096365233, 821.5850995247422, 822.0669540072001, 822.1039067416544, 822.7202751064607, 19513.219471221135, 19837.702434463496, 19917.30289439877, 19977.200911963504, 20094.598108037757, 21511.46221344479, 21528.0, 22050.4487387815, 22117.33647990563, 22185.667214991787, 17197.140142992925, 17234.396796112076, 17294.537622399926, 17388.304467821272, 17514.12429378526, 3699.7780133192095, 3701.796401197933, 3709.443583462475, 3716.6655001050035, 3719.5722491913234, 126616.0, 126649.0, 126804.36597817045, 130970.0, 131559.0, 16561.0, 17059.402920897708, 17210.0, 17317.91341043312, 17787.822121778874, 31204.0, 31709.36581268408, 31820.522692159637, 32290.83854580759, 32481.35037299241, 7646.9617651912495, 7809.297163255334, 8078.111407745143, 8927.866082008775, 9116.954415227881, 4283.443152390169, 4418.823247070145, 4426.11477704454, 4429.534898835575, 4439.289713645866, 5369.946300537023, 5414.485623865805, 5432.185172224119, 5480.588955828329, 5493.038718224375, 1103.939283339424, 1108.8059589571792, 1124.4995976790412, 1125.2966895690195, 1131.9037881779975, 658.8418779492887, 672.5460314287836, 681.6285124607101, 684.6439851277407, 688.6006116452446, 11349.319040857576, 11529.308241505536, 12090.576829810914, 12869.163504372185, 12976.740465190644, 647.3235468934975, 653.3433898931544, 664.1930054983122, 668.4051194436955, 669.7321071571411, 5427.538659214011, 5428.511433970962, 5560.193771903051, 5962.0, 6412.9679351602945, 107320.0, 108881.45559272153, 112379.4381028106, 112948.43525782482, 118286.0, 93467.0, 93859.0, 95196.0480395201, 96126.51936740271, 97352.0, 86957.56521217353, 87092.0, 87779.0, 90130.09869901217, 90194.54902725444, 12651.114421990373, 12767.106302558732, 12845.293508857103, 12907.419166137734, 12949.158304710163, 38576.22847543089, 38874.61125388823, 39011.6098839008, 39473.60526394699, 41002.58997409988, 49726.25410618916, 50852.237216442525, 51597.74201129121, 52329.738351308, 53227.201591976926, 18022.189001494702, 18677.90661046686, 19332.0, 19356.61286774285, 19537.706934395712, 19885.0, 20295.28966486166, 20686.689699654216, 20827.0, 21023.369298920446, 17911.0, 18226.270949162415, 18362.0, 18787.812121879153, 19714.21143154315, 17566.736498952272, 18597.34909278169, 19624.607507849476, 20113.497162571053, 20338.288159914333, 17604.823951760318, 18594.442166735018, 18687.972161531245, 18710.0, 18789.530261743566, 13135.868641313464, 13360.0, 13506.0, 13615.0, 13778.173309601456, 13897.112048523817, 14127.43490260378, 14310.928445357707, 14771.0, 14929.10425374481], "value": 9277.82322246144, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1536.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 109.0, 103.0, 108.0, 108.0, 114.0, 111.0, 110.0, 108.0, 108.0], "value": 107.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 117.0, 149.0, 180.0, 122.0, 145.0, 144.0, 131.0, 188.0, 119.0], "value": 143.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 164.0, 162.0, 164.0, 167.0, 166.0, 165.0, 163.0, 165.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 75.0, 75.0, 153.0, 76.0, 76.0, 74.0, 89.0, 109.0, 76.0], "value": 88.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 135.0, 79.0, 81.0, 79.0, 133.0, 79.0, 79.0, 124.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 115.0, 114.0, 114.0, 115.0, 115.0, 117.0, 115.0, 114.0, 116.0], "value": 116.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 156.0, 149.0, 150.0, 151.0, 150.0, 152.0, 149.0, 154.0, 150.0], "value": 151.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 98.0, 94.0, 94.0, 95.0, 94.0, 96.0, 94.0, 94.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 56.0, 59.0, 58.0, 62.0, 58.0, 55.0, 55.0, 56.0], "value": 57.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 67.0, 55.0, 55.0, 56.0, 56.0, 58.0, 55.0, 58.0, 62.0], "value": 57.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 89.0, 78.0, 78.0, 79.0, 78.0, 84.0, 78.0, 78.0], "value": 79.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 126.0, 129.0, 126.0, 122.0, 138.0, 131.0, 124.0, 134.0, 169.0], "value": 133.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 175.0, 186.0, 177.0, 176.0, 175.0, 181.0, 170.0, 180.0, 173.0], "value": 177.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 76.0, 67.0, 67.0, 76.0, 68.0, 70.0, 70.0, 70.0], "value": 70.4, "unit": "score"}]}]} 10:59:17 INFO - Return code: 0 10:59:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:59:17 INFO - # TBPL SUCCESS # 10:59:17 INFO - Running post-action listener: _package_coverage_data 10:59:17 INFO - Running post-action listener: _resource_record_post_action 10:59:17 INFO - [mozharness: 2017-08-12 17:59:17.542265Z] Finished run-tests step (success) 10:59:17 INFO - Running post-run listener: _resource_record_post_run 10:59:17 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 5206016; Write bytes: 439812096; Read time: 1840; Write time: 803368 10:59:17 INFO - TinderboxPrint: CPU usage
13.2% 10:59:17 INFO - TinderboxPrint: I/O read bytes / time
5,206,016 / 1,840 10:59:17 INFO - TinderboxPrint: I/O write bytes / time
439,812,096 / 803,368 10:59:17 INFO - TinderboxPrint: CPU idle
8,313.3 (86.8%) 10:59:17 INFO - TinderboxPrint: CPU user
1,202.4 (12.6%) 10:59:17 INFO - TinderboxPrint: Swap in / out
0 / 0 10:59:17 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 117399552; Read time: 376; Write time: 181752 10:59:17 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:59:17 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 2969600; Write bytes: 322412544; Read time: 1260; Write time: 621616 10:59:18 INFO - Running post-run listener: _upload_blobber_files 10:59:18 INFO - Blob upload gear active. 10:59:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:59:18 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 10:59:18 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'] 10:59:18 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 10:59:18 INFO - (blobuploader) - INFO - Open directory for files ... 10:59:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:59:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:59:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:59:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:59:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:59:19 INFO - (blobuploader) - INFO - Done attempting. 10:59:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:59:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:59:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:59:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:59:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:59:22 INFO - (blobuploader) - INFO - Done attempting. 10:59:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:59:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:59:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:59:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:59:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:59:23 INFO - (blobuploader) - INFO - Done attempting. 10:59:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:59:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:59:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:59:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:59:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:59:23 INFO - (blobuploader) - INFO - Done attempting. 10:59:23 INFO - (blobuploader) - INFO - Iteration through files over. 10:59:23 INFO - Return code: 0 10:59:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:59:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:59:23 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/351f0ff9f15adf76e2b1980e5fd032b267ee8588426b5bbecdd2a9ea0e4207822ccbb411297f3d0cff80d5cffa2a10b66b911515f65715a5f222619b6cc76efc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adc7df288ea6a3c283b9d847ae6e5f1aad2154e128d00731620ca060f676c9f3a755d2882dc66d439b30a2da7e4e8a30abd4e5145f08be5990f27fed7efc0b72", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88190f040a4f34cc4c653aa3292496d095cec2be303debf70f7a725842218af9ef5af13ed4941529d1872e72ee920a100de0b37c97df6612c10b4b6ffa1d86b1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b20aafd0335728cc631f58087391feac5ee9aa2e1ed32893f405acf661d9bd66760885df9bd10d0489ca1683f9a8f73927fb47dce8acf63af41f2f70a97210b5"} 10:59:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:59:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:59:23 INFO - Contents: 10:59:23 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/351f0ff9f15adf76e2b1980e5fd032b267ee8588426b5bbecdd2a9ea0e4207822ccbb411297f3d0cff80d5cffa2a10b66b911515f65715a5f222619b6cc76efc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adc7df288ea6a3c283b9d847ae6e5f1aad2154e128d00731620ca060f676c9f3a755d2882dc66d439b30a2da7e4e8a30abd4e5145f08be5990f27fed7efc0b72", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88190f040a4f34cc4c653aa3292496d095cec2be303debf70f7a725842218af9ef5af13ed4941529d1872e72ee920a100de0b37c97df6612c10b4b6ffa1d86b1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b20aafd0335728cc631f58087391feac5ee9aa2e1ed32893f405acf661d9bd66760885df9bd10d0489ca1683f9a8f73927fb47dce8acf63af41f2f70a97210b5"} 10:59:23 INFO - Running post-run listener: copy_logs_to_upload_dir 10:59:23 INFO - Copying logs to upload dir... 10:59:23 INFO - mkdir: /builds/slave/test/build/upload/logs 10:59:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.877592 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-08-12 10:59:24.163034) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-12 10:59:24.171530) ========= 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=df17445c3590ff7897617a0e00000225-1502557983.913160-761455044 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/351f0ff9f15adf76e2b1980e5fd032b267ee8588426b5bbecdd2a9ea0e4207822ccbb411297f3d0cff80d5cffa2a10b66b911515f65715a5f222619b6cc76efc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adc7df288ea6a3c283b9d847ae6e5f1aad2154e128d00731620ca060f676c9f3a755d2882dc66d439b30a2da7e4e8a30abd4e5145f08be5990f27fed7efc0b72", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88190f040a4f34cc4c653aa3292496d095cec2be303debf70f7a725842218af9ef5af13ed4941529d1872e72ee920a100de0b37c97df6612c10b4b6ffa1d86b1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b20aafd0335728cc631f58087391feac5ee9aa2e1ed32893f405acf661d9bd66760885df9bd10d0489ca1683f9a8f73927fb47dce8acf63af41f2f70a97210b5"} build_url:https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011193 build_url: 'https://queue.taskcluster.net/v1/task/equmlGMdQTeuA3YD9CXIvQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/351f0ff9f15adf76e2b1980e5fd032b267ee8588426b5bbecdd2a9ea0e4207822ccbb411297f3d0cff80d5cffa2a10b66b911515f65715a5f222619b6cc76efc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adc7df288ea6a3c283b9d847ae6e5f1aad2154e128d00731620ca060f676c9f3a755d2882dc66d439b30a2da7e4e8a30abd4e5145f08be5990f27fed7efc0b72", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88190f040a4f34cc4c653aa3292496d095cec2be303debf70f7a725842218af9ef5af13ed4941529d1872e72ee920a100de0b37c97df6612c10b4b6ffa1d86b1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b20aafd0335728cc631f58087391feac5ee9aa2e1ed32893f405acf661d9bd66760885df9bd10d0489ca1683f9a8f73927fb47dce8acf63af41f2f70a97210b5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-12 10:59:24.204015) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 10:59:24.204337) ========= 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=df17445c3590ff7897617a0e00000225-1502557983.913160-761455044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004487 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 10:59:24.286445) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-12 10:59:24.286755) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-12 10:59:24.297553) ========= ========= Total master_lag: 0.25 =========