builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-030 starttime: 1501807400.57 results: success (0) revision: 5742919ec43f834cc061a96d87c767af1a1f7f75 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-03 17:43:20.567593) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-03 17:43:20.568098) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-03 17:43:20.568397) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-03 17:43:20.677925) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 17:43:20.678175) ========= 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=3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-03 17:43:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 226M=0s 2017-08-03 17:43:20 (226 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113745 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 17:43:20.807273) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 17:43:20.807647) ========= 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=3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021024 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 17:43:20.858103) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 52 secs) (at 2017-08-03 17:43:20.858486) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5742919ec43f834cc061a96d87c767af1a1f7f75 --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 5742919ec43f834cc061a96d87c767af1a1f7f75 --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=3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-03 17:43:21,012 truncating revision to first 12 chars 2017-08-03 17:43:21,012 Setting DEBUG logging. 2017-08-03 17:43:21,012 attempt 1/10 2017-08-03 17:43:21,012 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5742919ec43f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-03 17:43:21,547 attempt 1/10 2017-08-03 17:43:22,142 current task status: no status available at this point. state: PENDING 2017-08-03 17:43:22,143 sleeping for 10.00s (attempt 1/10) 2017-08-03 17:43:32,153 attempt 2/10 2017-08-03 17:43:32,717 current task status: no status available at this point. state: PENDING 2017-08-03 17:43:32,717 sleeping for 16.00s (attempt 2/10) 2017-08-03 17:43:48,734 attempt 3/10 2017-08-03 17:43:49,253 current task status: no status available at this point. state: PENDING 2017-08-03 17:43:49,253 sleeping for 23.00s (attempt 3/10) 2017-08-03 17:44:12,277 attempt 4/10 2017-08-03 17:44:12,587 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-03 17:44:12,833 unpacking tar archive at: mozilla-central-5742919ec43f/testing/mozharness/ program finished with exit code 0 elapsedTime=52.127723 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 52 secs) (at 2017-08-03 17:44:13.000884) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-03 17:44:13.001555) ========= script_repo_revision: 5742919ec43f834cc061a96d87c767af1a1f7f75 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-03 17:44:13.002187) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-03 17:44:13.002638) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-03 17:44:13.013784) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-03 17:44:13.014205) ========= /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=3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:44:13 INFO - MultiFileLogger online at 20170803 17:44:13 in /builds/slave/test 17:44:13 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 17:44:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:44:13 INFO - {'append_to_log': False, 17:44:13 INFO - 'base_work_dir': '/builds/slave/test', 17:44:13 INFO - 'blob_upload_branch': 'Firefox', 17:44:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:44:13 INFO - 'branch': 'Firefox', 17:44:13 INFO - 'buildbot_json_path': 'buildprops.json', 17:44:13 INFO - 'code_coverage': False, 17:44:13 INFO - 'config_files': ('talos/linux_config.py',), 17:44:13 INFO - 'default_actions': ('clobber', 17:44:13 INFO - 'read-buildbot-config', 17:44:13 INFO - 'download-and-extract', 17:44:13 INFO - 'populate-webroot', 17:44:13 INFO - 'create-virtualenv', 17:44:13 INFO - 'install', 17:44:13 INFO - 'setup-mitmproxy', 17:44:13 INFO - 'run-tests'), 17:44:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:44:13 INFO - 'disable_ccov_upload': False, 17:44:13 INFO - 'download_minidump_stackwalk': True, 17:44:13 INFO - 'download_symbols': 'ondemand', 17:44:13 INFO - 'enable_stylo': False, 17:44:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:44:13 INFO - 'tooltool.py': '/tools/tooltool.py', 17:44:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:44:13 INFO - '/tools/misc-python/virtualenv.py')}, 17:44:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:44:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:44:13 INFO - 'gecko_profile': False, 17:44:13 INFO - 'gecko_profile_interval': 0, 17:44:13 INFO - 'installer_path': 'installer.exe', 17:44:13 INFO - 'log_level': 'info', 17:44:13 INFO - 'log_name': 'talos', 17:44:13 INFO - 'log_to_console': True, 17:44:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:44:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:44:13 INFO - 'opt_config_files': (), 17:44:13 INFO - 'pip_index': False, 17:44:13 INFO - 'suite': 'dromaeojs-e10s', 17:44:13 INFO - 'system_bits': '32', 17:44:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:44:13 INFO - 'title': 'talos-linux64-ix-030', 17:44:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:44:13 INFO - 'use_talos_json': True, 17:44:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:44:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:44:13 INFO - 'work_dir': 'build'} 17:44:13 INFO - [mozharness: 2017-08-04 00:44:13.274939Z] Running clobber step. 17:44:13 INFO - Running pre-action listener: _resource_record_pre_action 17:44:13 INFO - Running main action method: clobber 17:44:13 INFO - rmtree: /builds/slave/test/build 17:44:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:44:14 INFO - Running post-action listener: _resource_record_post_action 17:44:14 INFO - [mozharness: 2017-08-04 00:44:14.322117Z] Finished clobber step (success) 17:44:14 INFO - [mozharness: 2017-08-04 00:44:14.322220Z] Running read-buildbot-config step. 17:44:14 INFO - Running pre-action listener: _resource_record_pre_action 17:44:14 INFO - Running main action method: read_buildbot_config 17:44:14 INFO - Using buildbot properties: 17:44:14 INFO - { 17:44:14 INFO - "project": "", 17:44:14 INFO - "product": "firefox", 17:44:14 INFO - "who": "kwierso@gmail.com", 17:44:14 INFO - "installer_path": "public/build/target.tar.bz2", 17:44:14 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 17:44:14 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:44:14 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 17:44:14 INFO - "stage_platform": "linux64-stylosequential", 17:44:14 INFO - "basedir": "/builds/slave/test", 17:44:14 INFO - "buildnumber": 168, 17:44:14 INFO - "platform": "ubuntu64_hw_styloseq", 17:44:14 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:44:14 INFO - "slavebuilddir": "test", 17:44:14 INFO - "taskId": "aC5NuRG_Swm4nnR6CRbYGg", 17:44:14 INFO - "branch": "mozilla-central", 17:44:14 INFO - "script_repo_revision": "production", 17:44:14 INFO - "revision": "5742919ec43f834cc061a96d87c767af1a1f7f75", 17:44:14 INFO - "slavename": "talos-linux64-ix-030", 17:44:14 INFO - "repo_path": "mozilla-central" 17:44:14 INFO - } 17:44:14 INFO - Finding installer, test and symbols from parent task. 17:44:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aC5NuRG_Swm4nnR6CRbYGg'}, attempt #1 17:44:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aC5NuRG_Swm4nnR6CRbYGg'}, attempt #1 17:44:15 INFO - Task dependencies: HIjfhibRQUeCo_3pJKMQOQ 17:44:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ'}, attempt #1 17:44:15 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.tar.bz2 17:44:15 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.test_packages.json 17:44:15 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.crashreporter-symbols.zip 17:44:15 INFO - Running post-action listener: _resource_record_post_action 17:44:15 INFO - [mozharness: 2017-08-04 00:44:15.683862Z] Finished read-buildbot-config step (success) 17:44:15 INFO - [mozharness: 2017-08-04 00:44:15.684085Z] Running download-and-extract step. 17:44:15 INFO - Running pre-action listener: _resource_record_pre_action 17:44:15 INFO - Running main action method: download_and_extract 17:44:15 INFO - mkdir: /builds/slave/test/build/tests 17:44: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')]} 17:44:15 INFO - trying https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.test_packages.json 17:44:15 INFO - Downloading https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:44:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:44:16 INFO - Downloaded 989 bytes. 17:44:16 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:44:16 INFO - Using the following test package requirements: 17:44:16 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:44:16 INFO - u'common': [u'target.common.tests.zip'], 17:44:16 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:44:16 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:44:16 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:44:16 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:44:16 INFO - u'mozbase': [u'target.common.tests.zip'], 17:44:16 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:44:16 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:44:16 INFO - u'web-platform': [u'target.common.tests.zip', 17:44:16 INFO - u'target.web-platform.tests.tar.gz'], 17:44:16 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:44:16 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:44:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.common.tests.zip 17:44:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:44:16 INFO - Fetch https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.common.tests.zip into memory 17:44:17 INFO - Content-Length response header: 54278766 17:44:17 INFO - Bytes received: 54278766 17:44:28 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:44:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.common.tests.zip 17:44:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:44:28 INFO - Fetch https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.common.tests.zip into memory 17:44:29 INFO - Content-Length response header: 54278766 17:44:29 INFO - Bytes received: 54278766 17:44:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.talos.tests.zip 17:44:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:44:41 INFO - Fetch https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.talos.tests.zip into memory 17:44:42 INFO - Content-Length response header: 14496464 17:44:42 INFO - Bytes received: 14496464 17:44:42 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')]} 17:44:42 INFO - trying https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.tar.bz2 17:44:42 INFO - Downloading https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:44:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:44:43 INFO - Downloaded 62096722 bytes. 17:44:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.tar.bz2 17:44:43 INFO - mkdir: /builds/slave/test/properties 17:44:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:44:43 INFO - Writing to file /builds/slave/test/properties/build_url 17:44:43 INFO - Contents: 17:44:43 INFO - build_url:https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.tar.bz2 17:44:43 INFO - Running post-action listener: _resource_record_post_action 17:44:43 INFO - Running post-action listener: set_extra_try_arguments 17:44:43 INFO - [mozharness: 2017-08-04 00:44:43.484676Z] Finished download-and-extract step (success) 17:44:43 INFO - [mozharness: 2017-08-04 00:44:43.484774Z] Running populate-webroot step. 17:44:43 INFO - Running pre-action listener: _resource_record_pre_action 17:44:43 INFO - Running main action method: populate_webroot 17:44:43 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:44:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:44:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:44:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:44:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:44:43 INFO - 'tests': ['damp', 'tps']}, 17:44:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:44:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:44:43 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:44:43 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:44:43 INFO - 'other-e10s': {'tests': ['a11yr', 17:44:43 INFO - 'ts_paint', 17:44:43 INFO - 'tpaint', 17:44:43 INFO - 'sessionrestore', 17:44:43 INFO - 'sessionrestore_many_windows', 17:44:43 INFO - 'sessionrestore_no_auto_restore', 17:44:43 INFO - 'tabpaint']}, 17:44:43 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 17:44:43 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 17:44:43 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:44:43 INFO - 'talos_options': ['--mitmproxy', 17:44:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:44:43 INFO - '--firstNonBlankPaint'], 17:44:43 INFO - 'tests': ['quantum_pageload_google', 17:44:43 INFO - 'quantum_pageload_youtube', 17:44:43 INFO - 'quantum_pageload_amazon', 17:44:43 INFO - 'quantum_pageload_facebook']}, 17:44:43 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:44:43 INFO - 'talos_options': ['--stylo', 17:44:43 INFO - '--mitmproxy', 17:44:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:44:43 INFO - '--firstNonBlankPaint'], 17:44:43 INFO - 'tests': ['quantum_pageload_google', 17:44:43 INFO - 'quantum_pageload_youtube', 17:44:43 INFO - 'quantum_pageload_amazon', 17:44:43 INFO - 'quantum_pageload_facebook']}, 17:44:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:44:43 INFO - 'tsvgr_opacity', 17:44:43 INFO - 'tart', 17:44:43 INFO - 'tscrollx', 17:44:43 INFO - 'cart', 17:44:43 INFO - 'tsvg_static']}, 17:44:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:44:43 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:44:43 INFO - 'talos_options': ['--stylo', 17:44:43 INFO - '--stylo-threads=1', 17:44:43 INFO - '--mitmproxy', 17:44:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:44:43 INFO - '--firstNonBlankPaint'], 17:44:43 INFO - 'tests': ['tp6_google', 17:44:43 INFO - 'tp6_youtube', 17:44:43 INFO - 'tp6_amazon', 17:44:43 INFO - 'tp6_facebook']}, 17:44:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:44:43 INFO - 'talos_options': ['--xperf_path', 17:44:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:44:43 INFO - 'tests': ['tp5n']}}} 17:44:43 INFO - Running post-action listener: _resource_record_post_action 17:44:43 INFO - [mozharness: 2017-08-04 00:44:43.493372Z] Finished populate-webroot step (success) 17:44:43 INFO - [mozharness: 2017-08-04 00:44:43.493463Z] Running create-virtualenv step. 17:44:43 INFO - Running pre-action listener: _resource_record_pre_action 17:44:43 INFO - Running main action method: create_virtualenv 17:44:43 INFO - Creating virtualenv /builds/slave/test/build/venv 17:44:43 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:44:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:44:43 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:44:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:44:43 INFO - Using real prefix '/usr' 17:44:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:44:44 INFO - Installing distribute.............................................................................................................................................................................................done. 17:44:46 INFO - Installing pip.................done. 17:44:46 INFO - Return code: 0 17:44:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:44:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:44:46 INFO - Reading from file tmpfile_stdout 17:44:46 INFO - Output received: 17:44:46 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:44:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:44:46 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')]} 17:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:46 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')]} 17:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x19311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9deb0>, '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 0x1c9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f240>, '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': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', '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 17:44:46 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 17:44:46 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 17:44:46 INFO - Using env: {'DISPLAY': ':0', 17:44:46 INFO - 'HOME': '/home/cltbld', 17:44:46 INFO - 'LANG': 'en_US.UTF-8', 17:44:46 INFO - 'LANGUAGE': 'en_US:en', 17:44:46 INFO - 'LOGNAME': 'cltbld', 17:44:46 INFO - 'MAIL': '/var/mail/cltbld', 17:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:46 INFO - 'MOZ_NO_REMOTE': '1', 17:44:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:46 INFO - 'NO_EM_RESTART': '1', 17:44:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:46 INFO - 'PWD': '/builds/slave/test', 17:44:46 INFO - 'SHELL': '/bin/bash', 17:44:46 INFO - 'SHLVL': '1', 17:44:46 INFO - 'TERM': 'linux', 17:44:46 INFO - 'TMOUT': '86400', 17:44:46 INFO - 'USER': 'cltbld', 17:44:46 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', 17:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:46 INFO - '_': '/tools/buildbot/bin/python'} 17:44:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:46 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:44:46 INFO - Cleaning up... 17:44:46 INFO - Return code: 0 17:44:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:44:46 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')]} 17:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:46 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')]} 17:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x19311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9deb0>, '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 0x1c9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f240>, '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': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', '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 17:44:46 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 17:44:46 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 17:44:46 INFO - Using env: {'DISPLAY': ':0', 17:44:46 INFO - 'HOME': '/home/cltbld', 17:44:46 INFO - 'LANG': 'en_US.UTF-8', 17:44:46 INFO - 'LANGUAGE': 'en_US:en', 17:44:46 INFO - 'LOGNAME': 'cltbld', 17:44:46 INFO - 'MAIL': '/var/mail/cltbld', 17:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:46 INFO - 'MOZ_NO_REMOTE': '1', 17:44:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:46 INFO - 'NO_EM_RESTART': '1', 17:44:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:46 INFO - 'PWD': '/builds/slave/test', 17:44:46 INFO - 'SHELL': '/bin/bash', 17:44:46 INFO - 'SHLVL': '1', 17:44:46 INFO - 'TERM': 'linux', 17:44:46 INFO - 'TMOUT': '86400', 17:44:46 INFO - 'USER': 'cltbld', 17:44:46 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', 17:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:46 INFO - '_': '/tools/buildbot/bin/python'} 17:44:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:46 INFO - Downloading/unpacking psutil>=3.1.1 17:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:44:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:44:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:44:47 INFO - Installing collected packages: psutil 17:44:47 INFO - Running setup.py install for psutil 17:44:47 INFO - building 'psutil._psutil_linux' extension 17:44:47 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 17:44:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 17:44:48 INFO - building 'psutil._psutil_posix' extension 17:44:48 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 17:44:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 17:44:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:44:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:44:48 INFO - Successfully installed psutil 17:44:48 INFO - Cleaning up... 17:44:48 INFO - Return code: 0 17:44:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:44:48 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')]} 17:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:48 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')]} 17:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:48 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 0x19311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9deb0>, '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 0x1c9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f240>, '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': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', '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 17:44:48 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 17:44:48 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 17:44:48 INFO - Using env: {'DISPLAY': ':0', 17:44:48 INFO - 'HOME': '/home/cltbld', 17:44:48 INFO - 'LANG': 'en_US.UTF-8', 17:44:48 INFO - 'LANGUAGE': 'en_US:en', 17:44:48 INFO - 'LOGNAME': 'cltbld', 17:44:48 INFO - 'MAIL': '/var/mail/cltbld', 17:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:48 INFO - 'MOZ_NO_REMOTE': '1', 17:44:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:48 INFO - 'NO_EM_RESTART': '1', 17:44:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:48 INFO - 'PWD': '/builds/slave/test', 17:44:48 INFO - 'SHELL': '/bin/bash', 17:44:48 INFO - 'SHLVL': '1', 17:44:48 INFO - 'TERM': 'linux', 17:44:48 INFO - 'TMOUT': '86400', 17:44:48 INFO - 'USER': 'cltbld', 17:44:48 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', 17:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:48 INFO - '_': '/tools/buildbot/bin/python'} 17:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:44:48 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 17:44:48 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 17:44:48 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 17:44:48 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 17:44:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:44:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:44:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:44:49 INFO - Installing collected packages: mozsystemmonitor 17:44:49 INFO - Running setup.py install for mozsystemmonitor 17:44:49 INFO - Successfully installed mozsystemmonitor 17:44:49 INFO - Cleaning up... 17:44:49 INFO - Return code: 0 17:44:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:44:49 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')]} 17:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:49 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')]} 17:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x19311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9deb0>, '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 0x1c9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f240>, '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': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', '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 17:44:49 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 17:44:49 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 17:44:49 INFO - Using env: {'DISPLAY': ':0', 17:44:49 INFO - 'HOME': '/home/cltbld', 17:44:49 INFO - 'LANG': 'en_US.UTF-8', 17:44:49 INFO - 'LANGUAGE': 'en_US:en', 17:44:49 INFO - 'LOGNAME': 'cltbld', 17:44:49 INFO - 'MAIL': '/var/mail/cltbld', 17:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:49 INFO - 'MOZ_NO_REMOTE': '1', 17:44:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:49 INFO - 'NO_EM_RESTART': '1', 17:44:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:49 INFO - 'PWD': '/builds/slave/test', 17:44:49 INFO - 'SHELL': '/bin/bash', 17:44:49 INFO - 'SHLVL': '1', 17:44:49 INFO - 'TERM': 'linux', 17:44:49 INFO - 'TMOUT': '86400', 17:44:49 INFO - 'USER': 'cltbld', 17:44:49 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', 17:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:49 INFO - '_': '/tools/buildbot/bin/python'} 17:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:50 INFO - Downloading/unpacking jsonschema==2.5.1 17:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:44:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:51 INFO - Downloading functools32-3.2.3-2.tar.gz 17:44:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:44:51 INFO - warning: no files found matching '*.txt' 17:44:51 INFO - no previously-included directories found matching 'build' 17:44:51 INFO - no previously-included directories found matching 'dist' 17:44:51 INFO - no previously-included directories found matching '.git*' 17:44:51 INFO - Installing collected packages: jsonschema, functools32 17:44:51 INFO - Running setup.py install for functools32 17:44:51 INFO - warning: no files found matching '*.txt' 17:44:51 INFO - no previously-included directories found matching 'build' 17:44:51 INFO - no previously-included directories found matching 'dist' 17:44:51 INFO - no previously-included directories found matching '.git*' 17:44:51 INFO - Successfully installed jsonschema functools32 17:44:51 INFO - Cleaning up... 17:44:51 INFO - Return code: 0 17:44:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:44: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')]} 17:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44: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')]} 17:44:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x19311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9deb0>, '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 0x1c9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f240>, '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': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', '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 17:44:51 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 17:44:51 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 17:44:51 INFO - Using env: {'DISPLAY': ':0', 17:44:51 INFO - 'HOME': '/home/cltbld', 17:44:51 INFO - 'LANG': 'en_US.UTF-8', 17:44:51 INFO - 'LANGUAGE': 'en_US:en', 17:44:51 INFO - 'LOGNAME': 'cltbld', 17:44:51 INFO - 'MAIL': '/var/mail/cltbld', 17:44:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:51 INFO - 'MOZ_NO_REMOTE': '1', 17:44:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:51 INFO - 'NO_EM_RESTART': '1', 17:44:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:51 INFO - 'PWD': '/builds/slave/test', 17:44:51 INFO - 'SHELL': '/bin/bash', 17:44:51 INFO - 'SHLVL': '1', 17:44:51 INFO - 'TERM': 'linux', 17:44:51 INFO - 'TMOUT': '86400', 17:44:51 INFO - 'USER': 'cltbld', 17:44:51 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', 17:44:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:51 INFO - '_': '/tools/buildbot/bin/python'} 17:44:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:44:51 INFO - Cleaning up... 17:44:51 INFO - Return code: 0 17:44:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:44: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')]} 17:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44: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')]} 17:44:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x19311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9deb0>, '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 0x1c9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f240>, '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': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', '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 17:44:51 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 17:44:51 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 17:44:51 INFO - Using env: {'DISPLAY': ':0', 17:44:51 INFO - 'HOME': '/home/cltbld', 17:44:51 INFO - 'LANG': 'en_US.UTF-8', 17:44:51 INFO - 'LANGUAGE': 'en_US:en', 17:44:51 INFO - 'LOGNAME': 'cltbld', 17:44:51 INFO - 'MAIL': '/var/mail/cltbld', 17:44:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:51 INFO - 'MOZ_NO_REMOTE': '1', 17:44:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:51 INFO - 'NO_EM_RESTART': '1', 17:44:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:51 INFO - 'PWD': '/builds/slave/test', 17:44:51 INFO - 'SHELL': '/bin/bash', 17:44:51 INFO - 'SHLVL': '1', 17:44:51 INFO - 'TERM': 'linux', 17:44:51 INFO - 'TMOUT': '86400', 17:44:51 INFO - 'USER': 'cltbld', 17:44:51 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', 17:44:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:51 INFO - '_': '/tools/buildbot/bin/python'} 17:44:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:51 INFO - Downloading/unpacking blobuploader==1.2.4 17:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:53 INFO - Downloading blobuploader-1.2.4.tar.gz 17:44:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:44:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:44:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:53 INFO - Downloading docopt-0.6.1.tar.gz 17:44:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:44:53 INFO - Installing collected packages: blobuploader, requests, docopt 17:44:53 INFO - Running setup.py install for blobuploader 17:44:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:44:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:44:53 INFO - Running setup.py install for requests 17:44:54 INFO - Running setup.py install for docopt 17:44:54 INFO - Successfully installed blobuploader requests docopt 17:44:54 INFO - Cleaning up... 17:44:54 INFO - Return code: 0 17:44:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:44:54 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')]} 17:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:54 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')]} 17:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:54 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 0x19311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9deb0>, '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 0x1c9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f240>, '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': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', '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 17:44:54 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 17:44:54 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 17:44:54 INFO - Using env: {'DISPLAY': ':0', 17:44:54 INFO - 'HOME': '/home/cltbld', 17:44:54 INFO - 'LANG': 'en_US.UTF-8', 17:44:54 INFO - 'LANGUAGE': 'en_US:en', 17:44:54 INFO - 'LOGNAME': 'cltbld', 17:44:54 INFO - 'MAIL': '/var/mail/cltbld', 17:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:54 INFO - 'MOZ_NO_REMOTE': '1', 17:44:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:54 INFO - 'NO_EM_RESTART': '1', 17:44:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:54 INFO - 'PWD': '/builds/slave/test', 17:44:54 INFO - 'SHELL': '/bin/bash', 17:44:54 INFO - 'SHLVL': '1', 17:44:54 INFO - 'TERM': 'linux', 17:44:54 INFO - 'TMOUT': '86400', 17:44:54 INFO - 'USER': 'cltbld', 17:44:54 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', 17:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:54 INFO - '_': '/tools/buildbot/bin/python'} 17:44:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:44:54 INFO - Running setup.py (path:/tmp/pip-hAXIy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:44:54 INFO - Running setup.py (path:/tmp/pip-d7w7F6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:44:54 INFO - Running setup.py (path:/tmp/pip-2fEDsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:44:54 INFO - Running setup.py (path:/tmp/pip-iIM8MX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:44:54 INFO - Running setup.py (path:/tmp/pip-JoWEKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:44:54 INFO - Running setup.py (path:/tmp/pip-I3_5yc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:44:54 INFO - Running setup.py (path:/tmp/pip-TjGFDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:44:54 INFO - Running setup.py (path:/tmp/pip-AWAkPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:44:55 INFO - Running setup.py (path:/tmp/pip-RIyYGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:44:55 INFO - Running setup.py (path:/tmp/pip-cJkM6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:44:55 INFO - Running setup.py (path:/tmp/pip-qNcM8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:44:55 INFO - Running setup.py (path:/tmp/pip-plgz7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:44:55 INFO - Running setup.py (path:/tmp/pip-icqWIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:44:55 INFO - Running setup.py (path:/tmp/pip-bQOgDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:44:55 INFO - Running setup.py (path:/tmp/pip-3dueFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:44:55 INFO - Running setup.py (path:/tmp/pip-DKnAxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:44:55 INFO - Running setup.py (path:/tmp/pip-fSKocK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:44:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:44:55 INFO - Running setup.py install for manifestparser 17:44:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:44:55 INFO - Running setup.py install for mozcrash 17:44:55 INFO - Running setup.py install for mozdebug 17:44:55 INFO - Running setup.py install for mozdevice 17:44:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:44:56 INFO - Running setup.py install for mozfile 17:44:56 INFO - Running setup.py install for mozhttpd 17:44:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:44:56 INFO - Running setup.py install for mozinfo 17:44:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:44:56 INFO - Running setup.py install for mozInstall 17:44:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:44:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:44:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:44:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:44:56 INFO - Running setup.py install for mozleak 17:44:56 INFO - Running setup.py install for mozlog 17:44:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:44:56 INFO - Running setup.py install for moznetwork 17:44:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:44:56 INFO - Running setup.py install for mozprocess 17:44:56 INFO - Running setup.py install for mozprofile 17:44:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:44:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:44:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:44:57 INFO - Running setup.py install for mozrunner 17:44:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:44:57 INFO - Running setup.py install for mozscreenshot 17:44:57 INFO - Running setup.py install for moztest 17:44:57 INFO - Running setup.py install for mozversion 17:44:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:44:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:44:57 INFO - Cleaning up... 17:44:57 INFO - Return code: 0 17:44:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:44: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')]} 17:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44: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')]} 17:44:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:57 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 0x19311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9deb0>, '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 0x1c9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f240>, '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': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', '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 17:44:57 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 17:44:57 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 17:44:57 INFO - Using env: {'DISPLAY': ':0', 17:44:57 INFO - 'HOME': '/home/cltbld', 17:44:57 INFO - 'LANG': 'en_US.UTF-8', 17:44:57 INFO - 'LANGUAGE': 'en_US:en', 17:44:57 INFO - 'LOGNAME': 'cltbld', 17:44:57 INFO - 'MAIL': '/var/mail/cltbld', 17:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:57 INFO - 'MOZ_NO_REMOTE': '1', 17:44:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:57 INFO - 'NO_EM_RESTART': '1', 17:44:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:57 INFO - 'PWD': '/builds/slave/test', 17:44:57 INFO - 'SHELL': '/bin/bash', 17:44:57 INFO - 'SHLVL': '1', 17:44:57 INFO - 'TERM': 'linux', 17:44:57 INFO - 'TMOUT': '86400', 17:44:57 INFO - 'USER': 'cltbld', 17:44:57 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', 17:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:57 INFO - '_': '/tools/buildbot/bin/python'} 17:44:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:44:57 INFO - Running setup.py (path:/tmp/pip-SpEoAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:44:57 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)) 17:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:44:57 INFO - Running setup.py (path:/tmp/pip-WJoc1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:44:57 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)) 17:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:44:57 INFO - Running setup.py (path:/tmp/pip-jO2N_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:44:57 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)) 17:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:44:57 INFO - Running setup.py (path:/tmp/pip-18MWmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:44:58 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)) 17:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:44:58 INFO - Running setup.py (path:/tmp/pip-7D6MbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:44:58 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)) 17:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:44:58 INFO - Running setup.py (path:/tmp/pip-eIO98x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:44:58 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)) 17:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:44:58 INFO - Running setup.py (path:/tmp/pip-O7Ms33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:44:58 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)) 17:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:44:58 INFO - Running setup.py (path:/tmp/pip-_T76lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:44:58 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)) 17:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:44:58 INFO - Running setup.py (path:/tmp/pip-FAAxjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:44:58 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)) 17:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:44:58 INFO - Running setup.py (path:/tmp/pip-YnXaLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:44:58 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)) 17:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:44:58 INFO - Running setup.py (path:/tmp/pip-3lO7qs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:44:58 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)) 17:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:44:58 INFO - Running setup.py (path:/tmp/pip-sxZYEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:44:58 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)) 17:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:44:58 INFO - Running setup.py (path:/tmp/pip-TYrlEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:44:58 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)) 17:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:44:58 INFO - Running setup.py (path:/tmp/pip-fK_wXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:44:58 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)) 17:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:44:58 INFO - Running setup.py (path:/tmp/pip-HfXpWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:44:58 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)) 17:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:44:58 INFO - Running setup.py (path:/tmp/pip-PWa9DY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:44:58 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)) 17:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:44:58 INFO - Running setup.py (path:/tmp/pip-0FPfrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:44:58 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)) 17:44:58 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)) 17:44:58 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)) 17:44:58 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)) 17:44:58 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)) 17:44:58 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)) 17:44:58 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)) 17:44:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:44:58 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 17:44:58 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 17:44:58 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 17:44:58 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 17:45:00 INFO - Downloading blessings-1.6.tar.gz 17:45:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:45:00 INFO - Installing collected packages: blessings 17:45:00 INFO - Running setup.py install for blessings 17:45:00 INFO - Successfully installed blessings 17:45:00 INFO - Cleaning up... 17:45:00 INFO - Return code: 0 17:45:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:45:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:45:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:45:00 INFO - Reading from file tmpfile_stdout 17:45:00 INFO - Current package versions: 17:45:00 INFO - argparse == 1.2.1 17:45:00 INFO - blessings == 1.6 17:45:00 INFO - blobuploader == 1.2.4 17:45:00 INFO - docopt == 0.6.1 17:45:00 INFO - functools32 == 3.2.3-2 17:45:00 INFO - jsonschema == 2.5.1 17:45:00 INFO - manifestparser == 1.1 17:45:00 INFO - mozInstall == 1.13 17:45:00 INFO - mozcrash == 1.0 17:45:00 INFO - mozdebug == 0.1 17:45:00 INFO - mozdevice == 0.51 17:45:00 INFO - mozfile == 1.2 17:45:00 INFO - mozhttpd == 0.7 17:45:00 INFO - mozinfo == 0.10 17:45:00 INFO - mozleak == 0.1 17:45:00 INFO - mozlog == 3.5 17:45:00 INFO - moznetwork == 0.27 17:45:00 INFO - mozprocess == 0.25 17:45:00 INFO - mozprofile == 0.28 17:45:00 INFO - mozrunner == 6.13 17:45:00 INFO - mozscreenshot == 0.1 17:45:00 INFO - mozsystemmonitor == 0.3 17:45:00 INFO - moztest == 0.8 17:45:00 INFO - mozversion == 1.4 17:45:00 INFO - psutil == 3.1.1 17:45:00 INFO - requests == 1.2.3 17:45:00 INFO - wsgiref == 0.1.2 17:45:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:45: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')]} 17:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45: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')]} 17:45:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:00 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 0x19311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9deb0>, '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 0x1c9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f240>, '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': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', '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 17:45:00 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 17:45:00 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 17:45:00 INFO - Using env: {'DISPLAY': ':0', 17:45:00 INFO - 'HOME': '/home/cltbld', 17:45:00 INFO - 'LANG': 'en_US.UTF-8', 17:45:00 INFO - 'LANGUAGE': 'en_US:en', 17:45:00 INFO - 'LOGNAME': 'cltbld', 17:45:00 INFO - 'MAIL': '/var/mail/cltbld', 17:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:00 INFO - 'MOZ_NO_REMOTE': '1', 17:45:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:45:00 INFO - 'NO_EM_RESTART': '1', 17:45:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:00 INFO - 'PWD': '/builds/slave/test', 17:45:00 INFO - 'SHELL': '/bin/bash', 17:45:00 INFO - 'SHLVL': '1', 17:45:00 INFO - 'TERM': 'linux', 17:45:00 INFO - 'TMOUT': '86400', 17:45:00 INFO - 'USER': 'cltbld', 17:45:00 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', 17:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:00 INFO - '_': '/tools/buildbot/bin/python'} 17:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:00 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)) 17:45:00 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)) 17:45:00 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)) 17:45:00 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)) 17:45:00 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)) 17:45:00 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)) 17:45:00 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)) 17:45:00 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)) 17:45:00 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)) 17:45:00 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:45: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 17:45: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 17:45: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 17:45: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 17:45:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:45:01 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)) 17:45:01 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)) 17:45:01 INFO - Installing collected packages: simplejson 17:45:01 INFO - Running setup.py install for simplejson 17:45:01 INFO - building 'simplejson._speedups' extension 17:45:01 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 17:45:02 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 17:45:02 INFO - Successfully installed simplejson 17:45:02 INFO - Cleaning up... 17:45:02 INFO - Return code: 0 17:45:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:45: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')]} 17:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45: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')]} 17:45:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9deb0>, '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 0x1c9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f240>, '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': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', '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 17:45: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'] in /builds/slave/test/build 17:45: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 17:45:02 INFO - Using env: {'DISPLAY': ':0', 17:45:02 INFO - 'HOME': '/home/cltbld', 17:45:02 INFO - 'LANG': 'en_US.UTF-8', 17:45:02 INFO - 'LANGUAGE': 'en_US:en', 17:45:02 INFO - 'LOGNAME': 'cltbld', 17:45:02 INFO - 'MAIL': '/var/mail/cltbld', 17:45:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:02 INFO - 'MOZ_NO_REMOTE': '1', 17:45:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:45:02 INFO - 'NO_EM_RESTART': '1', 17:45:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:45:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:02 INFO - 'PWD': '/builds/slave/test', 17:45:02 INFO - 'SHELL': '/bin/bash', 17:45:02 INFO - 'SHLVL': '1', 17:45:02 INFO - 'TERM': 'linux', 17:45:02 INFO - 'TMOUT': '86400', 17:45:02 INFO - 'USER': 'cltbld', 17:45:02 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', 17:45:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:02 INFO - '_': '/tools/buildbot/bin/python'} 17:45:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:45:03 INFO - Cleaning up... 17:45:03 INFO - Return code: 0 17:45:03 INFO - Running post-action listener: _resource_record_post_action 17:45:03 INFO - Running post-action listener: _start_resource_monitoring 17:45:03 INFO - Starting resource monitoring. 17:45:03 INFO - [mozharness: 2017-08-04 00:45:03.162624Z] Finished create-virtualenv step (success) 17:45:03 INFO - [mozharness: 2017-08-04 00:45:03.163134Z] Running install step. 17:45:03 INFO - Running pre-action listener: _resource_record_pre_action 17:45:03 INFO - Running main action method: install 17:45:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:45:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:45:03 INFO - Reading from file tmpfile_stdout 17:45:03 INFO - Detecting whether we're running mozinstall >=1.0... 17:45:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:45:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:45:03 INFO - Reading from file tmpfile_stdout 17:45:03 INFO - Output received: 17:45:03 INFO - Usage: mozinstall [options] installer 17:45:03 INFO - Options: 17:45:03 INFO - -h, --help show this help message and exit 17:45:03 INFO - -d DEST, --destination=DEST 17:45:03 INFO - Directory to install application into. [default: 17:45:03 INFO - "/builds/slave/test"] 17:45:03 INFO - --app=APP Application being installed. [default: firefox] 17:45:03 INFO - mkdir: /builds/slave/test/build/application 17:45:03 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'] 17:45:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:45:21 INFO - Reading from file tmpfile_stdout 17:45:21 INFO - Output received: 17:45:21 INFO - /builds/slave/test/build/application/firefox/firefox 17:45:21 INFO - Running post-action listener: _resource_record_post_action 17:45:21 INFO - [mozharness: 2017-08-04 00:45:21.658568Z] Finished install step (success) 17:45:21 INFO - [mozharness: 2017-08-04 00:45:21.658822Z] Running setup-mitmproxy step. 17:45:21 INFO - Running pre-action listener: _resource_record_pre_action 17:45:21 INFO - Running main action method: setup_mitmproxy 17:45:21 INFO - Skipping: mitmproxy is not required 17:45:21 INFO - Running post-action listener: _resource_record_post_action 17:45:21 INFO - [mozharness: 2017-08-04 00:45:21.659937Z] Finished setup-mitmproxy step (success) 17:45:21 INFO - [mozharness: 2017-08-04 00:45:21.660159Z] Running run-tests step. 17:45:21 INFO - Running pre-action listener: _resource_record_pre_action 17:45:21 INFO - Running pre-action listener: _set_gcov_prefix 17:45:21 INFO - Running main action method: run_tests 17:45:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:45:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:45:21 INFO - Python 2.7.3 17:45:21 INFO - Return code: 0 17:45:21 INFO - grabbing minidump binary from tooltool 17:45:21 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')]} 17:45:21 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9deb0>, '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 0x1c9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:45:21 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:45:21 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 17:45:21 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:45:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:45:21 INFO - Return code: 0 17:45:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:45:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:45:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:45:21 INFO - ENV: RUST_BACKTRACE is now 1 17:45:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:45:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:45:21 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/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 17:45:21 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/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-030 --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 17:45:21 INFO - Using env: {'DISPLAY': ':0', 17:45:21 INFO - 'HOME': '/home/cltbld', 17:45:21 INFO - 'LANG': 'en_US.UTF-8', 17:45:21 INFO - 'LANGUAGE': 'en_US:en', 17:45:21 INFO - 'LOGNAME': 'cltbld', 17:45:21 INFO - 'MAIL': '/var/mail/cltbld', 17:45:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:45:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:45:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:21 INFO - 'MOZ_NO_REMOTE': '1', 17:45:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:45:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:45:21 INFO - 'NO_EM_RESTART': '1', 17:45:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:45:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:21 INFO - 'PWD': '/builds/slave/test', 17:45:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:45:21 INFO - 'RUST_BACKTRACE': '1', 17:45:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:45:21 INFO - 'SHELL': '/bin/bash', 17:45:21 INFO - 'SHLVL': '1', 17:45:21 INFO - 'STYLO_FORCE_ENABLED': '1', 17:45:21 INFO - 'TERM': 'linux', 17:45:21 INFO - 'TMOUT': '86400', 17:45:21 INFO - 'USER': 'cltbld', 17:45:21 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737', 17:45:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:21 INFO - '_': '/tools/buildbot/bin/python'} 17:45:21 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/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 17:45:22 INFO - mozversion application_buildid: 20170804000129 17:45:22 INFO - mozversion application_changeset: 5742919ec43f834cc061a96d87c767af1a1f7f75 17:45:22 INFO - mozversion application_display_name: Nightly 17:45:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:45:22 INFO - mozversion application_name: Firefox 17:45:22 INFO - mozversion application_remotingname: firefox 17:45:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:45:22 INFO - mozversion application_vendor: Mozilla 17:45:22 INFO - mozversion application_version: 57.0a1 17:45:22 INFO - mozversion platform_buildid: 20170804000129 17:45:22 INFO - mozversion platform_changeset: 5742919ec43f834cc061a96d87c767af1a1f7f75 17:45:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:45:22 INFO - mozversion platform_version: 57.0a1 17:45:22 INFO - using testdate: 1501807522 17:45:22 INFO - actual date: 1501807522 17:45:22 INFO - starting webserver on 'localhost:35175' 17:45:22 INFO - SUITE-START | Running 2 tests 17:45:22 INFO - TEST-START | dromaeo_css 17:45:22 INFO - Initialising browser for dromaeo_css test... 17:45:22 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35175/getInfo.html -profile /tmp/tmph8GgKc/profile 17:45:22 INFO - TEST-INFO | started process 31498 (/builds/slave/test/build/application/firefox/firefox http://localhost:35175/getInfo.html) 17:45:27 INFO - TEST-INFO | 31498: exit 0 17:45:27 INFO - Browser initialized. 17:45:27 INFO - Running cycle 1/1 for dromaeo_css test... 17:45:27 INFO - TEST-INFO | started process 31724 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmph8GgKc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:45:28 INFO - PID 31724 | 17:45:28 INFO - PID 31724 | (/builds/slave/test/build/application/firefox/firefox:31779): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:45:28 INFO - PID 31724 | 17:45:39 INFO - PID 31724 | RSS: Main: 174014464 17:45:39 INFO - PID 31724 | 17:48:39 INFO - PID 31724 | Cycle 1(1): loaded http://localhost:35175/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35175/tests/dromaeo/cssquery-ext.html) 17:48:40 INFO - PID 31724 | RSS: Main: 175669248 17:48:40 INFO - PID 31724 | 17:51:42 INFO - PID 31724 | Cycle 1(1): loaded http://localhost:35175/tests/dromaeo/cssquery-ext.html (next: http://localhost:35175/tests/dromaeo/cssquery-jquery.html) 17:51:42 INFO - PID 31724 | RSS: Main: 173359104 17:51:42 INFO - PID 31724 | 17:54:44 INFO - PID 31724 | Cycle 1(1): loaded http://localhost:35175/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35175/tests/dromaeo/cssquery-mootools.html) 17:54:45 INFO - PID 31724 | RSS: Main: 175190016 17:54:45 INFO - PID 31724 | 17:57:46 INFO - PID 31724 | Cycle 1(1): loaded http://localhost:35175/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35175/tests/dromaeo/cssquery-prototype.html) 17:57:47 INFO - PID 31724 | RSS: Main: 173936640 17:57:47 INFO - PID 31724 | 18:00:51 INFO - PID 31724 | Cycle 1(1): loaded http://localhost:35175/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35175/tests/dromaeo/cssquery-yui.html) 18:00:51 INFO - PID 31724 | RSS: Main: 177172480 18:00:51 INFO - PID 31724 | 18:03:53 INFO - PID 31724 | Cycle 1(1): loaded http://localhost:35175/tests/dromaeo/cssquery-yui.html (next: http://localhost:35175/tests/dromaeo/cssquery-dojo.html) 18:03:53 INFO - PID 31724 | RSS: Main: 177397760 18:03:53 INFO - PID 31724 | 18:03:53 INFO - PID 31724 | __start_tp_report 18:03:53 INFO - PID 31724 | _x_x_mozilla_page_load 18:03:53 INFO - PID 31724 | _x_x_mozilla_page_load_details 18:03:53 INFO - PID 31724 | |i|pagename|runs| 18:03:53 INFO - PID 31724 | |0;dojo.html;3179.3959147761907;3197.2486465680563;3207.326461443097;3218.7425005999517;3229.9515507267397;6175.042868355401;6238.532110091739;6251.218597675291;6300.464460520861;6319.936800631998;6081.057436097412;6205.782797602084;6242.656653884034;6262.874742505159;6271.529635277361;5702.230198923134;5725.4847063764355;5758.712064396785;5771.3074431068335;5833.416658334175;5530.917036244439;5694.658320500763;5694.6867922264255;5772.653640781546;5820.767169313222;5259.737013149346;5553.4724201200825;5682.346530149003;5690.573207009449;5696.231008813798;5662.631928924648;5672.12082127271;5698.886022279531;5700.201971723962;5707.429257074301;5664.830055098354;5668.886622267572;5674.886502269973;5676.148577713335;5685.289338832646;6191.19514463117;6241.500679945593;6247.531435142382;6289.276733175652;6302.716377763011;6670.433013193884;6676.198856137246;6690.59856408617;6782.694778734969;6786.3892249697765;6617.735290588371;6744.7976560703255;6747.561408508432;6763.560368576027;6836.623985680785;6133.29467111279;6134.04922237054;6134.999999999955;6136.7238474268315;6137.048757442603;6797.320267973163;6799.966000169968;6801.251862295144;6836.555623884432;6890.896636550456;6568.244651865001;6570.441512471493;6601.966990165114;6646.468282537386;6656.534042617067;6665.766698165541;6762.459003279726;6764.966175169093;6765.323467653294;6774.7628832991595;6605.57063790852;6615.437687796495;6649.335066493409;6693.397594216543;6740;6570.934290657128;6684;6730.360615741594;6741.1910570731825;6766.424853887376;5392.622516423891;5438.102713052302;5444.754986025639;5444.8094316698725;5452.154915988028;4628.3983082199775;4650.255959046536;4653.441587009581;4660.13787449323;4699.929501057487;5770;5777.971110144423;5856.180134781134;5884.323302820145;5885.234919460443;6613.801585952429;6683.699233534503;6697.095892054608;6718.260991290954;6793.898091528631;5786.161006654096;5798.217240672455;5821.970890145522;5831.737571809279;5839;5903.734331955073;5943.910841337383;5950.31571369298;5955.285365756137;5964.791232306935;6252.751665513609;6511.446527045159;6548.9017664735065;6561.671916404256;6562.442192413603;6724.697388617622;6747.291534388818;6811.2167100784045;6815.625140617115;6830.077939478306;6347.523935704933;6463.3213512580505;6465.482761378984;6465.870682586416;6563.409293163447;6551.050097735706;6552.967235163794;6582.374674406115;6589.209294884645;6597.2742998271115;6728.596284222962;6757.3242675732035;6757.797266082025;6758.797236082925;6762.763303284361;6435.549511534147;6489.383508566679;6494;6511.088447617432;6514.120593719787;6517.283098859217;6518.73925043012;6544.312847150981;6621.304763000008;6623.4038936495945;6423.743050277936;6427.614343139239;6494.350564943468;6521.380468855451;6528.640924749185;6302.590331628429;6392.904106438315;6402.231732192166;6429;6458.967705161444;6270.968645156745;6355.3326900674765;6355.809325720235;6402.839929001812;6451.903221451776 18:03:53 INFO - PID 31724 | |1;ext.html;825.2119226139038;857.6355049104129;878.7012415778635;907.8002839375339;907.9001309855914;40204.59795402045;40849.59150408495;40912.38631420527;41011;41054.58945410545;37509.249815003684;37539;37593.62406375935;37693.05767355818;37769.81115094421;6720.59676419414;6847.10987571615;6854.074699915497;6855.554388964726;6857.142857142857;6733.730650773988;6787.694553745068;6802.557833740816;6816.250212476623;6819.522633415663;6761.560498567602;6784.3554862288;6803.591784492922;6817.659117044128;7336.743213987512;7466.178720340786;7514.58669773165;7526.247375262485;7528.962355188189;7561.24387561245;7294.197638259787;7342.118945726494;7369.594672293022;7373;7377.6311184440565;37645.43531847024;37925.241495170216;38214.04464888372;38485;38580;3487.7035451986358;3491.6857482826413;3499.9825000874835;3502.527158833576;3537.5224344713392;31336.21659458532;31419.05742827719;31427;31611.52582711261;31720;4841.273808928689;4862.343583616202;4885.5358740919555;4896.4858689837765;4927.162382395001;180003;181369;184594;185203;196522.99999999857;48494.515054849;48940.510594893596;48995;49003;49063.75468122637;50787;50904.74547627312;51007.489925101014;51015.234771478456;51076.48923510792;15760.054396736003;15836.44572439982;15850.286737096627;15850.603734906488;16043.358265669476;13090.280034598189;13105.541306054241;13153.079284449997;13153.934230328978;13176.341182940814;14124;14128.71742565143;14172.149671019772;14243.78634320486;14360.497382591559;5862.208601838697;5863.296404431496;5877.706114694248;5898.14476900847;5898.616589921728;979.2704435195825;982.4596471940404;990.2474119668959;1013.5692330759398;1016.5273147986222;23078.769212307663;23529.529409411716;23579.41051473727;23602.29193124209;23666.7633323668;1092.9234953553334;1097.950592223336;1106.065374758344;1113.9443027848574;1114.4483480677118;12300.507979680891;12467.5012999479;15853.603659908364;15867.365305387655;16573.83426165723;226667;226797;226814;227481;227611;54995.450045499034;55051;55383.723081384334;55470.16794748001;55874.72062639742;1390.1381143690774;1463.290304202461;1464.5825939607162;1472.175581674266;1477.0842077911548;27463.45073098487;27748.30629234285;27768.583471248352;27878.860605696842;27930.86034569814;38153;38402.61597383991;38409;38465.42301865418;38482.80758596189;203190;203408;205435;206290;206382;34114.82942585271;35832.820835895654;37854.81072594619;38136.04659883525;38150;45519.77240113778;46479;46861.53138468708;47205.763971181295;47812.5218747822;34511.65488345084;34512.82743586266;34758.13104672403;35144.47283290701;35237.82381088078;33804.66195338113;34049.82975085109;34363;34440.82779586086;34723.479147813305;37250.44124338083;37817.62182378251;38076;38222.617773821905;38229.235415292096;32827.835860820545;33240.33519329648;33507;33629.66370336363;33675.66324336725;24674;24764.38089047716;25035.74964250407;25526.23421297364;25803.483930320912 18:03:53 INFO - PID 31724 | |2;jquery.html;567.5459632294167;582.2634367525079;593.1784478497283;596.6241268001157;602.3976023976024;22408.215712450066;22616.982235799387;23236.8838155809;23251.186208482708;23258.88370558149;26708.198754037363;27107.1867843965;27195.864020679917;27204.727952720517;27281;6405.071264666621;6576.967115164418;6582.835429114262;6585.045168450596;6595.373439523261;5910;6391.488680905516;6498.837529061764;6512.185976752906;6517.511186660996;6462.5153113516435;6466.676666166696;6482.805515834532;6486.156799616043;6487.18910136233;6479.676016199218;6488.805335839932;6492.448141907944;6494.642794646293;6496.123023391829;6056.939430605682;6062.151298818169;6065.332813390523;6065.363136870609;6065.847488977124;30009.099727008226;30077.69922300771;30709.539356909663;31090.222744431343;31325;1864.7855496617926;1886.0946745562208;1901.1824915286422;1904.047976011994;1904.0670071664922;18675.346362877233;19224.90387548067;19259.13333899964;19277.51806204842;19384.515387115294;2316.7104111986;2359.882005899698;2400.2799160251857;2412.7828495435497;2418.8427752196226;148630.25684871615;148784;151975;153116;153184;14221.64445888861;14853.851461485247;15056.849431505763;15128.773068403983;15874.682506349809;23713;23761.524769504515;23766;23798.88100559486;23870.880645597008;9915.405075695482;10092.848607270897;10126.392416455037;10313.84529232062;10331.896681033244;9337.299702522203;9374.484403357745;9394.06059394055;9467.52662366879;9521.476318802532;8963.117745273566;8996.190342869173;9387.432775545385;9391.436513809193;9442.291828112919;2783.359827239746;2804.859757012182;2824.4351129774127;2827.5475923852086;2836.602875597418;935.4153653966271;966.7046158148115;971.8007808399261;972.2076507646245;977.2621670638622;19598;19839.60320793576;20156.92088779218;20577.38267851967;20611;1017.9643712470028;1026.9794604107876;1027.6814187601867;1034.3070143004206;1037.901399367059;11272.267302625303;11283.323000619825;11323.830142547868;11382.146339024606;11383.430828458544;486358;487559;552816;557210;566641;122407;124055;124139;127304;127629.36185319014;1248.9625311240677;1271.5104684696273;1308.3915979069777;1312.586535241396;1315.1780137414116;20802.687959680316;20993.265235716677;21353.039113239633;21371.89314053482;21479.248226312004;60363.09455358261;60530;61999;62714.37285627086;62995;77703.61148194222;78738.6063069681;78978.6051069741;78984.21015789769;79161;12678.112532122663;14406.279686015867;14830.62923426923;15478.767818482507;15483.767743483631;24757.25728228156;24809;24927.750722492543;25028.124015659363;25243;17720.822791771916;18761.90619046896;18915.810841891405;18941.242350305834;19161.13774880106;17589.560260993578;17818.55453613618;17996.55008624795;18139.183736732142;18283.360082397052;19953.900230499334;20130.29543965954;20184.697229541864;20278.695819563014;20495.385138445276;14610.780838287646;15064.849351506344;15429.074255544703;15432.537023889301;15436.614084647974;19085.141168647166;19253.32613358526;19328.4201473956;19347.226110955406;19575 18:03:53 INFO - PID 31724 | |3;mootools.html;1329.2755448280686;1349.7502961952039;1365.4265208612387;1375.2573610250465;1388.5695434415325;13612.931935340323;14331.785023224647;14333.856661433409;14340.928295358535;14349.354279057441;13807.240601766898;14373.425062997494;14793.704125917502;14821.629459263524;14859.554213373589;2694.5688689809635;2759.0619189475574;2771.1547977866744;2771.9861400692967;2777.1807316841478;2655.81409301349;2664.5337066013467;2676.718944510818;2704.580789977557;2706.472237913608;2741.08173761789;2774.611554382388;2775.084222206667;2776.0283900634818;2776.6667999840047;2660.215236505226;2714.5792402177694;2718.9864050679817;2724.1555117913313;2725.509408306514;2562.2825608829557;2642.8546429946346;2645.285772841322;2693.636359091517;2707.431439397729;13955.79066314006;14051.578452646438;14060.437582496688;14076.225807580578;14106.365213565414;7679;7970.242826931432;7974.481658692224;7982.720604778863;7983.481073730247;5214.556762675177;5359.5712343012465;5429.67421954684;5517.724113794335;5521.254630624854;1808.9638207235782;1827.7898041725239;1843.723441483788;1881.6895212289955;1882.6799444094536;23171.53656926869;23294;23307;23346.066157353685;23366;6472.223333200046;6617.470602351897;6655.46756259498;6808.2510923798345;6823.590584564942;15958.12230327343;15981.121038343003;15988;16013.519594412186;16033.038017718742;7324.377427918686;7528.644843928036;7531.661075251627;7535.8116047099265;7544.547327160388;6898.551594146365;6954.9304506955295;7005.229424763272;7062.6821793019435;7101;4869.245266983623;5041.697498150123;5046.520580544842;5055.393352797688;5120.564751996048;1423.679672073796;1446.8263808343065;1506.3372116268806;1552.945646902353;1568.5921660368158;842.7303262955795;866.787637028932;869.387082107113;878.3939082033377;889.4396530185942;7484.625768711543;8198.426110172231;8216.424850260544;8229.588520573947;8259.463134896212;945.7021038372891;964.6816550538306;972.6255391674256;977.2653539966474;977.3744803325877;4479.507254202035;4590.563896429834;4609.446866376056;4628.722276663344;5571.4428557143965;19623.705644415346;19697.21211151538;20089.799102008794;20102.798972010092;20165.596688066158;18466.446006619823;19614.509637259183;19617;19625.80374196268;19641.803581964283;9993.250506211918;10349.379037257939;10364.429956352322;10404.11565016967;10417.42704151279;8074.555899425588;8313.916860831314;8609.741707748777;8621.784455388666;8678;16681;17061.232244548777;17465.650686986442;17651.735223971395;17691.292348305924;17253;17458.912705436393;17569.121543923007;17625.11874406249;17649.647007060044;11489.770204596029;11686.422332984937;11771.411429428666;11774.764504710029;11779.88220117788;13172.341382931007;13175.934120329337;13352.265625390697;13376.130551514121;13388.397522111334;10901.509432075418;11254.324740515594;11257.380844053656;11308.49111789988;11434.371109589052;10748.193885458466;10945.781084378426;11001.889981100087;11074.280171788809;11090.16823738239;9576.56905439243;9647.938726740193;9732.221422286058;9741.707748767549;9758;7098.751543695946;7127.073480447509;7134.8929766052515;7144.642767861482;7156.785296441115;6969.7909062728195;7061.576305421691;7103.2186459488385;7154.427645788429;7188.640567971685 18:03:53 INFO - PID 31724 | |4;prototype.html;486.61332512694275;493.5459377372817;511.47573736919674;518.2718280815453;535.7990753467449;6112.235970503687;6112.29708583513;6189.319174890769;6198;6275.52933529986;5925.7925972590965;5982.730777115029;6030.457258846703;6041.395860413956;6104.236970378703;4889.3888263967;4930.753462326878;4934.605231581465;4963.354763880709;4970.950290497103;4744.102359056388;4949.727764972925;4961.355023846895;4965.577925876305;4987.551120399164;4900;4913.287573301869;4916.70499770015;4920.60635149189;4935.876603084934;4887.682300650446;4912.189488734391;4956.306117143603;4962.280469331963;5000.199968005102;4446.88662986736;4460.955390446086;4463.464384273875;4470.687051906368;4505.932411013838;6021.668808215546;6042.848928776772;6080.30076541199;6099.969500152515;6145.139680444697;5618.2415373924405;5676.432356764331;5696.69283940005;5749;5754.395788442237;6422.518311126684;6475.838104047437;6487.545871788977;6525;6541.345865413468;4827.25177597473;4853.854384368475;4884.389451318585;4892.559669629751;4900.705957642553;6794.422474089659;6848.280930502325;6852.349026842442;6918.411934985532;6929.618870962095;4954.5540901319055;4996.225585034325;5002.299678045077;5004.548680882576;5005.899882002413;4985.875353116201;5000.574951129195;5036.924446133311;5085.847424577269;5097.668651537638;5590.021746194399;5620.353659329147;5641.351244606923;5665.433456654301;5716.371199168088;6430.7106180222;6483.984946999668;6520;6568.310327415648;6575.112359831457;1655.1641421082277;1689.5015970288794;1699.6175860431304;1892.9716054259197;1936.2255097960929;1399.9090059146326;1422.239102080382;1430.7623905321823;1452.0561634937164;1476;5676.68778217202;5680.943190568124;5722.399148089474;5731.799387021434;5742.253507044057;6579.8026059217345;6593.86812263752;6613.900791488131;6656.933430665728;6660.500462465335;5746.88506229873;5767.942320576741;5774.133879917962;5776.913346299809;5777.711114444261;3542.326957877995;3613.903935717278;3618.819059047037;3625.7099432045375;3632.076178676191;6516.934830651728;6542.626048529862;6567.540272180901;6569.24453687832;6592.208934927743;6850.143732033496;6856.451483881277;6871.347222013901;6886.449084073262;6934;6493.2857385688485;6514.469099761457;6525.2169739631545;6526.118973938553;6555.573887697285;6744.966275168593;6764.038428855577;6766.733144396682;6783.4573234142135;6818.454523637998;6536.640484773383;6606.48050948275;6651.800445986628;6655.670515319793;6679.732810687518;6349.333320001333;6383.1382763326355;6428.646424446513;6436.645984470899;6497.350264973465;4981.402231732215;5013.448520662797;5032.144535429059;5045.5459008689395;5051.87370315745;6636.800895973129;6711;6731;6744.494162937897;6801.489888258499;6172.290186628596;6210.819944210585;6389.137466441971;6442.226932768098;6491.7403303869205;6385.169927909342;6411.7941029485255;6444;6464.709088091141;6477.805665830033;6306.716197771019;6336.92272313717;6371.458426033839;6384.87230255383;6429.839254018687;6463.482921366185;6492.253390859923;6550.447439029828;6555.147830781968;6567.540272181092;6301.40136687014;6339.858825411286;6349.555531112777;6365.7135428904885;6400.839979000562 18:03:53 INFO - PID 31724 | |5;yui.html;906.2115959115571;912.4981260306831;927.10071230906;933.971980840575;937.1097457415455;17931.731024034634;17935.551611209725;18838.71741923872;18853.905730471346;18861.81138188618;21302.254421095266;21978.670319945213;22029.669554956647;22053.779462205373;22203.44491387716;979.745266230778;1008.2841182760212;1010.4442556593881;1011.4993078426153;1011.6004178349548;971.1745016735754;1014.411641248078;1065.0999905080196;1069.9728260869535;1070.4808168038496;1121.6354684727455;1124.60638776428;1124.820028795393;1125.5497800879632;1125.7579620381616;1107.385401102388;1114.5486078138326;1117.8155604325277;1118.2116607791488;1118.2787102319;808.292743849132;809.7004108479841;810.2586133687669;812.9837403251961;821.1501096365233;19209.711854322195;20037.1985120595;20221.39335819913;20269.898650506802;20361.287354942506;20174.394768156977;20597;21091.261805836722;21099.578008439745;21322.040508177015;16346.83653163465;16535.338586456528;16562.17189140538;16616.6676666466;16637.50087497377;3576.499290099388;3593.658602432765;3611.3499570077374;3615.9096022599383;3617.855285788565;120942;123928;125348;125927;127686;16184.676306473804;16337.264823082753;16356.754648680278;16408.425705100264;16634.75047874283;28544.143675689764;29409.85295073511;29602.555961660593;29670.55494167589;29687.25781855471;8358.749237522885;8511.702090426805;8545.658173672984;8577.785555361166;8577.914220857836;4175.561566035583;4244.384564238168;4293.463317085364;4299.957000429956;4306.504751953503;5234.7644356004075;5331.466853314638;5426.131818908955;5437.864053398696;5452.482014208644;989.0653332600767;1011.4437059617181;1020.3061917895753;1031.7575369788094;1035.896410358958;695.1519146641191;697.930206979298;711.3491155592575;711.9572825630479;717.519262094398;11712.590059348053;12112.21270617407;12204.511819527119;12487.50049998008;12567.057470689735;691.9688614012382;702.2065066474851;710.6304721544768;719.1047146302886;726.46604745512;5239.869003274948;5279.049771041249;5334.4398838122215;5446.319210098738;5546.69493177871;89460;95286;97888.51055744676;98403;98515.50742246243;81890.59054704689;82740;83875;84811.57594211989;85089.14910850812;75153.24846751463;75202;75694.86457703139;76328.61835690786;77035.2296477028;11159.497822598165;11174.329540227613;11223.887761122285;11265.662719852751;11592.1305902056;28490.629200122014;36624.63375366319;36839.44740828943;38366;38425.4236186463;64536.35463645491;64837.67581162064;66482;66548;66676.33323666701;18091;18514.907425462785;18536.16587253604;18623.627527449647;18734.437966861016;20562.177512899318;20574.279900203423;20691.4827129323;20705.792942070988;21059.578808424052;14887;14945.02857314271;14999.925000375364;15070.321835517208;15195.848041519443;17517.474475765746;18036.056118394252;18050.1877415514;18221;18309.45071647853;15912.602184945006;16462.75305870389;16490.422835200712;17758;17984.2806287747;12758.553450629184;13126.540571080348;13473.32633368308;13507.662308442368;13843.861561384258;13707.177569345871;14070.226137562533;14148.858511415167;14204.715905681622;14274.71450570962 18:03:53 INFO - PID 31724 | __end_tp_report 18:03:53 INFO - PID 31724 | __start_cc_report 18:03:53 INFO - PID 31724 | _x_x_mozilla_cycle_collect,385 18:03:53 INFO - PID 31724 | __end_cc_report 18:03:53 INFO - PID 31724 | __startTimestamp1501808633980__endTimestamp 18:03:53 INFO - PID 31724 | 18:03:53 INFO - PID 31724 | ------- Summary: start ------- 18:03:53 INFO - PID 31724 | Number of tests: 6 18:03:53 INFO - PID 31724 | 18:03:53 INFO - PID 31724 | [#0] dojo.html Cycles:165 Average:6158.07 Median:6402.23 stddev:722.83 (11.3%) stddev-sans-first:686.24 18:03:53 INFO - PID 31724 | Values: 3179.4 3197.2 3207.3 3218.7 3230.0 6175.0 6238.5 6251.2 6300.5 6319.9 6081.1 6205.8 6242.7 6262.9 6271.5 5702.2 5725.5 5758.7 5771.3 5833.4 5530.9 5694.7 5694.7 5772.7 5820.8 5259.7 5553.5 5682.3 5690.6 5696.2 5662.6 5672.1 5698.9 5700.2 5707.4 5664.8 5668.9 5674.9 5676.1 5685.3 6191.2 6241.5 6247.5 6289.3 6302.7 6670.4 6676.2 6690.6 6782.7 6786.4 6617.7 6744.8 6747.6 6763.6 6836.6 6133.3 6134.0 6135.0 6136.7 6137.0 6797.3 6800.0 6801.3 6836.6 6890.9 6568.2 6570.4 6602.0 6646.5 6656.5 6665.8 6762.5 6765.0 6765.3 6774.8 6605.6 6615.4 6649.3 6693.4 6740.0 6570.9 6684.0 6730.4 6741.2 6766.4 5392.6 5438.1 5444.8 5444.8 5452.2 4628.4 4650.3 4653.4 4660.1 4699.9 5770.0 5778.0 5856.2 5884.3 5885.2 6613.8 6683.7 6697.1 6718.3 6793.9 5786.2 5798.2 5822.0 5831.7 5839.0 5903.7 5943.9 5950.3 5955.3 5964.8 6252.8 6511.4 6548.9 6561.7 6562.4 6724.7 6747.3 6811.2 6815.6 6830.1 6347.5 6463.3 6465.5 6465.9 6563.4 6551.1 6553.0 6582.4 6589.2 6597.3 6728.6 6757.3 6757.8 6758.8 6762.8 6435.5 6489.4 6494.0 6511.1 6514.1 6517.3 6518.7 6544.3 6621.3 6623.4 6423.7 6427.6 6494.4 6521.4 6528.6 6302.6 6392.9 6402.2 6429.0 6459.0 6271.0 6355.3 6355.8 6402.8 6451.9 18:03:53 INFO - PID 31724 | 18:03:53 INFO - PID 31724 | [#1] ext.html Cycles:180 Average:38099.07 Median:37524.12 stddev:53491.70 (142.6%) stddev-sans-first:53568.54 18:03:53 INFO - PID 31724 | Values: 825.2 857.6 878.7 907.8 907.9 40204.6 40849.6 40912.4 41011.0 41054.6 37509.2 37539.0 37593.6 37693.1 37769.8 6720.6 6847.1 6854.1 6855.6 6857.1 6733.7 6787.7 6802.6 6816.3 6819.5 6761.6 6784.4 6803.6 6817.7 7336.7 7466.2 7514.6 7526.2 7529.0 7561.2 7294.2 7342.1 7369.6 7373.0 7377.6 37645.4 37925.2 38214.0 38485.0 38580.0 3487.7 3491.7 3500.0 3502.5 3537.5 31336.2 31419.1 31427.0 31611.5 31720.0 4841.3 4862.3 4885.5 4896.5 4927.2 180003.0 181369.0 184594.0 185203.0 196523.0 48494.5 48940.5 48995.0 49003.0 49063.8 50787.0 50904.7 51007.5 51015.2 51076.5 15760.1 15836.4 15850.3 15850.6 16043.4 13090.3 13105.5 13153.1 13153.9 13176.3 14124.0 14128.7 14172.1 14243.8 14360.5 5862.2 5863.3 5877.7 5898.1 5898.6 979.3 982.5 990.2 1013.6 1016.5 23078.8 23529.5 23579.4 23602.3 23666.8 1092.9 1098.0 1106.1 1113.9 1114.4 12300.5 12467.5 15853.6 15867.4 16573.8 226667.0 226797.0 226814.0 227481.0 227611.0 54995.5 55051.0 55383.7 55470.2 55874.7 1390.1 1463.3 1464.6 1472.2 1477.1 27463.5 27748.3 27768.6 27878.9 27930.9 38153.0 38402.6 38409.0 38465.4 38482.8 203190.0 203408.0 205435.0 206290.0 206382.0 34114.8 35832.8 37854.8 38136.0 38150.0 45519.8 46479.0 46861.5 47205.8 47812.5 34511.7 34512.8 34758.1 35144.5 35237.8 33804.7 34049.8 34363.0 34440.8 34723.5 37250.4 37817.6 38076.0 38222.6 38229.2 32827.8 33240.3 33507.0 33629.7 33675.7 24674.0 24764.4 25035.7 25526.2 25803.5 18:03:54 INFO - PID 31724 | 18:03:54 INFO - PID 31724 | [#2] jquery.html Cycles:180 Average:37244.02 Median:13036.44 stddev:89727.56 (688.3%) stddev-sans-first:89937.01 18:03:54 INFO - PID 31724 | Values: 567.5 582.3 593.2 596.6 602.4 22408.2 22617.0 23236.9 23251.2 23258.9 26708.2 27107.2 27195.9 27204.7 27281.0 6405.1 6577.0 6582.8 6585.0 6595.4 5910.0 6391.5 6498.8 6512.2 6517.5 6462.5 6466.7 6482.8 6486.2 6487.2 6479.7 6488.8 6492.4 6494.6 6496.1 6056.9 6062.2 6065.3 6065.4 6065.8 30009.1 30077.7 30709.5 31090.2 31325.0 1864.8 1886.1 1901.2 1904.0 1904.1 18675.3 19224.9 19259.1 19277.5 19384.5 2316.7 2359.9 2400.3 2412.8 2418.8 148630.3 148784.0 151975.0 153116.0 153184.0 14221.6 14853.9 15056.8 15128.8 15874.7 23713.0 23761.5 23766.0 23798.9 23870.9 9915.4 10092.8 10126.4 10313.8 10331.9 9337.3 9374.5 9394.1 9467.5 9521.5 8963.1 8996.2 9387.4 9391.4 9442.3 2783.4 2804.9 2824.4 2827.5 2836.6 935.4 966.7 971.8 972.2 977.3 19598.0 19839.6 20156.9 20577.4 20611.0 1018.0 1027.0 1027.7 1034.3 1037.9 11272.3 11283.3 11323.8 11382.1 11383.4 486358.0 487559.0 552816.0 557210.0 566641.0 122407.0 124055.0 124139.0 127304.0 127629.4 1249.0 1271.5 1308.4 1312.6 1315.2 20802.7 20993.3 21353.0 21371.9 21479.2 60363.1 60530.0 61999.0 62714.4 62995.0 77703.6 78738.6 78978.6 78984.2 79161.0 12678.1 14406.3 14830.6 15478.8 15483.8 24757.3 24809.0 24927.8 25028.1 25243.0 17720.8 18761.9 18915.8 18941.2 19161.1 17589.6 17818.6 17996.6 18139.2 18283.4 19953.9 20130.3 20184.7 20278.7 20495.4 14610.8 15064.8 15429.1 15432.5 15436.6 19085.1 19253.3 19328.4 19347.2 19575.0 18:03:54 INFO - PID 31724 | 18:03:54 INFO - PID 31724 | [#3] mootools.html Cycles:180 Average:8849.70 Median:2685.18 stddev:6035.90 (224.8%) stddev-sans-first:6026.38 18:03:54 INFO - PID 31724 | Values: 1329.3 1349.8 1365.4 1375.3 1388.6 13612.9 14331.8 14333.9 14340.9 14349.4 13807.2 14373.4 14793.7 14821.6 14859.6 2694.6 2759.1 2771.2 2772.0 2777.2 2655.8 2664.5 2676.7 2704.6 2706.5 2741.1 2774.6 2775.1 2776.0 2776.7 2660.2 2714.6 2719.0 2724.2 2725.5 2562.3 2642.9 2645.3 2693.6 2707.4 13955.8 14051.6 14060.4 14076.2 14106.4 7679.0 7970.2 7974.5 7982.7 7983.5 5214.6 5359.6 5429.7 5517.7 5521.3 1809.0 1827.8 1843.7 1881.7 1882.7 23171.5 23294.0 23307.0 23346.1 23366.0 6472.2 6617.5 6655.5 6808.3 6823.6 15958.1 15981.1 15988.0 16013.5 16033.0 7324.4 7528.6 7531.7 7535.8 7544.5 6898.6 6954.9 7005.2 7062.7 7101.0 4869.2 5041.7 5046.5 5055.4 5120.6 1423.7 1446.8 1506.3 1552.9 1568.6 842.7 866.8 869.4 878.4 889.4 7484.6 8198.4 8216.4 8229.6 8259.5 945.7 964.7 972.6 977.3 977.4 4479.5 4590.6 4609.4 4628.7 5571.4 19623.7 19697.2 20089.8 20102.8 20165.6 18466.4 19614.5 19617.0 19625.8 19641.8 9993.3 10349.4 10364.4 10404.1 10417.4 8074.6 8313.9 8609.7 8621.8 8678.0 16681.0 17061.2 17465.7 17651.7 17691.3 17253.0 17458.9 17569.1 17625.1 17649.6 11489.8 11686.4 11771.4 11774.8 11779.9 13172.3 13175.9 13352.3 13376.1 13388.4 10901.5 11254.3 11257.4 11308.5 11434.4 10748.2 10945.8 11001.9 11074.3 11090.2 9576.6 9647.9 9732.2 9741.7 9758.0 7098.8 7127.1 7134.9 7144.6 7156.8 6969.8 7061.6 7103.2 7154.4 7188.6 18:03:54 INFO - PID 31724 | 18:03:54 INFO - PID 31724 | [#4] prototype.html Cycles:180 Average:5487.99 Median:6061.57 stddev:1515.44 (25.0%) stddev-sans-first:1472.46 18:03:54 INFO - PID 31724 | Values: 486.6 493.5 511.5 518.3 535.8 6112.2 6112.3 6189.3 6198.0 6275.5 5925.8 5982.7 6030.5 6041.4 6104.2 4889.4 4930.8 4934.6 4963.4 4971.0 4744.1 4949.7 4961.4 4965.6 4987.6 4900.0 4913.3 4916.7 4920.6 4935.9 4887.7 4912.2 4956.3 4962.3 5000.2 4446.9 4461.0 4463.5 4470.7 4505.9 6021.7 6042.8 6080.3 6100.0 6145.1 5618.2 5676.4 5696.7 5749.0 5754.4 6422.5 6475.8 6487.5 6525.0 6541.3 4827.3 4853.9 4884.4 4892.6 4900.7 6794.4 6848.3 6852.3 6918.4 6929.6 4954.6 4996.2 5002.3 5004.5 5005.9 4985.9 5000.6 5036.9 5085.8 5097.7 5590.0 5620.4 5641.4 5665.4 5716.4 6430.7 6484.0 6520.0 6568.3 6575.1 1655.2 1689.5 1699.6 1893.0 1936.2 1399.9 1422.2 1430.8 1452.1 1476.0 5676.7 5680.9 5722.4 5731.8 5742.3 6579.8 6593.9 6613.9 6656.9 6660.5 5746.9 5767.9 5774.1 5776.9 5777.7 3542.3 3613.9 3618.8 3625.7 3632.1 6516.9 6542.6 6567.5 6569.2 6592.2 6850.1 6856.5 6871.3 6886.4 6934.0 6493.3 6514.5 6525.2 6526.1 6555.6 6745.0 6764.0 6766.7 6783.5 6818.5 6536.6 6606.5 6651.8 6655.7 6679.7 6349.3 6383.1 6428.6 6436.6 6497.4 4981.4 5013.4 5032.1 5045.5 5051.9 6636.8 6711.0 6731.0 6744.5 6801.5 6172.3 6210.8 6389.1 6442.2 6491.7 6385.2 6411.8 6444.0 6464.7 6477.8 6306.7 6336.9 6371.5 6384.9 6429.8 6463.5 6492.3 6550.4 6555.1 6567.5 6301.4 6339.9 6349.6 6365.7 6400.8 18:03:54 INFO - PID 31724 | 18:03:54 INFO - PID 31724 | [#5] yui.html Cycles:180 Average:22252.15 Median:20644.24 stddev:29350.32 (142.2%) stddev-sans-first:29388.88 18:03:54 INFO - PID 31724 | Values: 906.2 912.5 927.1 934.0 937.1 17931.7 17935.6 18838.7 18853.9 18861.8 21302.3 21978.7 22029.7 22053.8 22203.4 979.7 1008.3 1010.4 1011.5 1011.6 971.2 1014.4 1065.1 1070.0 1070.5 1121.6 1124.6 1124.8 1125.5 1125.8 1107.4 1114.5 1117.8 1118.2 1118.3 808.3 809.7 810.3 813.0 821.2 19209.7 20037.2 20221.4 20269.9 20361.3 20174.4 20597.0 21091.3 21099.6 21322.0 16346.8 16535.3 16562.2 16616.7 16637.5 3576.5 3593.7 3611.3 3615.9 3617.9 120942.0 123928.0 125348.0 125927.0 127686.0 16184.7 16337.3 16356.8 16408.4 16634.8 28544.1 29409.9 29602.6 29670.6 29687.3 8358.7 8511.7 8545.7 8577.8 8577.9 4175.6 4244.4 4293.5 4300.0 4306.5 5234.8 5331.5 5426.1 5437.9 5452.5 989.1 1011.4 1020.3 1031.8 1035.9 695.2 697.9 711.3 712.0 717.5 11712.6 12112.2 12204.5 12487.5 12567.1 692.0 702.2 710.6 719.1 726.5 5239.9 5279.0 5334.4 5446.3 5546.7 89460.0 95286.0 97888.5 98403.0 98515.5 81890.6 82740.0 83875.0 84811.6 85089.1 75153.2 75202.0 75694.9 76328.6 77035.2 11159.5 11174.3 11223.9 11265.7 11592.1 28490.6 36624.6 36839.4 38366.0 38425.4 64536.4 64837.7 66482.0 66548.0 66676.3 18091.0 18514.9 18536.2 18623.6 18734.4 20562.2 20574.3 20691.5 20705.8 21059.6 14887.0 14945.0 14999.9 15070.3 15195.8 17517.5 18036.1 18050.2 18221.0 18309.5 15912.6 16462.8 16490.4 17758.0 17984.3 12758.6 13126.5 13473.3 13507.7 13843.9 13707.2 14070.2 14148.9 14204.7 14274.7 18:03:54 INFO - PID 31724 | -------- Summary: end -------- 18:03:54 INFO - PID 31724 | 18:03:54 INFO - TEST-INFO | 31724: exit 0 18:03:54 INFO - TEST-OK | dromaeo_css | took 1112583ms 18:03:54 INFO - TEST-START | kraken 18:03:54 INFO - Initialising browser for kraken test... 18:03:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35175/getInfo.html -profile /tmp/tmpsLDZPZ/profile 18:03:54 INFO - TEST-INFO | started process 32100 (/builds/slave/test/build/application/firefox/firefox http://localhost:35175/getInfo.html) 18:04:00 INFO - TEST-INFO | 32100: exit 0 18:04:00 INFO - Browser initialized. 18:04:00 INFO - Running cycle 1/1 for kraken test... 18:04:00 INFO - TEST-INFO | started process 32325 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsLDZPZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:04:01 INFO - PID 32325 | 18:04:01 INFO - PID 32325 | (/builds/slave/test/build/application/firefox/firefox:32379): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:04:01 INFO - PID 32325 | 18:04:12 INFO - PID 32325 | RSS: Main: 168534016 18:04:12 INFO - PID 32325 | 18:04:14 INFO - PID 32325 | Cycle 1(1): loaded http://localhost:35175/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35175/tests/kraken/driver.html?testName=audio-beat-detection) 18:04:15 INFO - PID 32325 | RSS: Main: 174399488 18:04:15 INFO - PID 32325 | 18:04:18 INFO - PID 32325 | Cycle 1(1): loaded http://localhost:35175/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35175/tests/kraken/driver.html?testName=audio-dft) 18:04:19 INFO - PID 32325 | RSS: Main: 175022080 18:04:19 INFO - PID 32325 | 18:04:22 INFO - PID 32325 | Cycle 1(1): loaded http://localhost:35175/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35175/tests/kraken/driver.html?testName=audio-fft) 18:04:22 INFO - PID 32325 | RSS: Main: 169775104 18:04:22 INFO - PID 32325 | 18:04:24 INFO - PID 32325 | Cycle 1(1): loaded http://localhost:35175/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35175/tests/kraken/driver.html?testName=audio-oscillator) 18:04:25 INFO - PID 32325 | RSS: Main: 169988096 18:04:25 INFO - PID 32325 | 18:04:27 INFO - PID 32325 | Cycle 1(1): loaded http://localhost:35175/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35175/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:04:28 INFO - PID 32325 | RSS: Main: 170315776 18:04:28 INFO - PID 32325 | 18:04:31 INFO - PID 32325 | Cycle 1(1): loaded http://localhost:35175/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35175/tests/kraken/driver.html?testName=imaging-darkroom) 18:04:32 INFO - PID 32325 | RSS: Main: 173162496 18:04:32 INFO - PID 32325 | 18:04:35 INFO - PID 32325 | Cycle 1(1): loaded http://localhost:35175/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35175/tests/kraken/driver.html?testName=imaging-desaturate) 18:04:36 INFO - PID 32325 | RSS: Main: 172879872 18:04:36 INFO - PID 32325 | 18:04:39 INFO - PID 32325 | Cycle 1(1): loaded http://localhost:35175/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35175/tests/kraken/driver.html?testName=json-parse-financial) 18:04:39 INFO - PID 32325 | RSS: Main: 173875200 18:04:39 INFO - PID 32325 | 18:04:41 INFO - PID 32325 | Cycle 1(1): loaded http://localhost:35175/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35175/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:04:42 INFO - PID 32325 | RSS: Main: 174030848 18:04:42 INFO - PID 32325 | 18:04:44 INFO - PID 32325 | Cycle 1(1): loaded http://localhost:35175/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35175/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:04:44 INFO - PID 32325 | RSS: Main: 174235648 18:04:44 INFO - PID 32325 | 18:04:46 INFO - PID 32325 | Cycle 1(1): loaded http://localhost:35175/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35175/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:04:47 INFO - PID 32325 | RSS: Main: 174239744 18:04:47 INFO - PID 32325 | 18:04:50 INFO - PID 32325 | Cycle 1(1): loaded http://localhost:35175/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35175/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:04:51 INFO - PID 32325 | RSS: Main: 174948352 18:04:51 INFO - PID 32325 | 18:04:54 INFO - PID 32325 | Cycle 1(1): loaded http://localhost:35175/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35175/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:04:54 INFO - PID 32325 | RSS: Main: 175206400 18:04:54 INFO - PID 32325 | 18:04:56 INFO - PID 32325 | Cycle 1(1): loaded http://localhost:35175/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35175/tests/kraken/driver.html?testName=ai-astar) 18:04:56 INFO - PID 32325 | RSS: Main: 176742400 18:04:56 INFO - PID 32325 | 18:04:56 INFO - PID 32325 | __start_tp_report 18:04:56 INFO - PID 32325 | _x_x_mozilla_page_load 18:04:56 INFO - PID 32325 | _x_x_mozilla_page_load_details 18:04:56 INFO - PID 32325 | |i|pagename|runs| 18:04:56 INFO - PID 32325 | |0;ai-astar;99;108;100;107;108;114;111;100;117;107 18:04:56 INFO - PID 32325 | |1;audio-beat-detection;153;117;147;166;122;147;142;130;183;127 18:04:56 INFO - PID 32325 | |2;audio-dft;164;170;176;162;163;169;165;161;162;164 18:04:56 INFO - PID 32325 | |3;audio-fft;74;78;74;187;82;81;75;76;159;75 18:04:56 INFO - PID 32325 | |4;audio-oscillator;80;82;126;82;79;79;148;79;88;139 18:04:56 INFO - PID 32325 | |5;imaging-gaussian-blur;114;113;114;114;114;114;113;114;121;114 18:04:56 INFO - PID 32325 | |6;imaging-darkroom;154;151;151;150;151;152;151;150;150;151 18:04:56 INFO - PID 32325 | |7;imaging-desaturate;99;98;94;94;100;94;94;94;94;93 18:04:56 INFO - PID 32325 | |8;json-parse-financial;55;54;54;55;58;56;55;55;55;55 18:04:56 INFO - PID 32325 | |9;json-stringify-tinderbox;55;54;55;54;55;56;58;55;60;54 18:04:56 INFO - PID 32325 | |10;stanford-crypto-aes;76;88;77;86;85;77;78;88;86;77 18:04:56 INFO - PID 32325 | |11;stanford-crypto-ccm;136;137;127;131;128;133;124;138;126;175 18:04:56 INFO - PID 32325 | |12;stanford-crypto-pbkdf2;186;182;180;175;193;179;181;177;176;177 18:04:56 INFO - PID 32325 | |13;stanford-crypto-sha256-iterative;78;70;71;68;71;68;67;69;72;69 18:04:56 INFO - PID 32325 | __end_tp_report 18:04:56 INFO - PID 32325 | __start_cc_report 18:04:56 INFO - PID 32325 | _x_x_mozilla_cycle_collect,1017 18:04:56 INFO - PID 32325 | __end_cc_report 18:04:56 INFO - PID 32325 | __startTimestamp1501808696969__endTimestamp 18:04:56 INFO - PID 32325 | 18:04:56 INFO - PID 32325 | ------- Summary: start ------- 18:04:56 INFO - PID 32325 | Number of tests: 14 18:04:56 INFO - PID 32325 | 18:04:56 INFO - PID 32325 | [#0] ai-astar Cycles:10 Average:107.10 Median:109.50 stddev:6.05 (5.5%) stddev-sans-first:5.66 18:04:56 INFO - PID 32325 | Values: 99.0 108.0 100.0 107.0 108.0 114.0 111.0 100.0 117.0 107.0 18:04:56 INFO - PID 32325 | 18:04:56 INFO - PID 32325 | [#1] audio-beat-detection Cycles:10 Average:143.40 Median:147.00 stddev:20.55 (14.0%) stddev-sans-first:21.51 18:04:56 INFO - PID 32325 | Values: 153.0 117.0 147.0 166.0 122.0 147.0 142.0 130.0 183.0 127.0 18:04:56 INFO - PID 32325 | 18:04:56 INFO - PID 32325 | [#2] audio-dft Cycles:10 Average:165.60 Median:164.50 stddev:4.70 (2.9%) stddev-sans-first:4.94 18:04:56 INFO - PID 32325 | Values: 164.0 170.0 176.0 162.0 163.0 169.0 165.0 161.0 162.0 164.0 18:04:56 INFO - PID 32325 | 18:04:56 INFO - PID 32325 | [#3] audio-fft Cycles:10 Average:96.10 Median:75.50 stddev:41.16 (54.5%) stddev-sans-first:42.87 18:04:56 INFO - PID 32325 | Values: 74.0 78.0 74.0 187.0 82.0 81.0 75.0 76.0 159.0 75.0 18:04:56 INFO - PID 32325 | 18:04:56 INFO - PID 32325 | [#4] audio-oscillator Cycles:10 Average:98.20 Median:79.50 stddev:27.86 (35.0%) stddev-sans-first:28.76 18:04:56 INFO - PID 32325 | Values: 80.0 82.0 126.0 82.0 79.0 79.0 148.0 79.0 88.0 139.0 18:04:56 INFO - PID 32325 | 18:04:56 INFO - PID 32325 | [#5] imaging-gaussian-blur Cycles:10 Average:114.50 Median:114.00 stddev:2.32 (2.0%) stddev-sans-first:2.46 18:04:56 INFO - PID 32325 | Values: 114.0 113.0 114.0 114.0 114.0 114.0 113.0 114.0 121.0 114.0 18:04:56 INFO - PID 32325 | 18:04:56 INFO - PID 32325 | [#6] imaging-darkroom Cycles:10 Average:151.10 Median:151.00 stddev:1.20 (0.8%) stddev-sans-first:0.67 18:04:56 INFO - PID 32325 | Values: 154.0 151.0 151.0 150.0 151.0 152.0 151.0 150.0 150.0 151.0 18:04:56 INFO - PID 32325 | 18:04:56 INFO - PID 32325 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:94.00 stddev:2.55 (2.7%) stddev-sans-first:2.35 18:04:56 INFO - PID 32325 | Values: 99.0 98.0 94.0 94.0 100.0 94.0 94.0 94.0 94.0 93.0 18:04:56 INFO - PID 32325 | 18:04:56 INFO - PID 32325 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.00 stddev:1.14 (2.1%) stddev-sans-first:1.20 18:04:56 INFO - PID 32325 | Values: 55.0 54.0 54.0 55.0 58.0 56.0 55.0 55.0 55.0 55.0 18:04:56 INFO - PID 32325 | 18:04:56 INFO - PID 32325 | [#9] json-stringify-tinderbox Cycles:10 Average:55.60 Median:55.00 stddev:1.96 (3.6%) stddev-sans-first:2.06 18:04:56 INFO - PID 32325 | Values: 55.0 54.0 55.0 54.0 55.0 56.0 58.0 55.0 60.0 54.0 18:04:56 INFO - PID 32325 | 18:04:56 INFO - PID 32325 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:85.50 stddev:5.16 (6.0%) stddev-sans-first:5.03 18:04:56 INFO - PID 32325 | Values: 76.0 88.0 77.0 86.0 85.0 77.0 78.0 88.0 86.0 77.0 18:04:56 INFO - PID 32325 | 18:04:56 INFO - PID 32325 | [#11] stanford-crypto-ccm Cycles:10 Average:135.50 Median:134.50 stddev:14.71 (10.9%) stddev-sans-first:15.60 18:04:56 INFO - PID 32325 | Values: 136.0 137.0 127.0 131.0 128.0 133.0 124.0 138.0 126.0 175.0 18:04:56 INFO - PID 32325 | 18:04:56 INFO - PID 32325 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.60 Median:180.50 stddev:5.44 (3.0%) stddev-sans-first:5.41 18:04:56 INFO - PID 32325 | Values: 186.0 182.0 180.0 175.0 193.0 179.0 181.0 177.0 176.0 177.0 18:04:56 INFO - PID 32325 | 18:04:56 INFO - PID 32325 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:70.50 stddev:3.13 (4.4%) stddev-sans-first:1.67 18:04:56 INFO - PID 32325 | Values: 78.0 70.0 71.0 68.0 71.0 68.0 67.0 69.0 72.0 69.0 18:04:56 INFO - PID 32325 | -------- Summary: end -------- 18:04:56 INFO - PID 32325 | 18:04:57 INFO - PID 32325 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:04:57 INFO - TEST-INFO | 32325: exit 0 18:04:57 INFO - TEST-OK | kraken | took 63065ms 18:04:57 INFO - SUITE-END | took 1175s 18:04:58 INFO - Completed test suite (00:19:36) 18:04:58 INFO - javascript benchmark 18:04:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8520.780666512992, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3179.3959147761907, 3197.2486465680563, 3207.326461443097, 3218.7425005999517, 3229.9515507267397, 6175.042868355401, 6238.532110091739, 6251.218597675291, 6300.464460520861, 6319.936800631998, 6081.057436097412, 6205.782797602084, 6242.656653884034, 6262.874742505159, 6271.529635277361, 5702.230198923134, 5725.4847063764355, 5758.712064396785, 5771.3074431068335, 5833.416658334175, 5530.917036244439, 5694.658320500763, 5694.6867922264255, 5772.653640781546, 5820.767169313222, 5259.737013149346, 5553.4724201200825, 5682.346530149003, 5690.573207009449, 5696.231008813798, 5662.631928924648, 5672.12082127271, 5698.886022279531, 5700.201971723962, 5707.429257074301, 5664.830055098354, 5668.886622267572, 5674.886502269973, 5676.148577713335, 5685.289338832646, 6191.19514463117, 6241.500679945593, 6247.531435142382, 6289.276733175652, 6302.716377763011, 6670.433013193884, 6676.198856137246, 6690.59856408617, 6782.694778734969, 6786.3892249697765, 6617.735290588371, 6744.7976560703255, 6747.561408508432, 6763.560368576027, 6836.623985680785, 6133.29467111279, 6134.04922237054, 6134.999999999955, 6136.7238474268315, 6137.048757442603, 6797.320267973163, 6799.966000169968, 6801.251862295144, 6836.555623884432, 6890.896636550456, 6568.244651865001, 6570.441512471493, 6601.966990165114, 6646.468282537386, 6656.534042617067, 6665.766698165541, 6762.459003279726, 6764.966175169093, 6765.323467653294, 6774.7628832991595, 6605.57063790852, 6615.437687796495, 6649.335066493409, 6693.397594216543, 6740.0, 6570.934290657128, 6684.0, 6730.360615741594, 6741.1910570731825, 6766.424853887376, 5392.622516423891, 5438.102713052302, 5444.754986025639, 5444.8094316698725, 5452.154915988028, 4628.3983082199775, 4650.255959046536, 4653.441587009581, 4660.13787449323, 4699.929501057487, 5770.0, 5777.971110144423, 5856.180134781134, 5884.323302820145, 5885.234919460443, 6613.801585952429, 6683.699233534503, 6697.095892054608, 6718.260991290954, 6793.898091528631, 5786.161006654096, 5798.217240672455, 5821.970890145522, 5831.737571809279, 5839.0, 5903.734331955073, 5943.910841337383, 5950.31571369298, 5955.285365756137, 5964.791232306935, 6252.751665513609, 6511.446527045159, 6548.9017664735065, 6561.671916404256, 6562.442192413603, 6724.697388617622, 6747.291534388818, 6811.2167100784045, 6815.625140617115, 6830.077939478306, 6347.523935704933, 6463.3213512580505, 6465.482761378984, 6465.870682586416, 6563.409293163447, 6551.050097735706, 6552.967235163794, 6582.374674406115, 6589.209294884645, 6597.2742998271115, 6728.596284222962, 6757.3242675732035, 6757.797266082025, 6758.797236082925, 6762.763303284361, 6435.549511534147, 6489.383508566679, 6494.0, 6511.088447617432, 6514.120593719787, 6517.283098859217, 6518.73925043012, 6544.312847150981, 6621.304763000008, 6623.4038936495945, 6423.743050277936, 6427.614343139239, 6494.350564943468, 6521.380468855451, 6528.640924749185, 6302.590331628429, 6392.904106438315, 6402.231732192166, 6429.0, 6458.967705161444, 6270.968645156745, 6355.3326900674765, 6355.809325720235, 6402.839929001812, 6451.903221451776], "value": 6104.663450433111, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [825.2119226139038, 857.6355049104129, 878.7012415778635, 907.8002839375339, 907.9001309855914, 40204.59795402045, 40849.59150408495, 40912.38631420527, 41011.0, 41054.58945410545, 37509.249815003684, 37539.0, 37593.62406375935, 37693.05767355818, 37769.81115094421, 6720.59676419414, 6847.10987571615, 6854.074699915497, 6855.554388964726, 6857.142857142857, 6733.730650773988, 6787.694553745068, 6802.557833740816, 6816.250212476623, 6819.522633415663, 6761.560498567602, 6784.3554862288, 6803.591784492922, 6817.659117044128, 7336.743213987512, 7466.178720340786, 7514.58669773165, 7526.247375262485, 7528.962355188189, 7561.24387561245, 7294.197638259787, 7342.118945726494, 7369.594672293022, 7373.0, 7377.6311184440565, 37645.43531847024, 37925.241495170216, 38214.04464888372, 38485.0, 38580.0, 3487.7035451986358, 3491.6857482826413, 3499.9825000874835, 3502.527158833576, 3537.5224344713392, 31336.21659458532, 31419.05742827719, 31427.0, 31611.52582711261, 31720.0, 4841.273808928689, 4862.343583616202, 4885.5358740919555, 4896.4858689837765, 4927.162382395001, 180003.0, 181369.0, 184594.0, 185203.0, 196522.99999999857, 48494.515054849, 48940.510594893596, 48995.0, 49003.0, 49063.75468122637, 50787.0, 50904.74547627312, 51007.489925101014, 51015.234771478456, 51076.48923510792, 15760.054396736003, 15836.44572439982, 15850.286737096627, 15850.603734906488, 16043.358265669476, 13090.280034598189, 13105.541306054241, 13153.079284449997, 13153.934230328978, 13176.341182940814, 14124.0, 14128.71742565143, 14172.149671019772, 14243.78634320486, 14360.497382591559, 5862.208601838697, 5863.296404431496, 5877.706114694248, 5898.14476900847, 5898.616589921728, 979.2704435195825, 982.4596471940404, 990.2474119668959, 1013.5692330759398, 1016.5273147986222, 23078.769212307663, 23529.529409411716, 23579.41051473727, 23602.29193124209, 23666.7633323668, 1092.9234953553334, 1097.950592223336, 1106.065374758344, 1113.9443027848574, 1114.4483480677118, 12300.507979680891, 12467.5012999479, 15853.603659908364, 15867.365305387655, 16573.83426165723, 226667.0, 226797.0, 226814.0, 227481.0, 227611.0, 54995.450045499034, 55051.0, 55383.723081384334, 55470.16794748001, 55874.72062639742, 1390.1381143690774, 1463.290304202461, 1464.5825939607162, 1472.175581674266, 1477.0842077911548, 27463.45073098487, 27748.30629234285, 27768.583471248352, 27878.860605696842, 27930.86034569814, 38153.0, 38402.61597383991, 38409.0, 38465.42301865418, 38482.80758596189, 203190.0, 203408.0, 205435.0, 206290.0, 206382.0, 34114.82942585271, 35832.820835895654, 37854.81072594619, 38136.04659883525, 38150.0, 45519.77240113778, 46479.0, 46861.53138468708, 47205.763971181295, 47812.5218747822, 34511.65488345084, 34512.82743586266, 34758.13104672403, 35144.47283290701, 35237.82381088078, 33804.66195338113, 34049.82975085109, 34363.0, 34440.82779586086, 34723.479147813305, 37250.44124338083, 37817.62182378251, 38076.0, 38222.617773821905, 38229.235415292096, 32827.835860820545, 33240.33519329648, 33507.0, 33629.66370336363, 33675.66324336725, 24674.0, 24764.38089047716, 25035.74964250407, 25526.23421297364, 25803.483930320912], "value": 17323.818608129328, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.5459632294167, 582.2634367525079, 593.1784478497283, 596.6241268001157, 602.3976023976024, 22408.215712450066, 22616.982235799387, 23236.8838155809, 23251.186208482708, 23258.88370558149, 26708.198754037363, 27107.1867843965, 27195.864020679917, 27204.727952720517, 27281.0, 6405.071264666621, 6576.967115164418, 6582.835429114262, 6585.045168450596, 6595.373439523261, 5910.0, 6391.488680905516, 6498.837529061764, 6512.185976752906, 6517.511186660996, 6462.5153113516435, 6466.676666166696, 6482.805515834532, 6486.156799616043, 6487.18910136233, 6479.676016199218, 6488.805335839932, 6492.448141907944, 6494.642794646293, 6496.123023391829, 6056.939430605682, 6062.151298818169, 6065.332813390523, 6065.363136870609, 6065.847488977124, 30009.099727008226, 30077.69922300771, 30709.539356909663, 31090.222744431343, 31325.0, 1864.7855496617926, 1886.0946745562208, 1901.1824915286422, 1904.047976011994, 1904.0670071664922, 18675.346362877233, 19224.90387548067, 19259.13333899964, 19277.51806204842, 19384.515387115294, 2316.7104111986, 2359.882005899698, 2400.2799160251857, 2412.7828495435497, 2418.8427752196226, 148630.25684871615, 148784.0, 151975.0, 153116.0, 153184.0, 14221.64445888861, 14853.851461485247, 15056.849431505763, 15128.773068403983, 15874.682506349809, 23713.0, 23761.524769504515, 23766.0, 23798.88100559486, 23870.880645597008, 9915.405075695482, 10092.848607270897, 10126.392416455037, 10313.84529232062, 10331.896681033244, 9337.299702522203, 9374.484403357745, 9394.06059394055, 9467.52662366879, 9521.476318802532, 8963.117745273566, 8996.190342869173, 9387.432775545385, 9391.436513809193, 9442.291828112919, 2783.359827239746, 2804.859757012182, 2824.4351129774127, 2827.5475923852086, 2836.602875597418, 935.4153653966271, 966.7046158148115, 971.8007808399261, 972.2076507646245, 977.2621670638622, 19598.0, 19839.60320793576, 20156.92088779218, 20577.38267851967, 20611.0, 1017.9643712470028, 1026.9794604107876, 1027.6814187601867, 1034.3070143004206, 1037.901399367059, 11272.267302625303, 11283.323000619825, 11323.830142547868, 11382.146339024606, 11383.430828458544, 486358.0, 487559.0, 552816.0, 557210.0, 566641.0, 122407.0, 124055.0, 124139.0, 127304.0, 127629.36185319014, 1248.9625311240677, 1271.5104684696273, 1308.3915979069777, 1312.586535241396, 1315.1780137414116, 20802.687959680316, 20993.265235716677, 21353.039113239633, 21371.89314053482, 21479.248226312004, 60363.09455358261, 60530.0, 61999.0, 62714.37285627086, 62995.0, 77703.61148194222, 78738.6063069681, 78978.6051069741, 78984.21015789769, 79161.0, 12678.112532122663, 14406.279686015867, 14830.62923426923, 15478.767818482507, 15483.767743483631, 24757.25728228156, 24809.0, 24927.750722492543, 25028.124015659363, 25243.0, 17720.822791771916, 18761.90619046896, 18915.810841891405, 18941.242350305834, 19161.13774880106, 17589.560260993578, 17818.55453613618, 17996.55008624795, 18139.183736732142, 18283.360082397052, 19953.900230499334, 20130.29543965954, 20184.697229541864, 20278.695819563014, 20495.385138445276, 14610.780838287646, 15064.849351506344, 15429.074255544703, 15432.537023889301, 15436.614084647974, 19085.141168647166, 19253.32613358526, 19328.4201473956, 19347.226110955406, 19575.0], "value": 12353.144249013212, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1329.2755448280686, 1349.7502961952039, 1365.4265208612387, 1375.2573610250465, 1388.5695434415325, 13612.931935340323, 14331.785023224647, 14333.856661433409, 14340.928295358535, 14349.354279057441, 13807.240601766898, 14373.425062997494, 14793.704125917502, 14821.629459263524, 14859.554213373589, 2694.5688689809635, 2759.0619189475574, 2771.1547977866744, 2771.9861400692967, 2777.1807316841478, 2655.81409301349, 2664.5337066013467, 2676.718944510818, 2704.580789977557, 2706.472237913608, 2741.08173761789, 2774.611554382388, 2775.084222206667, 2776.0283900634818, 2776.6667999840047, 2660.215236505226, 2714.5792402177694, 2718.9864050679817, 2724.1555117913313, 2725.509408306514, 2562.2825608829557, 2642.8546429946346, 2645.285772841322, 2693.636359091517, 2707.431439397729, 13955.79066314006, 14051.578452646438, 14060.437582496688, 14076.225807580578, 14106.365213565414, 7679.0, 7970.242826931432, 7974.481658692224, 7982.720604778863, 7983.481073730247, 5214.556762675177, 5359.5712343012465, 5429.67421954684, 5517.724113794335, 5521.254630624854, 1808.9638207235782, 1827.7898041725239, 1843.723441483788, 1881.6895212289955, 1882.6799444094536, 23171.53656926869, 23294.0, 23307.0, 23346.066157353685, 23366.0, 6472.223333200046, 6617.470602351897, 6655.46756259498, 6808.2510923798345, 6823.590584564942, 15958.12230327343, 15981.121038343003, 15988.0, 16013.519594412186, 16033.038017718742, 7324.377427918686, 7528.644843928036, 7531.661075251627, 7535.8116047099265, 7544.547327160388, 6898.551594146365, 6954.9304506955295, 7005.229424763272, 7062.6821793019435, 7101.0, 4869.245266983623, 5041.697498150123, 5046.520580544842, 5055.393352797688, 5120.564751996048, 1423.679672073796, 1446.8263808343065, 1506.3372116268806, 1552.945646902353, 1568.5921660368158, 842.7303262955795, 866.787637028932, 869.387082107113, 878.3939082033377, 889.4396530185942, 7484.625768711543, 8198.426110172231, 8216.424850260544, 8229.588520573947, 8259.463134896212, 945.7021038372891, 964.6816550538306, 972.6255391674256, 977.2653539966474, 977.3744803325877, 4479.507254202035, 4590.563896429834, 4609.446866376056, 4628.722276663344, 5571.4428557143965, 19623.705644415346, 19697.21211151538, 20089.799102008794, 20102.798972010092, 20165.596688066158, 18466.446006619823, 19614.509637259183, 19617.0, 19625.80374196268, 19641.803581964283, 9993.250506211918, 10349.379037257939, 10364.429956352322, 10404.11565016967, 10417.42704151279, 8074.555899425588, 8313.916860831314, 8609.741707748777, 8621.784455388666, 8678.0, 16681.0, 17061.232244548777, 17465.650686986442, 17651.735223971395, 17691.292348305924, 17253.0, 17458.912705436393, 17569.121543923007, 17625.11874406249, 17649.647007060044, 11489.770204596029, 11686.422332984937, 11771.411429428666, 11774.764504710029, 11779.88220117788, 13172.341382931007, 13175.934120329337, 13352.265625390697, 13376.130551514121, 13388.397522111334, 10901.509432075418, 11254.324740515594, 11257.380844053656, 11308.49111789988, 11434.371109589052, 10748.193885458466, 10945.781084378426, 11001.889981100087, 11074.280171788809, 11090.16823738239, 9576.56905439243, 9647.938726740193, 9732.221422286058, 9741.707748767549, 9758.0, 7098.751543695946, 7127.073480447509, 7134.8929766052515, 7144.642767861482, 7156.785296441115, 6969.7909062728195, 7061.576305421691, 7103.2186459488385, 7154.427645788429, 7188.640567971685], "value": 6452.283517418015, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.61332512694275, 493.5459377372817, 511.47573736919674, 518.2718280815453, 535.7990753467449, 6112.235970503687, 6112.29708583513, 6189.319174890769, 6198.0, 6275.52933529986, 5925.7925972590965, 5982.730777115029, 6030.457258846703, 6041.395860413956, 6104.236970378703, 4889.3888263967, 4930.753462326878, 4934.605231581465, 4963.354763880709, 4970.950290497103, 4744.102359056388, 4949.727764972925, 4961.355023846895, 4965.577925876305, 4987.551120399164, 4900.0, 4913.287573301869, 4916.70499770015, 4920.60635149189, 4935.876603084934, 4887.682300650446, 4912.189488734391, 4956.306117143603, 4962.280469331963, 5000.199968005102, 4446.88662986736, 4460.955390446086, 4463.464384273875, 4470.687051906368, 4505.932411013838, 6021.668808215546, 6042.848928776772, 6080.30076541199, 6099.969500152515, 6145.139680444697, 5618.2415373924405, 5676.432356764331, 5696.69283940005, 5749.0, 5754.395788442237, 6422.518311126684, 6475.838104047437, 6487.545871788977, 6525.0, 6541.345865413468, 4827.25177597473, 4853.854384368475, 4884.389451318585, 4892.559669629751, 4900.705957642553, 6794.422474089659, 6848.280930502325, 6852.349026842442, 6918.411934985532, 6929.618870962095, 4954.5540901319055, 4996.225585034325, 5002.299678045077, 5004.548680882576, 5005.899882002413, 4985.875353116201, 5000.574951129195, 5036.924446133311, 5085.847424577269, 5097.668651537638, 5590.021746194399, 5620.353659329147, 5641.351244606923, 5665.433456654301, 5716.371199168088, 6430.7106180222, 6483.984946999668, 6520.0, 6568.310327415648, 6575.112359831457, 1655.1641421082277, 1689.5015970288794, 1699.6175860431304, 1892.9716054259197, 1936.2255097960929, 1399.9090059146326, 1422.239102080382, 1430.7623905321823, 1452.0561634937164, 1476.0, 5676.68778217202, 5680.943190568124, 5722.399148089474, 5731.799387021434, 5742.253507044057, 6579.8026059217345, 6593.86812263752, 6613.900791488131, 6656.933430665728, 6660.500462465335, 5746.88506229873, 5767.942320576741, 5774.133879917962, 5776.913346299809, 5777.711114444261, 3542.326957877995, 3613.903935717278, 3618.819059047037, 3625.7099432045375, 3632.076178676191, 6516.934830651728, 6542.626048529862, 6567.540272180901, 6569.24453687832, 6592.208934927743, 6850.143732033496, 6856.451483881277, 6871.347222013901, 6886.449084073262, 6934.0, 6493.2857385688485, 6514.469099761457, 6525.2169739631545, 6526.118973938553, 6555.573887697285, 6744.966275168593, 6764.038428855577, 6766.733144396682, 6783.4573234142135, 6818.454523637998, 6536.640484773383, 6606.48050948275, 6651.800445986628, 6655.670515319793, 6679.732810687518, 6349.333320001333, 6383.1382763326355, 6428.646424446513, 6436.645984470899, 6497.350264973465, 4981.402231732215, 5013.448520662797, 5032.144535429059, 5045.5459008689395, 5051.87370315745, 6636.800895973129, 6711.0, 6731.0, 6744.494162937897, 6801.489888258499, 6172.290186628596, 6210.819944210585, 6389.137466441971, 6442.226932768098, 6491.7403303869205, 6385.169927909342, 6411.7941029485255, 6444.0, 6464.709088091141, 6477.805665830033, 6306.716197771019, 6336.92272313717, 6371.458426033839, 6384.87230255383, 6429.839254018687, 6463.482921366185, 6492.253390859923, 6550.447439029828, 6555.147830781968, 6567.540272181092, 6301.40136687014, 6339.858825411286, 6349.555531112777, 6365.7135428904885, 6400.839979000562], "value": 5055.053784813804, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [906.2115959115571, 912.4981260306831, 927.10071230906, 933.971980840575, 937.1097457415455, 17931.731024034634, 17935.551611209725, 18838.71741923872, 18853.905730471346, 18861.81138188618, 21302.254421095266, 21978.670319945213, 22029.669554956647, 22053.779462205373, 22203.44491387716, 979.745266230778, 1008.2841182760212, 1010.4442556593881, 1011.4993078426153, 1011.6004178349548, 971.1745016735754, 1014.411641248078, 1065.0999905080196, 1069.9728260869535, 1070.4808168038496, 1121.6354684727455, 1124.60638776428, 1124.820028795393, 1125.5497800879632, 1125.7579620381616, 1107.385401102388, 1114.5486078138326, 1117.8155604325277, 1118.2116607791488, 1118.2787102319, 808.292743849132, 809.7004108479841, 810.2586133687669, 812.9837403251961, 821.1501096365233, 19209.711854322195, 20037.1985120595, 20221.39335819913, 20269.898650506802, 20361.287354942506, 20174.394768156977, 20597.0, 21091.261805836722, 21099.578008439745, 21322.040508177015, 16346.83653163465, 16535.338586456528, 16562.17189140538, 16616.6676666466, 16637.50087497377, 3576.499290099388, 3593.658602432765, 3611.3499570077374, 3615.9096022599383, 3617.855285788565, 120942.0, 123928.0, 125348.0, 125927.0, 127686.0, 16184.676306473804, 16337.264823082753, 16356.754648680278, 16408.425705100264, 16634.75047874283, 28544.143675689764, 29409.85295073511, 29602.555961660593, 29670.55494167589, 29687.25781855471, 8358.749237522885, 8511.702090426805, 8545.658173672984, 8577.785555361166, 8577.914220857836, 4175.561566035583, 4244.384564238168, 4293.463317085364, 4299.957000429956, 4306.504751953503, 5234.7644356004075, 5331.466853314638, 5426.131818908955, 5437.864053398696, 5452.482014208644, 989.0653332600767, 1011.4437059617181, 1020.3061917895753, 1031.7575369788094, 1035.896410358958, 695.1519146641191, 697.930206979298, 711.3491155592575, 711.9572825630479, 717.519262094398, 11712.590059348053, 12112.21270617407, 12204.511819527119, 12487.50049998008, 12567.057470689735, 691.9688614012382, 702.2065066474851, 710.6304721544768, 719.1047146302886, 726.46604745512, 5239.869003274948, 5279.049771041249, 5334.4398838122215, 5446.319210098738, 5546.69493177871, 89460.0, 95286.0, 97888.51055744676, 98403.0, 98515.50742246243, 81890.59054704689, 82740.0, 83875.0, 84811.57594211989, 85089.14910850812, 75153.24846751463, 75202.0, 75694.86457703139, 76328.61835690786, 77035.2296477028, 11159.497822598165, 11174.329540227613, 11223.887761122285, 11265.662719852751, 11592.1305902056, 28490.629200122014, 36624.63375366319, 36839.44740828943, 38366.0, 38425.4236186463, 64536.35463645491, 64837.67581162064, 66482.0, 66548.0, 66676.33323666701, 18091.0, 18514.907425462785, 18536.16587253604, 18623.627527449647, 18734.437966861016, 20562.177512899318, 20574.279900203423, 20691.4827129323, 20705.792942070988, 21059.578808424052, 14887.0, 14945.02857314271, 14999.925000375364, 15070.321835517208, 15195.848041519443, 17517.474475765746, 18036.056118394252, 18050.1877415514, 18221.0, 18309.45071647853, 15912.602184945006, 16462.75305870389, 16490.422835200712, 17758.0, 17984.2806287747, 12758.553450629184, 13126.540571080348, 13473.32633368308, 13507.662308442368, 13843.861561384258, 13707.177569345871, 14070.226137562533, 14148.858511415167, 14204.715905681622, 14274.71450570962], "value": 8981.049742331215, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1550.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 108.0, 100.0, 107.0, 108.0, 114.0, 111.0, 100.0, 117.0, 107.0], "value": 107.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 117.0, 147.0, 166.0, 122.0, 147.0, 142.0, 130.0, 183.0, 127.0], "value": 143.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 170.0, 176.0, 162.0, 163.0, 169.0, 165.0, 161.0, 162.0, 164.0], "value": 165.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 78.0, 74.0, 187.0, 82.0, 81.0, 75.0, 76.0, 159.0, 75.0], "value": 96.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 126.0, 82.0, 79.0, 79.0, 148.0, 79.0, 88.0, 139.0], "value": 98.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 121.0, 114.0], "value": 114.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 151.0, 151.0, 150.0, 151.0, 152.0, 151.0, 150.0, 150.0, 151.0], "value": 151.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 98.0, 94.0, 94.0, 100.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 55.0, 58.0, 56.0, 55.0, 55.0, 55.0, 55.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 54.0, 55.0, 56.0, 58.0, 55.0, 60.0, 54.0], "value": 55.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 88.0, 77.0, 86.0, 85.0, 77.0, 78.0, 88.0, 86.0, 77.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 127.0, 131.0, 128.0, 133.0, 124.0, 138.0, 126.0, 175.0], "value": 135.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 182.0, 180.0, 175.0, 193.0, 179.0, 181.0, 177.0, 176.0, 177.0], "value": 180.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 70.0, 71.0, 68.0, 71.0, 68.0, 67.0, 69.0, 72.0, 69.0], "value": 70.3, "unit": "score"}]}]} 18:04:58 INFO - Return code: 0 18:04:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:04:58 INFO - # TBPL SUCCESS # 18:04:58 INFO - Running post-action listener: _package_coverage_data 18:04:58 INFO - Running post-action listener: _resource_record_post_action 18:04:58 INFO - [mozharness: 2017-08-04 01:04:58.232502Z] Finished run-tests step (success) 18:04:58 INFO - Running post-run listener: _resource_record_post_run 18:04:58 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 0; Write bytes: 431812608; Read time: 0; Write time: 321916 18:04:58 INFO - TinderboxPrint: CPU usage
13.2% 18:04:58 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 18:04:58 INFO - TinderboxPrint: I/O write bytes / time
431,812,608 / 321,916 18:04:58 INFO - TinderboxPrint: CPU idle
8,273.3 (86.8%) 18:04:58 INFO - TinderboxPrint: CPU user
1,206.4 (12.7%) 18:04:58 INFO - TinderboxPrint: Swap in / out
0 / 0 18:04:58 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 106643456; Read time: 0; Write time: 92092 18:04:58 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:04:58 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 325169152; Read time: 0; Write time: 229824 18:04:58 INFO - Running post-run listener: _upload_blobber_files 18:04:58 INFO - Blob upload gear active. 18:04:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:04:58 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:04:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:04:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:04:59 INFO - (blobuploader) - INFO - Open directory for files ... 18:04:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:04:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:04:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:05:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:05:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:05:06 INFO - (blobuploader) - INFO - Done attempting. 18:05:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:05:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:05:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:05:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:05:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:05:07 INFO - (blobuploader) - INFO - Done attempting. 18:05:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:05:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:05:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:05:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:05:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:05:08 INFO - (blobuploader) - INFO - Done attempting. 18:05:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:05:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:05:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:05:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:05:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:05:09 INFO - (blobuploader) - INFO - Done attempting. 18:05:09 INFO - (blobuploader) - INFO - Iteration through files over. 18:05:09 INFO - Return code: 0 18:05:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:05:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:05:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/915267a2a6edc1aa2e3348fc01db01d12de7290e5b5c022941dae7159bf1cbf3aa22a605b707946617740d2574c4631897b6d2e32ba23f10d4134d2e3c964d83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ccd8d4d6dafd44b201242e3399cdb0e7709616adadb69d2580b394a502ba792cbe8e1474ca63b97b698a229ca9fa24b57caf968bbcb76e0372c781ba921ff647", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93c1c4f8022b8652ec542634759f4f573dffda364ea51fe35c0044bd8367c3de9abcdf71afb83b532431b9d5c65b95e7e52ec38f09b2486b0da4bf7e91b1ee13", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c08db71635431391f8a0e4e7162b9950bcc4a6aa12e498f2be3dafa306b1d14ff2d758c1a84062629c6e73b61f4fa42985976129f49c3a6da008b305c0fcd3c4"} 18:05:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:05:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:05:09 INFO - Contents: 18:05:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/915267a2a6edc1aa2e3348fc01db01d12de7290e5b5c022941dae7159bf1cbf3aa22a605b707946617740d2574c4631897b6d2e32ba23f10d4134d2e3c964d83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ccd8d4d6dafd44b201242e3399cdb0e7709616adadb69d2580b394a502ba792cbe8e1474ca63b97b698a229ca9fa24b57caf968bbcb76e0372c781ba921ff647", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93c1c4f8022b8652ec542634759f4f573dffda364ea51fe35c0044bd8367c3de9abcdf71afb83b532431b9d5c65b95e7e52ec38f09b2486b0da4bf7e91b1ee13", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c08db71635431391f8a0e4e7162b9950bcc4a6aa12e498f2be3dafa306b1d14ff2d758c1a84062629c6e73b61f4fa42985976129f49c3a6da008b305c0fcd3c4"} 18:05:09 INFO - Running post-run listener: copy_logs_to_upload_dir 18:05:09 INFO - Copying logs to upload dir... 18:05:09 INFO - mkdir: /builds/slave/test/build/upload/logs 18:05:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.231801 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-03 18:05:09.265623) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-03 18:05:09.269801) ========= 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=3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/915267a2a6edc1aa2e3348fc01db01d12de7290e5b5c022941dae7159bf1cbf3aa22a605b707946617740d2574c4631897b6d2e32ba23f10d4134d2e3c964d83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ccd8d4d6dafd44b201242e3399cdb0e7709616adadb69d2580b394a502ba792cbe8e1474ca63b97b698a229ca9fa24b57caf968bbcb76e0372c781ba921ff647", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93c1c4f8022b8652ec542634759f4f573dffda364ea51fe35c0044bd8367c3de9abcdf71afb83b532431b9d5c65b95e7e52ec38f09b2486b0da4bf7e91b1ee13", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c08db71635431391f8a0e4e7162b9950bcc4a6aa12e498f2be3dafa306b1d14ff2d758c1a84062629c6e73b61f4fa42985976129f49c3a6da008b305c0fcd3c4"} build_url:https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011340 build_url: 'https://queue.taskcluster.net/v1/task/HIjfhibRQUeCo_3pJKMQOQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/915267a2a6edc1aa2e3348fc01db01d12de7290e5b5c022941dae7159bf1cbf3aa22a605b707946617740d2574c4631897b6d2e32ba23f10d4134d2e3c964d83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ccd8d4d6dafd44b201242e3399cdb0e7709616adadb69d2580b394a502ba792cbe8e1474ca63b97b698a229ca9fa24b57caf968bbcb76e0372c781ba921ff647", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93c1c4f8022b8652ec542634759f4f573dffda364ea51fe35c0044bd8367c3de9abcdf71afb83b532431b9d5c65b95e7e52ec38f09b2486b0da4bf7e91b1ee13", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c08db71635431391f8a0e4e7162b9950bcc4a6aa12e498f2be3dafa306b1d14ff2d758c1a84062629c6e73b61f4fa42985976129f49c3a6da008b305c0fcd3c4"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-03 18:05:09.294959) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 18:05:09.295344) ========= 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=3836458cccd887f2d2de3cf1000001de-1501806316.337820-1144232737 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004412 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 18:05:09.380419) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-03 18:05:09.380735) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-03 18:05:09.381045) ========= ========= Total master_lag: 0.17 =========